2018-01-19T11:03:17.608834Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2018-01-19T11:03:17.608958Z 0 [Warning] WSREP: Node is running in bootstrap/initialize mode. Disabling pxc_strict_mode checks 2018-01-19T11:03:18.869883Z 0 [Warning] InnoDB: New log files created, LSN=45790 2018-01-19T11:03:19.130408Z 0 [Warning] InnoDB: Creating foreign key constraint system tables. 2018-01-19T11:03:19.231641Z 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: 5b5a8d3c-fd08-11e7-ad25-001a4a160161. 2018-01-19T11:03:19.233890Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened. 2018-01-19T11:03:20.062934Z 0 [Warning] CA certificate ca.pem is self signed. 2018-01-19T11:03:20.292619Z 1 [Note] A temporary password is generated for root@localhost: DlCcweLTX2>l 2018-01-19T11:03:31.036857Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2018-01-19T11:03:31.038409Z 0 [Note] /usr/sbin/mysqld (mysqld 5.7.19-17-57-log) starting as process 29406 ... 2018-01-19T11:03:31.042088Z 0 [Note] WSREP: Setting wsrep_ready to false 2018-01-19T11:03:31.042118Z 0 [Note] WSREP: No pre-stored wsrep-start position found. Skipping position initialization. 2018-01-19T11:03:31.042129Z 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera3/libgalera_smm.so' 2018-01-19T11:03:31.047745Z 0 [Note] WSREP: wsrep_load(): Galera 3.22(r8678538) by Codership Oy loaded successfully. 2018-01-19T11:03:31.047844Z 0 [Note] WSREP: CRC-32C: using hardware acceleration. 2018-01-19T11:03:31.048324Z 0 [Note] [Debug] WSREP: galerautils/src/gu_log.c:gu_conf_debug_on():58: Turning debug logging on 2018-01-19T11:03:31.048391Z 0 [Warning] WSREP: galera/src/saved_state.cpp:SavedState():48: Could not open state file for reading: '/var/lib/mysql//grastate.dat' 2018-01-19T11:03:31.048406Z 0 [Warning] WSREP: galera/src/saved_state.cpp:SavedState():49: No persistent state found. Bootstraping with default state 2018-01-19T11:03:31.048474Z 0 [Note] WSREP: galera/src/saved_state.cpp:SavedState():120: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootsrap: 1 2018-01-19T11:03:31.048490Z 0 [Note] [Debug] WSREP: galera/src/saved_state.cpp:SavedState():135: Initialized current_len_ to 0 2018-01-19T11:03:31.048574Z 0 [Note] [Debug] WSREP: galerautils/src/gu_fdesc.cpp:constructor_common():204: Opened file '/var/lib/mysql//galera.cache', size: 134219048 2018-01-19T11:03:31.048588Z 0 [Note] [Debug] WSREP: galerautils/src/gu_fdesc.cpp:constructor_common():205: File descriptor: 4 2018-01-19T11:03:31.048633Z 0 [Note] [Debug] WSREP: galerautils/src/gu_fdesc.cpp:prealloc():304: Preallocating 134219048/134219048 bytes in '/var/lib/mysql//galera.cache'... 2018-01-19T11:03:31.048738Z 0 [Note] [Debug] WSREP: galerautils/src/gu_mmap.cpp:MMap():74: Memory mapped: 0x7fa741010000 (134219048 bytes) 2018-01-19T11:03:31.060049Z 0 [Note] [Debug] WSREP: gcs/src/gcs.cpp:gcs_create():318: Requesting recv queue len: 14908909 2018-01-19T11:03:31.060076Z 0 [Note] [Debug] WSREP: galerautils/src/gu_fifo.c:gu_fifo_create():127: Creating FIFO buffer of 16777216 elements of size 56, memory min used: 65800, max used: 939589896 2018-01-19T11:03:31.060911Z 0 [Note] WSREP: galera/src/galera_gcs.hpp:Gcs():89: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = [fd00:faca:0:b0::192]; base_port = 4567; cert.log_conflicts = no; debug = yes; 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/mysql/; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 128M; 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.listen_addr = tcp://[::]:4567; 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-01-19T11:03:31.061356Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: ist.recv_bind not set. 2018-01-19T11:03:31.061398Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: ist.recv_addr not set. 2018-01-19T11:03:31.061420Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::192] 2018-01-19T11:03:31.077405Z 0 [Note] [Debug] WSREP: galera/src/replicator_smm.cpp:ReplicatorSMM():274: End state: 00000000-0000-0000-0000-000000000000:-1 ################# 2018-01-19T11:03:31.077472Z 0 [Note] WSREP: gcache/src/GCache_seqno.cpp:seqno_reset():28: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1 2018-01-19T11:03:31.080587Z 0 [Note] WSREP: galera/src/certification.cpp:assign_initial_position():913: Assign initial position for certification: -1, protocol version: -1 2018-01-19T11:03:31.080621Z 0 [Note] WSREP: Preparing to initiate SST/IST 2018-01-19T11:03:31.080630Z 0 [Note] WSREP: Starting replication 2018-01-19T11:03:31.080652Z 0 [Note] WSREP: galera/src/replicator_smm.cpp:connect():331: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2018-01-19T11:03:31.080672Z 0 [Note] [Debug] WSREP: gcs/src/gcs_core.cpp:gcs_core_open():197: Initializing backend IO layer 2018-01-19T11:03:31.080687Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: pc://[fd00:faca:0:b0::164],[fd00:faca:0:b0::177],[fd00:faca:0:b0::192] 2018-01-19T11:03:31.080841Z 0 [Note] [Debug] WSREP: gcomm/src/protonet.cpp:create():71: protonet asio version 0 2018-01-19T11:03:31.081031Z 0 [Note] WSREP: gcomm/src/datagram.cpp:checksum_type():26: Using CRC-32C for message checksums. 2018-01-19T11:03:31.081056Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: socket.ssl not set. 2018-01-19T11:03:31.081101Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:GCommConn():215: backend: asio 2018-01-19T11:03:31.081186Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:connect():279: gcomm thread scheduling priority set to other:0 2018-01-19T11:03:31.081221Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: pc.linger not set. 2018-01-19T11:03:31.081375Z 0 [Warning] WSREP: gcomm/src/view.cpp:read_file():389: Fail to access the file (/var/lib/mysql//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-01-19T11:03:31.081390Z 0 [Note] WSREP: gcomm/src/pc.cpp:PC():256: Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown 2018-01-19T11:03:31.081522Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: socket.ssl not set. 2018-01-19T11:03:31.081555Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.group not set. 2018-01-19T11:03:31.081580Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.mcast_addr not set. 2018-01-19T11:03:31.081602Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.mcast_ttl not set. 2018-01-19T11:03:31.081626Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.time_wait not set. 2018-01-19T11:03:31.081759Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.peer_timeout not set. 2018-01-19T11:03:31.081809Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: gmcast.mira not set. 2018-01-19T11:03:31.081837Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:GMCast():98: GMCast version 0 2018-01-19T11:03:31.081873Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.081954Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.082024Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:set_initial_addr():276: (626ab09e, 'tcp://[::]:4567') initial addr: tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.082051Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.082103Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.082147Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:set_initial_addr():276: (626ab09e, 'tcp://[::]:4567') initial addr: tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.082170Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.082217Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.082260Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:set_initial_addr():276: (626ab09e, 'tcp://[::]:4567') initial addr: tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.082286Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[::]:4567 2018-01-19T11:03:31.082323Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[::]:4567 2018-01-19T11:03:31.082364Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[::]:4567 2018-01-19T11:03:31.082409Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:GMCast():191: (626ab09e, 'tcp://[::]:4567') listening at tcp://[::]:4567 2018-01-19T11:03:31.082423Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:GMCast():192: (626ab09e, 'tcp://[::]:4567') multicast: , ttl: 1 2018-01-19T11:03:31.082504Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.version not set. 2018-01-19T11:03:31.082532Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.debug_log_mask not set. 2018-01-19T11:03:31.082554Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.info_log_mask not set. 2018-01-19T11:03:31.082839Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.keepalive_period not set. 2018-01-19T11:03:31.082887Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.install_timeout not set. 2018-01-19T11:03:31.083069Z 0 [Note] [Debug] WSREP: galerautils/src/gu_config.hpp:get():125: evs.use_aggregate not set. 2018-01-19T11:03:31.083110Z 0 [Note] WSREP: gcomm/src/evs_proto.cpp:Proto():199: EVS version 0 2018-01-19T11:03:31.083185Z 0 [Note] [Debug] WSREP: gcomm/src/evs_input_map2.cpp:reset():150: size 0 2018-01-19T11:03:31.083202Z 0 [Note] [Debug] WSREP: gcomm/src/evs_input_map2.cpp:reset():156: node: {idx=0,range=[0,-1],safe_seq=-1} size 1 2018-01-19T11:03:31.083314Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:connect():309: gcomm: connecting to group 'pxc-cluster', peer '[fd00:faca:0:b0::164]:,[fd00:faca:0:b0::177]:,[fd00:faca:0:b0::192]:' 2018-01-19T11:03:31.083365Z 0 [Note] [Debug] WSREP: gcomm/src/protonet.cpp:insert():16: insert pstack 0x344ea28 2018-01-19T11:03:31.083382Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:connect():293: gmcast 626ab09e connect 2018-01-19T11:03:31.083393Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[::]:4567 2018-01-19T11:03:31.083544Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:AsioTcpSocket():27: ctor for 0x345c258 2018-01-19T11:03:31.083589Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:insert_address():777: (626ab09e, 'tcp://[::]:4567'): new address entry 00000000 tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.083612Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.083689Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:AsioTcpSocket():27: ctor for 0x3464a88 2018-01-19T11:03:31.083869Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: INIT -> HANDSHAKE_WAIT 2018-01-19T11:03:31.083895Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:insert_address():777: (626ab09e, 'tcp://[::]:4567'): new address entry 00000000 tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.083908Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.084007Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:AsioTcpSocket():27: ctor for 0x346d2d8 2018-01-19T11:03:31.084093Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: INIT -> HANDSHAKE_WAIT 2018-01-19T11:03:31.084112Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:insert_address():777: (626ab09e, 'tcp://[::]:4567'): new address entry 00000000 tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.084123Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.084261Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:AsioTcpSocket():27: ctor for 0x3475ab8 2018-01-19T11:03:31.084364Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: INIT -> HANDSHAKE_WAIT 2018-01-19T11:03:31.084427Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.hpp:connect():169: 626ab09e start_prim 0 2018-01-19T11:03:31.084452Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: CLOSED -> NON_PRIM prim 0 last prim view_id(NON_PRIM,00000000,0) to_seq -1 2018-01-19T11:03:31.084592Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:set_socket_options():574: socket recv buf size 212992 2018-01-19T11:03:31.084605Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:accept_handler():764: accepted socket 0x345c258 2018-01-19T11:03:31.084790Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: INIT -> HANDSHAKE_SENT 2018-01-19T11:03:31.084811Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:gmcast_accept():428: handshake sent 2018-01-19T11:03:31.084851Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:AsioTcpSocket():27: ctor for 0x347e6e8 2018-01-19T11:03:31.084902Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:set_socket_options():574: socket recv buf size 212992 2018-01-19T11:03:31.084915Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:connect_handler():139: socket 0x3464a88 connected, remote endpoint tcp://[fd00:faca:0:b0::164]:4567 local endpoint tcp://[fd00:faca:0:b0::192]:46782 2018-01-19T11:03:31.084927Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_connected():543: transport 0x34649e0 connected 2018-01-19T11:03:31.084986Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:set_socket_options():574: socket recv buf size 212992 2018-01-19T11:03:31.085004Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:connect_handler():139: socket 0x346d2d8 connected, remote endpoint tcp://[fd00:faca:0:b0::177]:4567 local endpoint tcp://[fd00:faca:0:b0::192]:54380 2018-01-19T11:03:31.085017Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_connected():543: transport 0x346d230 connected 2018-01-19T11:03:31.085062Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:set_socket_options():574: socket recv buf size 212992 2018-01-19T11:03:31.085074Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:connect_handler():139: socket 0x3475ab8 connected, remote endpoint tcp://[fd00:faca:0:b0::192]:4567 local endpoint tcp://[fd00:faca:0:b0::192]:55968 2018-01-19T11:03:31.085087Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_connected():543: transport 0x3475a10 connected 2018-01-19T11:03:31.085148Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_WAIT -> HANDSHAKE_RESPONSE_SENT 2018-01-19T11:03:31.085213Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_WAIT -> HANDSHAKE_RESPONSE_SENT 2018-01-19T11:03:31.085293Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_WAIT -> HANDSHAKE_RESPONSE_SENT 2018-01-19T11:03:31.085325Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[fd00:faca:0:b0::192]:55968 2018-01-19T11:03:31.085395Z 0 [Note] [Debug] WSREP: galerautils/src/gu_uri.cpp:parse():158: URI: tcp://[::]:4567 2018-01-19T11:03:31.085448Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_SENT -> OK 2018-01-19T11:03:31.085470Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:handle_established():555: (626ab09e, 'tcp://[::]:4567') connection established to 626ab09e tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:31.085491Z 0 [Warning] WSREP: gcomm/src/gmcast.cpp:handle_established():580: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' points to own listening address, blacklisting 2018-01-19T11:03:31.085506Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:close():220: closing 0x345c258 state 2 send_q size 1 2018-01-19T11:03:31.085522Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:31.085547Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x34649e0,ts=56379172418339 2018-01-19T11:03:31.085560Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x346d230,ts=56379172571678 2018-01-19T11:03:31.085573Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.085584Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:31.085595Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:31.085752Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_RESPONSE_SENT -> OK 2018-01-19T11:03:31.085774Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:handle_established():555: (626ab09e, 'tcp://[::]:4567') connection established to 6e3010ed tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.085786Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_established():602: Erasing tcp://[fd00:faca:0:b0::164]:4567 from panding list 2018-01-19T11:03:31.085811Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_established():608: Inserting tcp://[fd00:faca:0:b0::164]:4567 to remote list 2018-01-19T11:03:31.085827Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:insert_address():777: (626ab09e, 'tcp://[::]:4567'): new address entry 6e3010ed tcp://[fd00:faca:0:b0::164]:4567 2018-01-19T11:03:31.085857Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:31.085873Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379173032107 2018-01-19T11:03:31.085887Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x346d230,ts=56379172571678 2018-01-19T11:03:31.085899Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.085920Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:31.085932Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:31.085971Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:31.086000Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:31.086014Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379173245394 2018-01-19T11:03:31.086028Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x346d230,ts=56379172571678 2018-01-19T11:03:31.086041Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.086052Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:31.086063Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:31.086078Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.99976S 2018-01-19T11:03:31.086090Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:check_liveness():1147: (626ab09e, 'tcp://[::]:4567') turning message relay requesting on, nonlive peers: 2018-01-19T11:03:31.086181Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_RESPONSE_SENT -> OK 2018-01-19T11:03:31.086197Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:handle_established():555: (626ab09e, 'tcp://[::]:4567') connection established to 82217999 tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.086208Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_established():602: Erasing tcp://[fd00:faca:0:b0::177]:4567 from panding list 2018-01-19T11:03:31.086219Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_established():608: Inserting tcp://[fd00:faca:0:b0::177]:4567 to remote list 2018-01-19T11:03:31.086231Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:insert_address():777: (626ab09e, 'tcp://[::]:4567'): new address entry 82217999 tcp://[fd00:faca:0:b0::177]:4567 2018-01-19T11:03:31.086284Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:31.086299Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:31.086313Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379173245394 2018-01-19T11:03:31.086327Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56379173462169 2018-01-19T11:03:31.086343Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.086362Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:31.086374Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:31.086405Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:31.086418Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:31.086430Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:31.086459Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379173245394 2018-01-19T11:03:31.086476Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56379173676871 2018-01-19T11:03:31.086490Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.086501Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:31.086512Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:31.086528Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.99932S 2018-01-19T11:03:31.086540Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.99972S 2018-01-19T11:03:31.584620Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.50126S 2018-01-19T11:03:31.584670Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.50167S 2018-01-19T11:03:31.585578Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3500: evs::proto(626ab09e, GATHER, view_id(TRANS,626ab09e,0)) commit gap from 626ab09e 2018-01-19T11:03:31.586016Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3500: evs::proto(626ab09e, GATHER, view_id(TRANS,626ab09e,0)) commit gap from 6e3010ed 2018-01-19T11:03:31.586187Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3500: evs::proto(626ab09e, GATHER, view_id(TRANS,626ab09e,0)) commit gap from 82217999 2018-01-19T11:03:31.586221Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3534: evs::proto(626ab09e, INSTALL, view_id(TRANS,626ab09e,0)): install gap {v=0,t=3,ut=255,o=1,s=-1,sr=-1,as=-1,f=0,src=626ab09e,srcvid=view_id(REG,626ab09e,9),insvid=view_id(UNKNOWN,00000000,0),ru=00000000,r=[-1,-1],fs=6,nl=( ) } 2018-01-19T11:03:31.586262Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3534: evs::proto(626ab09e, INSTALL, view_id(TRANS,626ab09e,0)): install gap {v=0,t=3,ut=255,o=1,s=-1,sr=-1,as=-1,f=4,src=82217999,srcvid=view_id(REG,626ab09e,9),insvid=view_id(UNKNOWN,00000000,0),ru=00000000,r=[-1,-1],fs=170085,nl=( ) } 2018-01-19T11:03:31.586332Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.cpp:handle_gap():3534: evs::proto(626ab09e, INSTALL, view_id(TRANS,626ab09e,0)): install gap {v=0,t=3,ut=255,o=1,s=-1,sr=-1,as=-1,f=4,src=6e3010ed,srcvid=view_id(REG,626ab09e,9),insvid=view_id(UNKNOWN,00000000,0),ru=00000000,r=[-1,-1],fs=170137,nl=( ) } 2018-01-19T11:03:31.586389Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_view():617: 626ab09e Current view of cluster as seen by this node view (view_id(TRANS,626ab09e,0) memb { 626ab09e,0 } joined { } left { } partitioned { } ) 2018-01-19T11:03:31.586410Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: NON_PRIM -> TRANS prim 0 last prim view_id(NON_PRIM,00000000,0) to_seq -1 2018-01-19T11:03:31.586437Z 0 [Note] [Debug] WSREP: gcomm/src/evs_input_map2.cpp:reset():150: size 0 2018-01-19T11:03:31.586452Z 0 [Note] [Debug] WSREP: gcomm/src/evs_input_map2.cpp:reset():156: node: {idx=0,range=[0,-1],safe_seq=-1} node: {idx=1,range=[0,-1],safe_seq=-1} node: {idx=2,range=[0,-1],safe_seq=-1} size 3 2018-01-19T11:03:31.586475Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1512: GMCast::handle_stable_view: Current view of cluster as seen by this node view (view_id(REG,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { 6e3010ed,0 82217999,0 } left { } partitioned { } ) 2018-01-19T11:03:31.586487Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1597: declaring 6e3010ed at tcp://[fd00:faca:0:b0::164]:4567 stable 2018-01-19T11:03:31.586497Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1597: declaring 82217999 at tcp://[fd00:faca:0:b0::177]:4567 stable 2018-01-19T11:03:31.586513Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.49933S 2018-01-19T11:03:31.586525Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.49973S 2018-01-19T11:03:31.586543Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379673604478 2018-01-19T11:03:31.586556Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56379673535879 2018-01-19T11:03:31.586569Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:31.586585Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_view():617: 626ab09e Current view of cluster as seen by this node view (view_id(REG,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { 6e3010ed,0 82217999,0 } left { } partitioned { } ) 2018-01-19T11:03:31.586602Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: TRANS -> STATES_EXCH prim 0 last prim view_id(NON_PRIM,00000000,0) to_seq -1 2018-01-19T11:03:31.586616Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:send_state():124: 626ab09e sending state 2018-01-19T11:03:31.586632Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:send_state():145: 626ab09e local to seq -1 2018-01-19T11:03:31.586656Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:send_state():146: 626ab09e sending state: pcmsg{ type=STATE, seq=0, flags= 0, node_map { 626ab09e,prim=0,un=0,last_seq=4294967295,last_prim=view_id(NON_PRIM,00000000,0),to_seq=-1,weight=1,segment=0 }} 2018-01-19T11:03:31.587192Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_state():957: 626ab09e handle state from 626ab09e pcmsg{ type=STATE, seq=0, flags= 0, node_map { 626ab09e,prim=0,un=0,last_seq=4294967295,last_prim=view_id(NON_PRIM,00000000,0),to_seq=-1,weight=1,segment=0 }} 2018-01-19T11:03:31.587227Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_state():957: 626ab09e handle state from 6e3010ed pcmsg{ type=STATE, seq=0, flags= 0, node_map { 6e3010ed,prim=1,un=0,last_seq=2,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 82217999,prim=1,un=0,last_seq=1,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 }} 2018-01-19T11:03:31.587259Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_state():957: 626ab09e handle state from 82217999 pcmsg{ type=STATE, seq=0, flags= 0, node_map { 6e3010ed,prim=1,un=0,last_seq=2,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 82217999,prim=1,un=0,last_seq=1,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 }} 2018-01-19T11:03:31.587282Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:validate_state_msgs():711: 626ab09e joining to view_id(PRIM,6e3010ed,8) 2018-01-19T11:03:31.587294Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:validate_state_msgs():711: 626ab09e joining to view_id(PRIM,6e3010ed,8) 2018-01-19T11:03:31.587305Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:validate_state_msgs():711: 626ab09e joining to view_id(PRIM,6e3010ed,8) 2018-01-19T11:03:31.587315Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:validate_state_msgs():711: 626ab09e joining to view_id(PRIM,6e3010ed,8) 2018-01-19T11:03:31.587329Z 0 [Note] WSREP: gcomm/src/pc_proto.cpp:is_prim():796: Node 6e3010ed state primary 2018-01-19T11:03:31.587342Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:is_prim():829: Non-prim 626ab09e from view_id(NON_PRIM,00000000,0) joining prim 2018-01-19T11:03:31.587355Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: STATES_EXCH -> INSTALL prim 0 last prim view_id(NON_PRIM,00000000,0) to_seq -1 2018-01-19T11:03:31.587366Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:send_install():161: 626ab09e send install 2018-01-19T11:03:31.587388Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:send_install():195: 626ab09e sending install: pcmsg{ type=INSTALL, seq=0, flags= 0, node_map { 626ab09e,prim=0,un=0,last_seq=4294967295,last_prim=view_id(NON_PRIM,00000000,0),to_seq=-1,weight=1,segment=0 6e3010ed,prim=1,un=0,last_seq=2,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 82217999,prim=1,un=0,last_seq=1,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 }} 2018-01-19T11:03:31.588039Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_install():1103: 626ab09e handle install from 626ab09e pcmsg{ type=INSTALL, seq=0, flags= 0, node_map { 626ab09e,prim=0,un=0,last_seq=4294967295,last_prim=view_id(NON_PRIM,00000000,0),to_seq=-1,weight=1,segment=0 6e3010ed,prim=1,un=0,last_seq=2,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 82217999,prim=1,un=0,last_seq=1,last_prim=view_id(PRIM,6e3010ed,8),to_seq=36,weight=1,segment=0 }} 2018-01-19T11:03:31.588061Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_install():1188: 626ab09e setting TO seq to 36 2018-01-19T11:03:31.588076Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: INSTALL -> PRIM prim 1 last prim view_id(PRIM,626ab09e,9) to_seq 36 2018-01-19T11:03:31.588095Z 0 [Note] WSREP: gcomm/src/pc_proto.cpp:deliver_view():229: Current view of cluster as seen by this node view (view_id(PRIM,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { } left { } partitioned { } ) 2018-01-19T11:03:31.588112Z 0 [Note] WSREP: gcomm/src/pc.cpp:handle_up():30: Save the discovered primary-component to disk 2018-01-19T11:03:31.588317Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1512: GMCast::handle_stable_view: Current view of cluster as seen by this node view (view_id(PRIM,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { } left { } partitioned { } ) 2018-01-19T11:03:31.588343Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.4975S 2018-01-19T11:03:31.588356Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.4979S 2018-01-19T11:03:31.588371Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56379675190279 2018-01-19T11:03:31.588392Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56379675290977 2018-01-19T11:03:31.588406Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=HANDSHAKE_RESPONSE_SENT,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:32.084734Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.00117S 2018-01-19T11:03:32.084789Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT2.00157S 2018-01-19T11:03:32.084974Z 0 [Note] [Debug] WSREP: gcomm/src/pc.cpp:connect():131: PC/EVS Proto initial state: evs::proto(evs::proto(626ab09e, OPERATIONAL, view_id(REG,626ab09e,9)), OPERATIONAL) { current_view=Current view of cluster as seen by this node view (view_id(REG,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { } left { } partitioned { } ), input_map=evs::input_map: {aru_seq=1,safe_seq=1,node_index=node: {idx=0,range=[2,1],safe_seq=1} node: {idx=1,range=[2,1],safe_seq=1} node: {idx=2,range=[2,1],safe_seq=1} }, fifo_seq=11, last_sent=1, known: 626ab09e at {o=1,s=0,i=1,fs=-1,} 6e3010ed at tcp://[fd00:faca:0:b0::164]:4567 {o=1,s=0,i=1,fs=170142,} 82217999 at tcp://[fd00:faca:0:b0::177]:4567 {o=1,s=0,i=1,fs=170090,} } 2018-01-19T11:03:32.085015Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:connect():319: gcomm: connected 2018-01-19T11:03:32.085051Z 0 [Note] [Debug] WSREP: gcs/src/gcs_core.cpp:gcs_core_set_pkt_size():1253: Changing maximum packet size to 64500, resulting msg size: 32636 2018-01-19T11:03:32.085072Z 0 [Note] [Debug] WSREP: gcs/src/gcs_core.cpp:gcs_core_set_pkt_size():1268: Message payload (action fragment size): 32616 2018-01-19T11:03:32.085146Z 0 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():649: Shifting CLOSED -> OPEN (TO: 0) 2018-01-19T11:03:32.085156Z 0 [Note] [Debug] WSREP: gcs/src/gcs.cpp:gcs_open():1500: Opened channel 'pxc-cluster' 2018-01-19T11:03:32.085290Z 0 [Note] WSREP: Waiting for SST/IST to complete. 2018-01-19T11:03:32.085752Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:fill_cmp_msg():684: member: 0 uuid: 626ab09e segment: 0 2018-01-19T11:03:32.085785Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:fill_cmp_msg():698: my index 0 2018-01-19T11:03:32.085797Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:fill_cmp_msg():684: member: 1 uuid: 6e3010ed segment: 0 2018-01-19T11:03:32.085810Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:fill_cmp_msg():684: member: 2 uuid: 82217999 segment: 0 2018-01-19T11:03:32.085829Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_comp_msg():410: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 3 2018-01-19T11:03:32.085961Z 0 [Note] WSREP: gcs/src/gcs_core.cpp:core_handle_comp_msg():767: STATE_EXCHANGE: sent state UUID: 6303f372-fd08-11e7-855e-5b19cc198634 2018-01-19T11:03:32.086962Z 0 [Note] WSREP: gcs/src/gcs_core.cpp:core_handle_uuid_msg():861: STATE EXCHANGE: sent state msg: 6303f372-fd08-11e7-855e-5b19cc198634 2018-01-19T11:03:32.087466Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_state_msg():592: STATE EXCHANGE: got state msg: 6303f372-fd08-11e7-855e-5b19cc198634 from 0 (alfresco2) 2018-01-19T11:03:32.087488Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:group_print_state_debug():574: Version : 4 Flags : 0x1 Protocols : 0 / 7 / 3 State : NON-PRIMARY Desync count : 0 Prim state : NON-PRIMARY Prim UUID : 00000000-0000-0000-0000-000000000000 Prim seqno : -1 First seqno : -1 Last seqno : -1 Prim JOINED : 0 State UUID : 6303f372-fd08-11e7-855e-5b19cc198634 Group UUID : 00000000-0000-0000-0000-000000000000 Name : 'alfresco2' Incoming addr: 'fd00:faca:0:b0::192:3306' 2018-01-19T11:03:32.087501Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_state_msg():592: STATE EXCHANGE: got state msg: 6303f372-fd08-11e7-855e-5b19cc198634 from 1 (divisa) 2018-01-19T11:03:32.087529Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:group_print_state_debug():574: Version : 4 Flags : 0x2 Protocols : 0 / 7 / 3 State : SYNCED Desync count : 0 Prim state : SYNCED Prim UUID : 4f7e7744-fd07-11e7-9011-ce7c33d2e8a2 Prim seqno : 8 First seqno : -1 Last seqno : 11336 Prim JOINED : 2 State UUID : 6303f372-fd08-11e7-855e-5b19cc198634 Group UUID : 5067efe8-f7b8-11e7-99f1-a656309a571f Name : 'divisa' Incoming addr: 'fd00:faca:0:b0::164:3306' 2018-01-19T11:03:32.087542Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_state_msg():592: STATE EXCHANGE: got state msg: 6303f372-fd08-11e7-855e-5b19cc198634 from 2 (sinos) 2018-01-19T11:03:32.087555Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:group_print_state_debug():574: Version : 4 Flags : 0x2 Protocols : 0 / 7 / 3 State : SYNCED Desync count : 0 Prim state : SYNCED Prim UUID : 4f7e7744-fd07-11e7-9011-ce7c33d2e8a2 Prim seqno : 8 First seqno : -1 Last seqno : 11336 Prim JOINED : 2 State UUID : 6303f372-fd08-11e7-855e-5b19cc198634 Group UUID : 5067efe8-f7b8-11e7-99f1-a656309a571f Name : 'sinos' Incoming addr: 'fd00:faca:0:b0::177:3306' 2018-01-19T11:03:32.087564Z 0 [Note] [Debug] WSREP: gcs/src/gcs_group.cpp:group_post_state_exchange():288: STATE EXCHANGE: 6303f372-fd08-11e7-855e-5b19cc198634 complete. 2018-01-19T11:03:32.087573Z 0 [Note] [Debug] WSREP: gcs/src/gcs_node.cpp:gcs_node_update_status():150: #281,#298 Carry over last prim state for divisa: SYNCED 2018-01-19T11:03:32.087580Z 0 [Note] [Debug] WSREP: gcs/src/gcs_node.cpp:gcs_node_update_status():150: #281,#298 Carry over last prim state for sinos: SYNCED 2018-01-19T11:03:32.087589Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:group_post_state_exchange():369: Quorum results: version = 4, component = PRIMARY, conf_id = 8, members = 2/3 (primary/total), act_id = 11336, last_appl. = -1, protocols = 0/7/3 (gcs/repl/appl), group UUID = 5067efe8-f7b8-11e7-99f1-a656309a571f 2018-01-19T11:03:32.087600Z 0 [Note] WSREP: gcs/src/gcs.cpp:_set_fc_limits():856: Flow-control interval: [173, 173] 2018-01-19T11:03:32.087615Z 0 [Note] WSREP: gcs/src/gcs_sm.hpp:gcs_sm_continue():452: Trying to continue unpaused monitor 2018-01-19T11:03:32.087623Z 0 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():649: Shifting OPEN -> PRIMARY (TO: 11336) 2018-01-19T11:03:32.087688Z 2 [Note] [Debug] WSREP: galera/src/monitor.hpp:drain_common():420: draining up to -1 2018-01-19T11:03:32.087710Z 2 [Note] [Debug] WSREP: galera/src/monitor.hpp:drain_common():420: draining up to -1 2018-01-19T11:03:32.087754Z 2 [Note] WSREP: galera/src/replicator_smm.cpp:process_conf_change():1546: State transfer required: Group state: 5067efe8-f7b8-11e7-99f1-a656309a571f:11336 Local state: 00000000-0000-0000-0000-000000000000:-1 2018-01-19T11:03:32.087771Z 2 [Note] WSREP: New cluster view: global state: 5067efe8-f7b8-11e7-99f1-a656309a571f:11336, view# 9: Primary, number of nodes: 3, my index: 0, protocol version 3 2018-01-19T11:03:32.087778Z 2 [Note] WSREP: Setting wsrep_ready to true 2018-01-19T11:03:32.087789Z 2 [Warning] WSREP: Gap in state sequence. Need state transfer. 2018-01-19T11:03:32.087795Z 2 [Note] WSREP: Setting wsrep_ready to false 2018-01-19T11:03:32.087884Z 0 [Note] WSREP: Initiating SST/IST transfer on JOINER side (wsrep_sst_xtrabackup-v2 --role 'joiner' --address '[fd00:faca:0:b0::192]' --datadir '/var/lib/mysql/' --defaults-file '/etc/my.cnf' --defaults-group-suffix '' --parent '29406' '' ) 2018-01-19T11:03:32.217365Z WSREP_SST: [DEBUG] The xtrabackup version is 2.4.9 2018-01-19T11:03:32.587359Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT1.49851S 2018-01-19T11:03:32.587405Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT1.49892S 2018-01-19T11:03:32.765789Z WSREP_SST: [DEBUG] Streaming with xbstream 2018-01-19T11:03:32.767314Z WSREP_SST: [DEBUG] Using socat as streamer 2018-01-19T11:03:32.859760Z WSREP_SST: [DEBUG] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr,retry=30 stdio | xbstream $xbstreameopts -x; RC=( ${PIPESTATUS[@]} ) 2018-01-19T11:03:33.028151Z 2 [Note] WSREP: Prepared SST/IST request: xtrabackup-v2|[fd00:faca:0:b0::192]:4444/xtrabackup_sst//1 2018-01-19T11:03:33.028203Z 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2018-01-19T11:03:33.028245Z 2 [Note] WSREP: galera/src/replicator_smm.cpp:establish_protocol_versions():1456: REPL Protocols: 7 (3, 2) 2018-01-19T11:03:33.028263Z 2 [Note] WSREP: galera/src/certification.cpp:assign_initial_position():913: Assign initial position for certification: 11336, protocol version: 3 2018-01-19T11:03:33.028311Z 0 [Note] WSREP: galera/src/galera_service_thd.cpp:thd_func():46: Service thread queue flushed. 2018-01-19T11:03:33.028344Z 2 [Note] WSREP: galera/src/replicator_str.cpp:prepare_state_request():598: Check if state gap can be serviced using IST 2018-01-19T11:03:33.028365Z 2 [Note] WSREP: galera/src/replicator_str.cpp:prepare_for_IST():535: Local UUID: 00000000-0000-0000-0000-000000000000 != Group UUID: 5067efe8-f7b8-11e7-99f1-a656309a571f 2018-01-19T11:03:33.028435Z 2 [Note] WSREP: galera/src/replicator_str.cpp:prepare_state_request():604: State gap can't be serviced using IST. Switching to SST 2018-01-19T11:03:33.028448Z 2 [Note] WSREP: galera/src/replicator_str.cpp:prepare_state_request():606: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (5067efe8-f7b8-11e7-99f1-a656309a571f): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():538. IST will be unavailable. 2018-01-19T11:03:33.028472Z 2 [Note] [Debug] WSREP: gcs/src/gcs.cpp:gcs_request_state_transfer():1816: ist_uuid[00000000-0000-0000-0000-000000000000], ist_seqno[-1] 2018-01-19T11:03:33.029411Z 0 [Note] WSREP: gcs/src/gcs_group.cpp:group_select_donor():1312: Member 0.0 (alfresco2) requested state transfer from '*any*'. Selected 1.0 (divisa)(SYNCED) as donor. 2018-01-19T11:03:33.029441Z 0 [Note] WSREP: gcs/src/gcs.cpp:gcs_shift_state():649: Shifting PRIMARY -> JOINER (TO: 11336) 2018-01-19T11:03:33.029490Z 2 [Note] WSREP: galera/src/replicator_str.cpp:send_state_request():731: Requesting state transfer: success, donor: 1 2018-01-19T11:03:33.029514Z 2 [Note] WSREP: gcache/src/GCache_seqno.cpp:seqno_reset():28: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 5067efe8-f7b8-11e7-99f1-a656309a571f:11336 2018-01-19T11:03:33.087478Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT0.998402S 2018-01-19T11:03:33.087525Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT0.998806S 2018-01-19T11:03:33.587662Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT0.498247S 2018-01-19T11:03:33.587744Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:check_liveness():1137: continuing relaying for PT0.49865S 2018-01-19T11:03:34.087693Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:check_liveness():1088: (626ab09e, 'tcp://[::]:4567') connection to peer 626ab09e with addr tcp://[fd00:faca:0:b0::192]:4567 timed out, no messages seen in PT3S 2018-01-19T11:03:34.087779Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast_proto.cpp:set_state():39: State change: HANDSHAKE_RESPONSE_SENT -> FAILED 2018-01-19T11:03:34.087804Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_failed():701: handle failed: v=0,lu=626ab09e,ru=626ab09e,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::192]:4567,mc=,gn=pxc-cluster,ch=0,st=FAILED,pr=0,tp=0x3475a10,ts=56379172492028 2018-01-19T11:03:34.087826Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:close():220: closing 0x3475ab8 state 2 send_q size 0 2018-01-19T11:03:34.087959Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:34.088004Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():862: (626ab09e, 'tcp://[::]:4567') address 'tcp://[fd00:faca:0:b0::192]:4567' pointing to uuid 626ab09e is blacklisted, skipping 2018-01-19T11:03:34.088051Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():903: (626ab09e, 'tcp://[::]:4567') --- mcast tree begin --- 2018-01-19T11:03:34.088073Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56381675684873 2018-01-19T11:03:34.088089Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():920: Proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56381675636174 2018-01-19T11:03:34.088103Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():944: (626ab09e, 'tcp://[::]:4567') self index: 0 2018-01-19T11:03:34.088125Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:update_addresses():945: (626ab09e, 'tcp://[::]:4567') --- mcast tree end --- 2018-01-19T11:03:34.088148Z 0 [Note] WSREP: gcomm/src/gmcast.cpp:check_liveness():1188: (626ab09e, 'tcp://[::]:4567') turning message relay requesting off 2018-01-19T11:03:34.088201Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():44: failed handler from read_completion_condition:486 socket 0x3475ab8 -1 error asio.system:125 0 state 0 2018-01-19T11:03:34.088214Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():51: local endpoint tcp://[fd00:faca:0:b0::192]:55968 remote endpoint tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:34.088244Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():44: failed handler from read_handler:393 socket 0x3475ab8 -1 error asio.system:125 0 state 0 2018-01-19T11:03:34.088258Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():51: local endpoint tcp://[fd00:faca:0:b0::192]:55968 remote endpoint tcp://[fd00:faca:0:b0::192]:4567 2018-01-19T11:03:34.088274Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:~AsioTcpSocket():32: dtor for 0x3475ab8 2018-01-19T11:03:34.088379Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():44: failed handler from read_completion_condition:486 socket 0x345c258 15 error asio.misc:2 1 state 4 2018-01-19T11:03:34.088395Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():51: local endpoint tcp://[fd00:faca:0:b0::192]:4567 remote endpoint tcp://[fd00:faca:0:b0::192]:55968 2018-01-19T11:03:34.088411Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():44: failed handler from read_handler:393 socket 0x345c258 15 error asio.misc:2 1 state 3 2018-01-19T11:03:34.088425Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:failed_handler():51: local endpoint tcp://[fd00:faca:0:b0::192]:4567 remote endpoint tcp://[fd00:faca:0:b0::192]:55968 2018-01-19T11:04:03.747444Z 0 [Warning] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_join_msg():740: 1.0 (divisa): State transfer to 0.0 (alfresco2) failed: -32 (Broken pipe) 2018-01-19T11:04:03.747494Z 0 [ERROR] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_join_msg():765: Will never receive state. Need to abort. 2018-01-19T11:04:03.747521Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:close():331: gcomm: terminating thread 2018-01-19T11:04:03.747539Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:close():334: gcomm: joining thread 2018-01-19T11:04:03.747785Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:close():344: gcomm: closing backend 2018-01-19T11:04:03.747807Z 0 [Note] [Debug] WSREP: gcomm/src/pc.cpp:close():186: PC/EVS Proto leaving 2018-01-19T11:04:03.747836Z 0 [Note] [Debug] WSREP: gcomm/src/evs_proto.hpp:close():290: evs::proto(626ab09e, OPERATIONAL, view_id(REG,626ab09e,9)) closing in state 5 2018-01-19T11:04:03.748394Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_view():617: 626ab09e Current view of cluster as seen by this node view (view_id(TRANS,626ab09e,9) memb { 626ab09e,0 } joined { } left { } partitioned { 6e3010ed,0 82217999,0 } ) 2018-01-19T11:04:03.748471Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_trans():519: 626ab09e current view Current view of cluster as seen by this node view (view_id(REG,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { 6e3010ed,0 82217999,0 } left { } partitioned { } ) next view Current view of cluster as seen by this node view (view_id(TRANS,626ab09e,9) memb { 626ab09e,0 } joined { } left { } partitioned { 6e3010ed,0 82217999,0 } ) pc view Current view of cluster as seen by this node view (view_id(PRIM,626ab09e,9) memb { 626ab09e,0 6e3010ed,0 82217999,0 } joined { } left { } partitioned { } ) 2018-01-19T11:04:03.748512Z 0 [Note] WSREP: gcomm/src/pc_proto.cpp:deliver_view():229: Current view of cluster as seen by this node view (view_id(NON_PRIM,626ab09e,9) memb { 626ab09e,0 } joined { } left { } partitioned { 6e3010ed,0 82217999,0 } ) 2018-01-19T11:04:03.748545Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1512: GMCast::handle_stable_view: Current view of cluster as seen by this node view (view_id(NON_PRIM,626ab09e,9) memb { 626ab09e,0 } joined { } left { } partitioned { 6e3010ed,0 82217999,0 } ) 2018-01-19T11:04:03.748567Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56411835638675 2018-01-19T11:04:03.748581Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56411835434011 2018-01-19T11:04:03.748594Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: PRIM -> TRANS prim 0 last prim view_id(PRIM,626ab09e,9) to_seq 42 2018-01-19T11:04:03.748615Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:handle_view():617: 626ab09e Current view of cluster as seen by this node view ((empty)) 2018-01-19T11:04:03.748630Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: TRANS -> NON_PRIM prim 0 last prim view_id(PRIM,626ab09e,9) to_seq 42 2018-01-19T11:04:03.748641Z 0 [Note] WSREP: gcomm/src/pc_proto.cpp:deliver_view():229: Current view of cluster as seen by this node view ((empty)) 2018-01-19T11:04:03.748655Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:handle_up():485: handle_up: self leave 2018-01-19T11:04:03.748666Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1512: GMCast::handle_stable_view: Current view of cluster as seen by this node view ((empty)) 2018-01-19T11:04:03.748681Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=6e3010ed,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::164]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x34649e0,ts=56411835638675 2018-01-19T11:04:03.748694Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:handle_stable_view():1617: proto: v=0,lu=626ab09e,ru=82217999,ls=0,rs=0,la=tcp://[::]:4567,ra=tcp://[fd00:faca:0:b0::177]:4567,mc=,gn=pxc-cluster,ch=0,st=OK,pr=1,tp=0x346d230,ts=56411835434011 2018-01-19T11:04:03.748706Z 0 [Note] [Debug] WSREP: gcomm/src/pc_proto.cpp:shift_to():388: 626ab09e shift_to: NON_PRIM -> CLOSED prim 0 last prim view_id(PRIM,626ab09e,9) to_seq 42 2018-01-19T11:04:03.748768Z 0 [Note] [Debug] WSREP: gcomm/src/gmcast.cpp:close():340: gmcast 626ab09e close 2018-01-19T11:04:03.748834Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:~AsioTcpSocket():32: dtor for 0x345c258 2018-01-19T11:04:03.748898Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:close():220: closing 0x3464a88 state 2 send_q size 0 2018-01-19T11:04:03.748942Z 0 [Note] [Debug] WSREP: gcomm/src/asio_tcp.cpp:close():220: closing 0x346d2d8 state 2 send_q size 0 2018-01-19T11:04:03.748978Z 0 [Note] [Debug] WSREP: gcomm/src/protonet.cpp:erase():26: erase pstack 0x344ea28 2018-01-19T11:04:03.749100Z 0 [Note] [Debug] WSREP: gcomm/src/evs_input_map2.cpp:clear():224: discarding 4 messages from recovery index 2018-01-19T11:04:03.749133Z 0 [Note] WSREP: gcs/src/gcs_gcomm.cpp:close():357: gcomm: closed 2018-01-19T11:04:03.749168Z 0 [Note] [Debug] WSREP: gcs/src/gcs_gcomm.cpp:close():358: profile name: gcs_gcomm point count calendar cpu ---------------------------------------------------------------------- tot count : 0 tot calendar time : 0.000 tot thread cputime: 0.000 tot ct since ctor : -1516303399.245 2018-01-19T11:04:03.749184Z 0 [Note] WSREP: galerautils/src/gu_abort.c:gu_abort():41: /usr/sbin/mysqld: Terminated. Terminado 2018-01-19T11:04:13.509043Z WSREP_SST: [ERROR] Removing /var/lib/mysql//xtrabackup_galera_info file due to signal 2018-01-19T11:04:13.512123Z WSREP_SST: [ERROR] Removing file due to signal 2018-01-19T11:04:13.515163Z WSREP_SST: [ERROR] ******************* FATAL ERROR ********************** 2018-01-19T11:04:13.516650Z WSREP_SST: [ERROR] Error while getting data from donor node: exit codes: 143 143 2018-01-19T11:04:13.518173Z WSREP_SST: [ERROR] ****************************************************** 2018-01-19T11:04:13.519973Z WSREP_SST: [ERROR] Cleanup after exit with status:32