2018-10-15T11:53:29.893837Z 0 [Warning] WSREP: Node is running in bootstrap/initialize mode. Disabling pxc_strict_mode checks 100 200 300 400 500 600 700 800 900 1000 1100 1200 1300 1400 1500 1600 1700 1800 1900 2000 2100 2200 2300 2400 2500 2600 2700 2800 2900 3000 3100 3200 3300 3400 3500 3600 3700 3800 3900 4000 100 200 300 400 500 600 700 800 900 1000 1100 1200 1300 1400 1500 1600 1700 1800 1900 2000 2100 2200 2300 2400 2500 2600 2700 2800 2900 3000 3100 3200 3300 3400 3500 3600 3700 3800 3900 4000 2018-10-15T11:53:42.745410Z 0 [Warning] InnoDB: New log files created, LSN=45790 2018-10-15T11:53:42.790512Z 0 [Warning] InnoDB: Creating foreign key constraint system tables. 2018-10-15T11:53:42.861289Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: f6b292ec-d070-11e8-a3a5-901b0ec43dfa. 2018-10-15T11:53:42.869706Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened. 2018-10-15T11:53:43.281941Z 0 [Warning] CA certificate ca.pem is self signed. 2018-10-15T11:53:43.345241Z 1 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option. 2018-10-15T11:53:44.722420Z 1 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722443Z 1 [Warning] 'user' entry 'mysql.session@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722465Z 1 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722477Z 1 [Warning] 'db' entry 'performance_schema mysql.session@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722484Z 1 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722493Z 1 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722526Z 1 [Warning] 'tables_priv' entry 'user mysql.session@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:44.722547Z 1 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode. 2018-10-15T11:53:53.386681Z 0 [Note] mysqld (mysqld 5.7.20-18-18-log) starting as process 31523 ... 2018-10-15T11:53:53.387996Z 0 [Note] WSREP: Setting wsrep_ready to false 2018-10-15T11:53:53.388022Z 0 [Note] WSREP: No pre-stored wsrep-start position found. Skipping position initialization. 2018-10-15T11:53:53.388025Z 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so' 2018-10-15T11:53:53.389764Z 0 [Note] WSREP: wsrep_load(): Galera 3.24(r) by Codership Oy loaded successfully. 2018-10-15T11:53:53.389808Z 0 [Note] WSREP: CRC-32C: using hardware acceleration. 2018-10-15T11:53:53.389992Z 0 [Warning] WSREP: Could not open state file for reading: '/var/lib/percona-xtradb-cluster//grastate.dat' 2018-10-15T11:53:53.390003Z 0 [Warning] WSREP: No persistent state found. Bootstraping with default state 2018-10-15T11:53:53.390019Z 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1 2018-10-15T11:53:53.396961Z 0 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/percona-xtradb-cluster/; base_host = 176.9.25.20; base_port = 4567; cert.log_conflicts = no; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 10; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 4; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/percona-xtradb-cluster/; gcache.freeze_purge_at_seqno = -1; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/percona-xtradb-cluster//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 1024M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1; gcs.fc_limit = 100; gcs.fc_master_slave = no; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = false; pc.recovery = 1; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = PT30S; pc.weight = 1; protonet.backend = asio; protonet.version = 0; repl.causal_read_timeout = PT30S; repl.commit_order = 3; repl.key_format = FLAT8; repl.max_ws_size = 2147483647; repl.proto_max = 7; socket.checksum = 2; socket.recv_buf_size = 212992; 2018-10-15T11:53:53.406472Z 0 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:53:53.409072Z 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2018-10-15T11:53:53.409086Z 0 [Note] WSREP: Preparing to initiate SST/IST 2018-10-15T11:53:53.409089Z 0 [Note] WSREP: Starting replication 2018-10-15T11:53:53.409095Z 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:53:53.409287Z 0 [Note] WSREP: Using CRC-32C for message checksums. 2018-10-15T11:53:53.409362Z 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 2018-10-15T11:53:53.409466Z 0 [Warning] WSREP: Fail to access the file (/var/lib/percona-xtradb-cluster//gvwstate.dat) error (No such file or directory). It is possible if node is booting for first time or re-booting after a graceful shutdown 2018-10-15T11:53:53.409487Z 0 [Note] WSREP: Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown 2018-10-15T11:53:53.409668Z 0 [Note] WSREP: GMCast version 0 2018-10-15T11:53:53.409874Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2018-10-15T11:53:53.409880Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2018-10-15T11:53:53.410121Z 0 [Note] WSREP: EVS version 0 2018-10-15T11:53:53.410211Z 0 [Note] WSREP: gcomm: connecting to group 'trabber-cluster', peer '176.9.25.20:,176.9.63.4:,176.9.210.66:' 2018-10-15T11:53:53.410773Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') connection established to fcfc1b0c tcp://176.9.25.20:4567 2018-10-15T11:53:53.410783Z 0 [Warning] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') address 'tcp://176.9.25.20:4567' points to own listening address, blacklisting 2018-10-15T11:53:53.411342Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') connection established to 27013b5f tcp://176.9.63.4:4567 2018-10-15T11:53:53.411468Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2018-10-15T11:53:53.411493Z 0 [Note] WSREP: (fcfc1b0c, 'tcp://0.0.0.0:4567') connection established to 5a04dec8 tcp://176.9.210.66:4567 2018-10-15T11:53:53.412495Z 0 [Note] WSREP: declaring 27013b5f at tcp://176.9.63.4:4567 stable 2018-10-15T11:53:53.412502Z 0 [Note] WSREP: declaring 5a04dec8 at tcp://176.9.210.66:4567 stable 2018-10-15T11:53:53.412768Z 0 [Note] WSREP: Node 27013b5f state primary 2018-10-15T11:53:53.413107Z 0 [Note] WSREP: Current view of cluster as seen by this node view (view_id(PRIM,27013b5f,181) memb { 27013b5f,0 5a04dec8,0 fcfc1b0c,0 } joined { } left { } partitioned { } ) 2018-10-15T11:53:53.413114Z 0 [Note] WSREP: Save the discovered primary-component to disk 2018-10-15T11:53:53.910809Z 0 [Note] WSREP: gcomm: connected 2018-10-15T11:53:53.910918Z 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2018-10-15T11:53:53.911031Z 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 2, memb_num = 3 2018-10-15T11:53:53.911165Z 0 [Note] WSREP: Waiting for SST/IST to complete. 2018-10-15T11:53:53.911170Z 0 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID. 2018-10-15T11:53:53.911260Z 0 [Note] WSREP: STATE EXCHANGE: sent state msg: fd02e760-d070-11e8-aab8-924e84a45b71 2018-10-15T11:53:53.911284Z 0 [Warning] WSREP: Action message in non-primary configuration from member 1 2018-10-15T11:53:53.911318Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: fd02e760-d070-11e8-aab8-924e84a45b71 from 0 (garb) 2018-10-15T11:53:53.911326Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: fd02e760-d070-11e8-aab8-924e84a45b71 from 1 (jon) 2018-10-15T11:53:53.912032Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: fd02e760-d070-11e8-aab8-924e84a45b71 from 2 (sam) 2018-10-15T11:53:53.912042Z 0 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 177, members = 2/3 (primary/total), act_id = 20868167853, last_appl. = -1, protocols = 0/7/3 (gcs/repl/appl), group UUID = 7f4a4aab-e3df-11e6-9919-63561272760b 2018-10-15T11:53:53.912047Z 0 [Note] WSREP: Flow-control interval: [141, 141] 2018-10-15T11:53:53.912051Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:53:53.912055Z 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 20868167853) 2018-10-15T11:53:53.912157Z 2 [Note] WSREP: State transfer required: Group state: 7f4a4aab-e3df-11e6-9919-63561272760b:20868167853 Local state: 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:53:53.912172Z 2 [Note] WSREP: New cluster view: global state: 7f4a4aab-e3df-11e6-9919-63561272760b:20868167853, view# 178: Primary, number of nodes: 3, my index: 2, protocol version 3 2018-10-15T11:53:53.912177Z 2 [Note] WSREP: Setting wsrep_ready to true 2018-10-15T11:53:53.912179Z 2 [Warning] WSREP: Gap in state sequence. Need state transfer. 2018-10-15T11:53:53.912182Z 2 [Note] WSREP: Setting wsrep_ready to false 2018-10-15T11:53:53.912294Z 0 [Note] WSREP: Initiating SST/IST transfer on JOINER side (wsrep_sst_xtrabackup-v2 --role 'joiner' --address '176.9.25.20' --datadir '/var/lib/percona-xtradb-cluster/' --defaults-file '/etc/mysql/my.cnf' --defaults-group-suffix '' --parent '31523' '' ) 2018-10-15T11:53:54.279758Z 2 [Note] WSREP: Prepared SST/IST request: xtrabackup-v2|176.9.25.20:4444/xtrabackup_sst//1 2018-10-15T11:53:54.384086Z 2 [Note] WSREP: REPL Protocols: 7 (3, 2) 2018-10-15T11:53:54.384108Z 2 [Note] WSREP: Assign initial position for certification: 20868167853, protocol version: 3 2018-10-15T11:53:54.384185Z 0 [Note] WSREP: Service thread queue flushed. 2018-10-15T11:53:54.384227Z 2 [Note] WSREP: Check if state gap can be serviced using IST 2018-10-15T11:53:54.384261Z 2 [Note] WSREP: Local UUID: 00000000-0000-0000-0000-000000000000 != Group UUID: 7f4a4aab-e3df-11e6-9919-63561272760b 2018-10-15T11:53:54.384331Z 2 [Note] WSREP: State gap can't be serviced using IST. Switching to SST 2018-10-15T11:53:54.384339Z 2 [Note] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (7f4a4aab-e3df-11e6-9919-63561272760b): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():538. IST will be unavailable. 2018-10-15T11:53:54.385100Z 0 [Note] WSREP: Member 2.0 (sam) requested state transfer from '*any*'. Selected 1.0 (jon)(SYNCED) as donor. 2018-10-15T11:53:54.385123Z 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 20868168211) 2018-10-15T11:53:54.385150Z 2 [Note] WSREP: Requesting state transfer: success, donor: 1 2018-10-15T11:53:54.385166Z 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 7f4a4aab-e3df-11e6-9919-63561272760b:20868167853 2018-10-15T11:53:56.056496Z WSREP_SST: [ERROR] Cleanup after exit with status:1 2018-10-15T11:53:56.062787Z 0 [ERROR] WSREP: Process completed with error: wsrep_sst_xtrabackup-v2 --role 'joiner' --address '176.9.25.20' --datadir '/var/lib/percona-xtradb-cluster/' --defaults-file '/etc/mysql/my.cnf' --defaults-group-suffix '' --parent '31523' '' : 1 (Operation not permitted) 2018-10-15T11:53:56.062804Z 0 [ERROR] WSREP: Failed to read uuid:seqno from joiner script. 2018-10-15T11:53:56.062808Z 0 [ERROR] WSREP: SST script aborted with error 1 (Operation not permitted) 2018-10-15T11:53:56.062898Z 0 [ERROR] WSREP: SST failed: 1 (Operation not permitted) 2018-10-15T11:53:56.062929Z 0 [ERROR] Aborting 2018-10-15T11:53:56.062932Z 0 [Note] WSREP: Signalling cancellation of the SST request. 2018-10-15T11:53:56.062963Z 0 [Note] WSREP: SST request was cancelled 2018-10-15T11:53:56.062982Z 0 [Note] Giving 2 client threads a chance to die gracefully 2018-10-15T11:53:56.063005Z 2 [Note] WSREP: Closing send monitor... 2018-10-15T11:53:56.063029Z 2 [Note] WSREP: Closed send monitor. 2018-10-15T11:53:56.063051Z 2 [Note] WSREP: gcomm: terminating thread 2018-10-15T11:53:56.063073Z 2 [Note] WSREP: gcomm: joining thread 2018-10-15T11:53:56.063161Z 2 [Note] WSREP: gcomm: closing backend 2018-10-15T11:53:56.063697Z 2 [Note] WSREP: Current view of cluster as seen by this node view (view_id(NON_PRIM,27013b5f,181) memb { fcfc1b0c,0 } joined { } left { } partitioned { 27013b5f,0 5a04dec8,0 } ) 2018-10-15T11:53:56.063718Z 2 [Note] WSREP: Current view of cluster as seen by this node view ((empty)) 2018-10-15T11:53:56.063832Z 2 [Note] WSREP: gcomm: closed 2018-10-15T11:53:56.063870Z 0 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1 2018-10-15T11:53:56.063917Z 0 [Note] WSREP: Flow-control interval: [100, 100] 2018-10-15T11:53:56.063936Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:53:56.063939Z 0 [Note] WSREP: Received NON-PRIMARY. 2018-10-15T11:53:56.063942Z 0 [Note] WSREP: Shifting JOINER -> OPEN (TO: 20868169237) 2018-10-15T11:53:56.063946Z 0 [Note] WSREP: Received self-leave message. 2018-10-15T11:53:56.063949Z 0 [Note] WSREP: Flow-control interval: [0, 0] 2018-10-15T11:53:56.063952Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:53:56.063970Z 0 [Note] WSREP: Received SELF-LEAVE. Closing connection. 2018-10-15T11:53:56.063973Z 0 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 20868169237) 2018-10-15T11:53:56.063978Z 0 [Note] WSREP: RECV thread exiting 0: Success 2018-10-15T11:53:56.064031Z 2 [Note] WSREP: recv_thread() joined. 2018-10-15T11:53:56.064040Z 2 [Note] WSREP: Closing replication queue. 2018-10-15T11:53:56.064044Z 2 [Note] WSREP: Closing slave action queue. 2018-10-15T11:53:56.064122Z 2 [ERROR] WSREP: Writeset deserialization failed: Unsupported RecordSet version: 2: 71 (Protocol error) at galerautils/src/gu_rset.cpp:header_version():272 at galera/src/trx_handle.cpp:unserialize():268 WS flags: 0 Trx proto: 3 Trx source: 00000000-0000-0000-0000-000000000000 Trx conn_id: 18446744073709551615 Trx trx_id: 18446744073709551615 Trx last_seen: -1 2018-10-15T11:53:56.064144Z 2 [ERROR] WSREP: Unsupported RecordSet version: 2: 71 (Protocol error) at galerautils/src/gu_rset.cpp:header_version():272 at galera/src/trx_handle.cpp:unserialize():268 2018-10-15T11:53:56.064162Z 2 [Note] WSREP: applier thread exiting (code:7) 2018-10-15T11:53:56.064165Z 2 [ERROR] WSREP: Node consistency compromised. Aborting 2018-10-15T11:53:56.064168Z 2 [ERROR] Aborting 2018-10-15T11:53:56.064172Z 2 [Note] Giving 2 client threads a chance to die gracefully 2018-10-15T11:53:58.063254Z 0 [Note] WSREP: Waiting for active wsrep applier to exit 2018-10-15T11:53:58.063265Z 1 [Note] WSREP: rollbacker thread exiting 2018-10-15T11:53:58.064325Z 0 [Note] WSREP: Waiting for active wsrep applier to exit 2018-10-15T11:53:58.064354Z 0 [Note] WSREP: Service disconnected. 2018-10-15T11:53:58.064358Z 0 [Note] WSREP: Waiting to close threads...... 2018-10-15T11:53:58.064376Z 0 [Note] WSREP: Service disconnected. 2018-10-15T11:53:58.064399Z 0 [Note] WSREP: Waiting to close threads...... 2018-10-15T11:54:03.064612Z 0 [Note] WSREP: Some threads may fail to exit. 2018-10-15T11:54:03.064649Z 0 [Note] Binlog end 2018-10-15T11:54:03.064616Z 0 [Note] WSREP: Some threads may fail to exit. 2018-10-15T11:54:03.065365Z 0 [Note] mysqld: Shutdown complete Fatal signal 6 while backtracing 2018-10-15T11:54:03.322090Z mysqld_safe Logging to '/var/log/mysqld.log'. 2018-10-15T11:54:03.332512Z mysqld_safe Starting mysqld daemon with databases from /var/lib/percona-xtradb-cluster 2018-10-15T11:54:03.337946Z mysqld_safe WSREP: Running position recovery with --log_error='/var/lib/percona-xtradb-cluster/wsrep_recovery.Q4gDha' --pid-file='/var/lib/percona-xtradb-cluster/sam-recover.pid' 2018-10-15T11:54:07.699669Z mysqld_safe WSREP: Recovered position 00000000-0000-0000-0000-000000000000:-1 Log of wsrep recovery (--wsrep-recover): 2018-10-15T11:54:03.342387Z 0 [Warning] Changed limits: max_open_files: 5000 (requested 6005) 2018-10-15T11:54:03.342414Z 0 [Warning] Changed limits: table_open_cache: 1895 (requested 2000) 2018-10-15T11:54:03.506448Z 0 [Note] /usr/sbin/mysqld (mysqld 5.7.20-18-18-log) starting as process 575 ... 2018-10-15T11:54:03.535366Z 0 [Note] InnoDB: PUNCH HOLE support available 2018-10-15T11:54:03.535386Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2018-10-15T11:54:03.535389Z 0 [Note] InnoDB: Uses event mutexes 2018-10-15T11:54:03.535392Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2018-10-15T11:54:03.535394Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.11 2018-10-15T11:54:03.535396Z 0 [Note] InnoDB: Using Linux native AIO 2018-10-15T11:54:03.535747Z 0 [Note] InnoDB: Number of pools: 1 2018-10-15T11:54:03.535806Z 0 [Note] InnoDB: Using CPU crc32 instructions 2018-10-15T11:54:03.556219Z 0 [Note] InnoDB: Initializing buffer pool, total size = 45G, instances = 8, chunk size = 128M 2018-10-15T11:54:04.377328Z 0 [Note] InnoDB: Completed initialization of buffer pool 2018-10-15T11:54:04.664853Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority(). 2018-10-15T11:54:04.677779Z 0 [Note] InnoDB: Crash recovery did not find the parallel doublewrite buffer at /var/lib/percona-xtradb-cluster/xb_doublewrite 2018-10-15T11:54:04.678475Z 0 [Note] InnoDB: Highest supported file format is Barracuda. 2018-10-15T11:54:05.037046Z 0 [Note] InnoDB: Created parallel doublewrite buffer at /var/lib/percona-xtradb-cluster/xb_doublewrite, size 31457280 bytes 2018-10-15T11:54:05.402914Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables 2018-10-15T11:54:05.402985Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ... 2018-10-15T11:54:05.422542Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB. 2018-10-15T11:54:05.423002Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active. 2018-10-15T11:54:05.423008Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active. 2018-10-15T11:54:05.423240Z 0 [Note] InnoDB: Waiting for purge to start 2018-10-15T11:54:05.473825Z 0 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.7.20-18 started; log sequence number 2570652 2018-10-15T11:54:05.473849Z 0 [Warning] InnoDB: Skipping buffer pool dump/restore during wsrep recovery. 2018-10-15T11:54:05.474059Z 0 [Note] Plugin 'FEDERATED' is disabled. 2018-10-15T11:54:05.476836Z 0 [Note] WSREP: Recovered position: 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:54:05.476845Z 0 [Note] Binlog end 2018-10-15T11:54:05.476867Z 0 [Note] Shutting down plugin 'ngram' 2018-10-15T11:54:05.476870Z 0 [Note] Shutting down plugin 'partition' 2018-10-15T11:54:05.476872Z 0 [Note] Shutting down plugin 'BLACKHOLE' 2018-10-15T11:54:05.476874Z 0 [Note] Shutting down plugin 'ARCHIVE' 2018-10-15T11:54:05.476875Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA' 2018-10-15T11:54:05.476899Z 0 [Note] Shutting down plugin 'MRG_MYISAM' 2018-10-15T11:54:05.476901Z 0 [Note] Shutting down plugin 'MyISAM' 2018-10-15T11:54:05.476905Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL' 2018-10-15T11:54:05.476907Z 0 [Note] Shutting down plugin 'INNODB_CHANGED_PAGES' 2018-10-15T11:54:05.476909Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES' 2018-10-15T11:54:05.476910Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES' 2018-10-15T11:54:05.476912Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS' 2018-10-15T11:54:05.476913Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN' 2018-10-15T11:54:05.476915Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS' 2018-10-15T11:54:05.476916Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS' 2018-10-15T11:54:05.476918Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES' 2018-10-15T11:54:05.476919Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS' 2018-10-15T11:54:05.476921Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES' 2018-10-15T11:54:05.476927Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE' 2018-10-15T11:54:05.476928Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE' 2018-10-15T11:54:05.476930Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG' 2018-10-15T11:54:05.476931Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED' 2018-10-15T11:54:05.476933Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED' 2018-10-15T11:54:05.476934Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD' 2018-10-15T11:54:05.476936Z 0 [Note] Shutting down plugin 'INNODB_METRICS' 2018-10-15T11:54:05.476938Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO' 2018-10-15T11:54:05.476939Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS' 2018-10-15T11:54:05.476941Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU' 2018-10-15T11:54:05.476942Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE' 2018-10-15T11:54:05.476944Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET' 2018-10-15T11:54:05.476945Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX' 2018-10-15T11:54:05.476947Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET' 2018-10-15T11:54:05.476948Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM' 2018-10-15T11:54:05.476950Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET' 2018-10-15T11:54:05.476951Z 0 [Note] Shutting down plugin 'INNODB_CMP' 2018-10-15T11:54:05.476953Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS' 2018-10-15T11:54:05.476954Z 0 [Note] Shutting down plugin 'INNODB_LOCKS' 2018-10-15T11:54:05.476956Z 0 [Note] Shutting down plugin 'INNODB_TRX' 2018-10-15T11:54:05.476957Z 0 [Note] Shutting down plugin 'XTRADB_ZIP_DICT_COLS' 2018-10-15T11:54:05.476959Z 0 [Note] Shutting down plugin 'XTRADB_ZIP_DICT' 2018-10-15T11:54:05.476960Z 0 [Note] Shutting down plugin 'XTRADB_RSEG' 2018-10-15T11:54:05.476962Z 0 [Note] Shutting down plugin 'XTRADB_INTERNAL_HASH_TABLES' 2018-10-15T11:54:05.476963Z 0 [Note] Shutting down plugin 'XTRADB_READ_VIEW' 2018-10-15T11:54:05.476965Z 0 [Note] Shutting down plugin 'InnoDB' 2018-10-15T11:54:05.477043Z 0 [Note] InnoDB: FTS optimize thread exiting. 2018-10-15T11:54:05.477117Z 0 [Note] InnoDB: Starting shutdown... 2018-10-15T11:54:06.579316Z 0 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool 2018-10-15T11:54:07.609865Z 0 [Note] InnoDB: Shutdown completed; log sequence number 2570855 2018-10-15T11:54:07.609915Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1" 2018-10-15T11:54:07.609921Z 0 [Note] Shutting down plugin 'MEMORY' 2018-10-15T11:54:07.609924Z 0 [Note] Shutting down plugin 'CSV' 2018-10-15T11:54:07.609927Z 0 [Note] Shutting down plugin 'sha256_password' 2018-10-15T11:54:07.609929Z 0 [Note] Shutting down plugin 'mysql_native_password' 2018-10-15T11:54:07.609930Z 0 [Note] Shutting down plugin 'wsrep' 2018-10-15T11:54:07.610019Z 0 [Note] Shutting down plugin 'binlog' 2018-10-15T11:54:07.645468Z 0 [Note] /usr/sbin/mysqld: Shutdown complete 2018-10-15T11:54:07.704897Z 0 [Warning] Changed limits: max_open_files: 5000 (requested 6005) 2018-10-15T11:54:07.704924Z 0 [Warning] Changed limits: table_open_cache: 1895 (requested 2000) 2018-10-15T11:54:07.866511Z 0 [Note] /usr/sbin/mysqld (mysqld 5.7.20-18-18-log) starting as process 654 ... 2018-10-15T11:54:07.867678Z 0 [Note] WSREP: Setting wsrep_ready to false 2018-10-15T11:54:07.867702Z 0 [Note] WSREP: No pre-stored wsrep-start position found. Skipping position initialization. 2018-10-15T11:54:07.867719Z 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so' 2018-10-15T11:54:07.869377Z 0 [Note] WSREP: wsrep_load(): Galera 3.24(r) by Codership Oy loaded successfully. 2018-10-15T11:54:07.869422Z 0 [Note] WSREP: CRC-32C: using hardware acceleration. 2018-10-15T11:54:07.869643Z 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1 2018-10-15T11:54:07.875753Z 0 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/percona-xtradb-cluster/; base_host = 176.9.25.20; base_port = 4567; cert.log_conflicts = no; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 10; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 4; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/percona-xtradb-cluster/; gcache.freeze_purge_at_seqno = -1; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/percona-xtradb-cluster//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 1024M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1; gcs.fc_limit = 100; gcs.fc_master_slave = no; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = false; pc.recovery = 1; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = PT30S; pc.weight = 1; protonet.backend = asio; protonet.version = 0; repl.causal_read_timeout = PT30S; repl.commit_order = 3; repl.key_format = FLAT8; repl.max_ws_size = 2147483647; repl.proto_max = 7; socket.checksum = 2; socket.recv_buf_size = 212992; 2018-10-15T11:54:07.885482Z 0 [Note] WSREP: GCache history reset: 7f4a4aab-e3df-11e6-9919-63561272760b:0 -> 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:54:07.887957Z 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2018-10-15T11:54:07.887989Z 0 [Note] WSREP: Preparing to initiate SST/IST 2018-10-15T11:54:07.887992Z 0 [Note] WSREP: Starting replication 2018-10-15T11:54:07.888013Z 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:54:07.888211Z 0 [Note] WSREP: Using CRC-32C for message checksums. 2018-10-15T11:54:07.888283Z 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 2018-10-15T11:54:07.888397Z 0 [Warning] WSREP: Fail to access the file (/var/lib/percona-xtradb-cluster//gvwstate.dat) error (No such file or directory). It is possible if node is booting for first time or re-booting after a graceful shutdown 2018-10-15T11:54:07.888406Z 0 [Note] WSREP: Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown 2018-10-15T11:54:07.888584Z 0 [Note] WSREP: GMCast version 0 2018-10-15T11:54:07.888806Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2018-10-15T11:54:07.888827Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2018-10-15T11:54:07.889092Z 0 [Note] WSREP: EVS version 0 2018-10-15T11:54:07.889169Z 0 [Note] WSREP: gcomm: connecting to group 'trabber-cluster', peer '176.9.25.20:,176.9.63.4:,176.9.210.66:' 2018-10-15T11:54:07.889629Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') connection established to 059d69da tcp://176.9.25.20:4567 2018-10-15T11:54:07.889639Z 0 [Warning] WSREP: (059d69da, 'tcp://0.0.0.0:4567') address 'tcp://176.9.25.20:4567' points to own listening address, blacklisting 2018-10-15T11:54:07.890613Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') connection established to 5a04dec8 tcp://176.9.210.66:4567 2018-10-15T11:54:07.890668Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2018-10-15T11:54:07.890892Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') connection established to 27013b5f tcp://176.9.63.4:4567 2018-10-15T11:54:07.914078Z 0 [Note] WSREP: declaring 27013b5f at tcp://176.9.63.4:4567 stable 2018-10-15T11:54:07.914103Z 0 [Note] WSREP: declaring 5a04dec8 at tcp://176.9.210.66:4567 stable 2018-10-15T11:54:07.914415Z 0 [Note] WSREP: Node 27013b5f state primary 2018-10-15T11:54:07.914788Z 0 [Note] WSREP: Current view of cluster as seen by this node view (view_id(PRIM,059d69da,183) memb { 059d69da,0 27013b5f,0 5a04dec8,0 } joined { } left { } partitioned { } ) 2018-10-15T11:54:07.914798Z 0 [Note] WSREP: Save the discovered primary-component to disk 2018-10-15T11:54:08.389709Z 0 [Note] WSREP: gcomm: connected 2018-10-15T11:54:08.389838Z 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2018-10-15T11:54:08.389921Z 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 3 2018-10-15T11:54:08.389950Z 0 [Note] WSREP: Waiting for SST/IST to complete. 2018-10-15T11:54:08.390044Z 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 05e9f098-d071-11e8-ae72-1a1a5d0244ef 2018-10-15T11:54:08.390058Z 0 [Warning] WSREP: Action message in non-primary configuration from member 2 2018-10-15T11:54:08.390871Z 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 05e9f098-d071-11e8-ae72-1a1a5d0244ef 2018-10-15T11:54:08.391283Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: 05e9f098-d071-11e8-ae72-1a1a5d0244ef from 0 (sam) 2018-10-15T11:54:08.391298Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: 05e9f098-d071-11e8-ae72-1a1a5d0244ef from 1 (garb) 2018-10-15T11:54:08.391304Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: 05e9f098-d071-11e8-ae72-1a1a5d0244ef from 2 (jon) 2018-10-15T11:54:08.391311Z 0 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 179, members = 2/3 (primary/total), act_id = 20868171092, last_appl. = -1, protocols = 0/7/3 (gcs/repl/appl), group UUID = 7f4a4aab-e3df-11e6-9919-63561272760b 2018-10-15T11:54:08.391317Z 0 [Note] WSREP: Flow-control interval: [141, 141] 2018-10-15T11:54:08.391321Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:54:08.391339Z 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 20868171092) 2018-10-15T11:54:08.391427Z 2 [Note] WSREP: State transfer required: Group state: 7f4a4aab-e3df-11e6-9919-63561272760b:20868171092 Local state: 00000000-0000-0000-0000-000000000000:-1 2018-10-15T11:54:08.391442Z 2 [Note] WSREP: New cluster view: global state: 7f4a4aab-e3df-11e6-9919-63561272760b:20868171092, view# 180: Primary, number of nodes: 3, my index: 0, protocol version 3 2018-10-15T11:54:08.391447Z 2 [Note] WSREP: Setting wsrep_ready to true 2018-10-15T11:54:08.391449Z 2 [Warning] WSREP: Gap in state sequence. Need state transfer. 2018-10-15T11:54:08.391452Z 2 [Note] WSREP: Setting wsrep_ready to false 2018-10-15T11:54:08.391607Z 0 [Note] WSREP: Initiating SST/IST transfer on JOINER side (wsrep_sst_xtrabackup-v2 --role 'joiner' --address '176.9.25.20' --datadir '/var/lib/percona-xtradb-cluster/' --defaults-file '/etc/mysql/my.cnf' --defaults-group-suffix '' --parent '654' '' ) 2018-10-15T11:54:08.542842Z WSREP_SST: [WARNING] Found a stale sst_in_progress file: /var/lib/percona-xtradb-cluster//sst_in_progress 2018-10-15T11:54:08.759598Z 2 [Note] WSREP: Prepared SST/IST request: xtrabackup-v2|176.9.25.20:4444/xtrabackup_sst//1 2018-10-15T11:54:08.863867Z 2 [Note] WSREP: REPL Protocols: 7 (3, 2) 2018-10-15T11:54:08.863919Z 2 [Note] WSREP: Assign initial position for certification: 20868171092, protocol version: 3 2018-10-15T11:54:08.864015Z 0 [Note] WSREP: Service thread queue flushed. 2018-10-15T11:54:08.864048Z 2 [Note] WSREP: Check if state gap can be serviced using IST 2018-10-15T11:54:08.864092Z 2 [Note] WSREP: Local UUID: 00000000-0000-0000-0000-000000000000 != Group UUID: 7f4a4aab-e3df-11e6-9919-63561272760b 2018-10-15T11:54:08.864114Z 2 [Note] WSREP: State gap can't be serviced using IST. Switching to SST 2018-10-15T11:54:08.864119Z 2 [Note] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (7f4a4aab-e3df-11e6-9919-63561272760b): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():538. IST will be unavailable. 2018-10-15T11:54:08.864750Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:08.864791Z 2 [Note] WSREP: Requesting state transfer failed: -11(Resource temporarily unavailable). Will keep retrying every 1 second(s) 2018-10-15T11:54:09.868290Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:10.869092Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:10.890099Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') connection to peer 059d69da with addr tcp://176.9.25.20:4567 timed out, no messages seen in PT3S (gmcast.peer_timeout) 2018-10-15T11:54:11.390101Z 0 [Note] WSREP: (059d69da, 'tcp://0.0.0.0:4567') turning message relay requesting off 2018-10-15T11:54:11.872429Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:12.873365Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:13.874461Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable 2018-10-15T11:54:14.875395Z 0 [Warning] WSREP: Member 0.0 (sam) requested state transfer from '*any*', but it is impossible to select State Transfer donor: Resource temporarily unavailable Terminated 2018-10-15T11:54:14.976677Z WSREP_SST: [ERROR] Removing /var/lib/percona-xtradb-cluster//xtrabackup_galera_info file due to signal 2018-10-15T11:54:14.978065Z WSREP_SST: [ERROR] Removing file due to signal 2018-10-15T11:54:14.979403Z WSREP_SST: [ERROR] ******************* FATAL ERROR ********************** 2018-10-15T11:54:14.980024Z WSREP_SST: [ERROR] Error while getting data from donor node: exit codes: 143 143 2018-10-15T11:54:14.980705Z WSREP_SST: [ERROR] ****************************************************** 2018-10-15T11:54:14.981472Z WSREP_SST: [ERROR] Cleanup after exit with status:32 2018-10-15T11:54:14.988337Z 0 [ERROR] WSREP: Process was aborted. 2018-10-15T11:54:14.988357Z 0 [ERROR] WSREP: Process completed with error: wsrep_sst_xtrabackup-v2 --role 'joiner' --address '176.9.25.20' --datadir '/var/lib/percona-xtradb-cluster/' --defaults-file '/etc/mysql/my.cnf' --defaults-group-suffix '' --parent '654' '' : 2 (No such file or directory) 2018-10-15T11:54:14.988364Z 0 [ERROR] WSREP: Failed to read uuid:seqno from joiner script. 2018-10-15T11:54:14.988382Z 0 [ERROR] WSREP: SST script aborted with error 2 (No such file or directory) 2018-10-15T11:54:14.988443Z 0 [ERROR] WSREP: SST failed: 2 (No such file or directory) 2018-10-15T11:54:14.988456Z 0 [ERROR] Aborting 2018-10-15T11:54:14.988462Z 0 [Note] WSREP: Signalling cancellation of the SST request. 2018-10-15T11:54:14.988477Z 0 [Note] WSREP: SST request was cancelled 2018-10-15T11:54:15.004591Z 0 [Warning] WSREP: Could not find peer: 2018-10-15T11:54:15.004641Z 0 [Warning] WSREP: 2.0 (jon): State transfer to -1.-1 (left the group) failed: -22 (Invalid argument) 2018-10-15T11:54:15.005112Z 0 [Note] WSREP: Member 2.0 (jon) synced with group. 2018-10-15T11:54:15.876461Z 0 [Note] WSREP: Member 0.0 (sam) requested state transfer from '*any*'. Selected 2.0 (jon)(SYNCED) as donor. 2018-10-15T11:54:15.876486Z 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 20868171630) 2018-10-15T11:54:15.876600Z 2 [Note] WSREP: Requesting state transfer: success after 8 tries, donor: 2 2018-10-15T11:54:15.876619Z 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 7f4a4aab-e3df-11e6-9919-63561272760b:20868171092 2018-10-15T11:54:15.879491Z 0 [Note] Giving 2 client threads a chance to die gracefully 2018-10-15T11:54:15.879552Z 2 [Note] WSREP: Closing send monitor... 2018-10-15T11:54:15.879566Z 2 [Note] WSREP: Closed send monitor. 2018-10-15T11:54:15.879588Z 2 [Note] WSREP: gcomm: terminating thread 2018-10-15T11:54:15.879600Z 2 [Note] WSREP: gcomm: joining thread 2018-10-15T11:54:15.879725Z 2 [Note] WSREP: gcomm: closing backend 2018-10-15T11:54:15.880323Z 2 [Note] WSREP: Current view of cluster as seen by this node view (view_id(NON_PRIM,059d69da,183) memb { 059d69da,0 } joined { } left { } partitioned { 27013b5f,0 5a04dec8,0 } ) 2018-10-15T11:54:15.880367Z 2 [Note] WSREP: Current view of cluster as seen by this node view ((empty)) 2018-10-15T11:54:15.880557Z 2 [Note] WSREP: gcomm: closed 2018-10-15T11:54:15.880598Z 0 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1 2018-10-15T11:54:15.880645Z 0 [Note] WSREP: Flow-control interval: [100, 100] 2018-10-15T11:54:15.880662Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:54:15.880665Z 0 [Note] WSREP: Received NON-PRIMARY. 2018-10-15T11:54:15.880668Z 0 [Note] WSREP: Shifting JOINER -> OPEN (TO: 20868171630) 2018-10-15T11:54:15.880686Z 0 [Note] WSREP: Received self-leave message. 2018-10-15T11:54:15.880689Z 0 [Note] WSREP: Flow-control interval: [0, 0] 2018-10-15T11:54:15.880691Z 0 [Note] WSREP: Trying to continue unpaused monitor 2018-10-15T11:54:15.880694Z 0 [Note] WSREP: Received SELF-LEAVE. Closing connection. 2018-10-15T11:54:15.880696Z 0 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 20868171630) 2018-10-15T11:54:15.880700Z 0 [Note] WSREP: RECV thread exiting 0: Success 2018-10-15T11:54:15.880748Z 2 [Note] WSREP: recv_thread() joined. 2018-10-15T11:54:15.880757Z 2 [Note] WSREP: Closing replication queue. 2018-10-15T11:54:15.880761Z 2 [Note] WSREP: Closing slave action queue. 2018-10-15T11:54:15.880833Z 2 [ERROR] WSREP: Writeset deserialization failed: Unsupported RecordSet version: 2: 71 (Protocol error) at galerautils/src/gu_rset.cpp:header_version():272 at galera/src/trx_handle.cpp:unserialize():268 WS flags: 0 Trx proto: 3 Trx source: 00000000-0000-0000-0000-000000000000 Trx conn_id: 18446744073709551615 Trx trx_id: 18446744073709551615 Trx last_seen: -1 2018-10-15T11:54:15.880876Z 2 [ERROR] WSREP: Unsupported RecordSet version: 2: 71 (Protocol error) at galerautils/src/gu_rset.cpp:header_version():272 at galera/src/trx_handle.cpp:unserialize():268 2018-10-15T11:54:15.880895Z 2 [Note] WSREP: applier thread exiting (code:7) 2018-10-15T11:54:15.880898Z 2 [ERROR] WSREP: Node consistency compromised. Aborting 2018-10-15T11:54:15.880902Z 2 [ERROR] Aborting 2018-10-15T11:54:15.880905Z 2 [Note] Giving 2 client threads a chance to die gracefully 2018-10-15T11:54:17.879795Z 0 [Note] WSREP: Waiting for active wsrep applier to exit 2018-10-15T11:54:17.879840Z 1 [Note] WSREP: rollbacker thread exiting 2018-10-15T11:54:17.881078Z 0 [Note] WSREP: Waiting for active wsrep applier to exit 2018-10-15T11:54:17.881107Z 0 [Note] WSREP: Service disconnected. 2018-10-15T11:54:17.881113Z 0 [Note] WSREP: Waiting to close threads...... 2018-10-15T11:54:17.881108Z 0 [Note] WSREP: Service disconnected. 2018-10-15T11:54:17.881159Z 0 [Note] WSREP: Waiting to close threads...... 2018-10-15T11:54:22.881273Z 0 [Note] WSREP: Some threads may fail to exit. 2018-10-15T11:54:22.881318Z 0 [Note] Binlog end 2018-10-15T11:54:22.881273Z 0 [Note] WSREP: Some threads may fail to exit. 2018-10-15T11:54:22.881426Z 0 [Note] /usr/sbin/mysqld: Shutdown complete Fatal signal 6 while backtracing