2017-09-01 13:39:49 2692439 [Note] WSREP: gcomm/src/gmcast.cpp:handle_established():555: (af813e7a, 'tcp://0.0.0.0:4567') connection established to 06be8576 tcp://192.168.11.6:4567 2017-09-01 13:39:50 2692439 [Note] WSREP: gcomm/src/gmcast.cpp:check_liveness():1147: (af813e7a, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2017-09-01 13:39:50 2692439 [Note] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1597: declaring 06be8576 at tcp://192.168.11.6:4567 stable 2017-09-01 13:39:50 2692439 [Note] WSREP: gcomm/src/pc_proto.cpp:is_prim():796: Node af813e7a state prim 2017-09-01 13:39:50 2692439 [Note] WSREP: gcomm/src/pc_proto.cpp:deliver_view():229: view(view_id(PRIM,06be8576,24) memb { 2017-09-01 13:39:50 2692439 [Note] WSREP: gcomm/src/pc.cpp:handle_up():30: save pc into disk 2017-09-01 13:39:50 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_comp_msg():410: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2 2017-09-01 13:39:50 2692439 [Note] WSREP: gcs/src/gcs_core.cpp:core_handle_comp_msg():771: STATE EXCHANGE: Waiting for state UUID. 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_core.cpp:core_handle_uuid_msg():861: STATE EXCHANGE: sent state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_state_msg():592: STATE EXCHANGE: got state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 from 0 (Node2) 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:group_print_state_debug():574: 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_state_msg():592: STATE EXCHANGE: got state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 from 1 (Node1) 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:group_print_state_debug():574: 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:group_post_state_exchange():369: Quorum results: 2017-09-01 13:39:51 2692439 [Note] WSREP: gcs/src/gcs.cpp:_set_fc_limits():799: Flow-control interval: [23, 23] 2017-09-01 13:39:51 2692439 [Note] WSREP: New cluster view: global state: af81d29b-8a9a-11e7-92d4-962488332acf:16744963, view# 24: Primary, number of nodes: 2, my index: 1, protocol version 3 2017-09-01 13:39:51 2692439 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2017-09-01 13:39:51 2692439 [Note] WSREP: galera/src/replicator_smm.cpp:establish_protocol_versions():1365: REPL Protocols: 7 (3, 2) 2017-09-01 13:39:51 2692439 [Note] WSREP: galera/src/certification.cpp:assign_initial_position():905: Assign initial position for certification: 16744963, protocol version: 3 2017-09-01 13:39:51 2692439 [Note] WSREP: galera/src/galera_service_thd.cpp:thd_func():39: Service thread queue flushed. 2017-09-01 13:39:54 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:group_select_donor():1312: Member 0.0 (Node2) requested state transfer from 'Node1'. Selected 1.0 (Node1)(SYNCED) as donor. 2017-09-01 13:39:54 2692439 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():595: Shifting SYNCED -> DONOR/DESYNCED (TO: 16744965) 2017-09-01 13:39:54 2692439 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2017-09-01 13:39:54 2692439 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'donor' --address '192.168.11.6:4444/xtrabackup_sst//1' --socket '/var/lib/mysql/mysql.sock' --datadir '/var/lib/mysql/' --defaults-file '/etc/my.cnf' --defaults-group-suffix '' '' --gtid 'af81d29b-8a9a-11e7-92d4-962488332acf:16744965'' 2017-09-01 13:39:54 2692439 [Note] WSREP: sst_donor_thread signaled with 0 WSREP_SST: [INFO] Streaming with xbstream (2017-09-01 13:40:12) WSREP_SST: [INFO] Using socat as streamer (2017-09-01 13:40:13) WSREP_SST: [INFO] Streaming GTID file before SST (2017-09-01 13:40:14) WSREP_SST: [INFO] Evaluating xbstream -c ${INFO_FILE} | socat -T 3600 -t 3600 -u stdio TCP:192.168.11.6:4444,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10; RC=( ${PIPESTATUS[@]} ) (2017-09-01 13:40:14) WSREP_SST: [INFO] Evaluated command: xbstream -c ${INFO_FILE} | socat -T 3600 -t 3600 -u stdio TCP:192.168.11.6:4444,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10; RC=( ${PIPESTATUS[@]} ) (2017-09-01 13:40:15) WSREP_SST: [INFO] Exit code : 0 (2017-09-01 13:40:15) WSREP_SST: [INFO] Sleeping before data transfer for SST (2017-09-01 13:40:15) 2017-09-01 13:41:20 2692439 [Note] WSREP: gcomm/src/gmcast.cpp:check_liveness():1188: (af813e7a, 'tcp://0.0.0.0:4567') turning message relay requesting off WSREP_SST: [INFO] Streaming the backup to joiner at 192.168.11.6 4444 (2017-09-01 13:41:45) WSREP_SST: [INFO] Evaluating innobackupex --defaults-file=/etc/my.cnf --defaults-group=mysqld --no-version-check $INNOEXTRA --galera-info --stream=$sfmt $itmpdir 2>${DATA}/innobackup.backup.log | socat -T 3600 -t 3600 -u stdio TCP:192.168.11.6:4444,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10; RC=( ${PIPESTATUS[@]} ) (2017-09-01 13:41:45) WSREP_SST: [INFO] Evaluated command: innobackupex --defaults-file=/etc/my.cnf --defaults-group=mysqld --no-version-check $INNOEXTRA --galera-info --stream=$sfmt $itmpdir 2>${DATA}/innobackup.backup.log | socat -T 3600 -t 3600 -u stdio TCP:192.168.11.6:4444,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10; RC=( ${PIPESTATUS[@]} ) (2017-09-01 23:57:26) WSREP_SST: [INFO] Exit code : 0 (2017-09-01 23:57:26) 2017-09-01 23:57:26 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_join_msg():792: 1.0 (Node1): State transfer to 0.0 (Node2) complete. 2017-09-01 23:57:26 2692439 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():595: Shifting DONOR/DESYNCED -> JOINED (TO: 19135007) 2017-09-01 23:57:26 2692439 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_sync_msg():838: Member 1.0 (Node1) synced with group. 2017-09-01 23:57:26 2692439 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():595: Shifting JOINED -> SYNCED (TO: 19135007) 2017-09-01 23:57:26 2692439 [Note] WSREP: Synchronized with group, ready for connections 2017-09-01 23:57:26 2692439 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. WSREP_SST: [INFO] Total time on donor: 0 seconds (2017-09-01 23:57:26) WSREP_SST: [INFO] Cleaning up temporary directories (2017-09-01 23:57:26)