2018-11-02 10:13:08 0 [Note] WSREP: Read nil XID from storage engines, skipping position init 2018-11-02 10:13:08 0 [Note] WSREP: wsrep_load(): loading provider library '/maria/app/lib/galera/libgalera_smm.so' 2018-11-02 10:13:08 0 [Note] WSREP: wsrep_load(): Galera 25.3.24(r3825) by Codership Oy <info@codership.com> loaded successfully. 2018-11-02 10:13:08 0 [Note] WSREP: CRC-32C: using hardware acceleration. 2018-11-02 10:13:08 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1 2018-11-02 10:13:08 0 [Note] WSREP: Passing config to GCS: base_dir = /dbdata/; base_host = 192.168.1.141; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /dbdata/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /dbdata//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 128M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1.0; gcs.fc_limit = 16; 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 2018-11-02 10:13:08 0 [Note] WSREP: GCache history reset: 07112bf5-dce0-11e8-bd0f-fe56e4bea91e:0 -> 00000000-0000-0000-0000-000000000000:-1 2018-11-02 10:13:08 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2018-11-02 10:13:08 0 [Note] WSREP: wsrep_sst_grab() 2018-11-02 10:13:08 0 [Note] WSREP: Start replication 2018-11-02 10:13:08 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2018-11-02 10:13:08 0 [Note] WSREP: protonet asio version 0 2018-11-02 10:13:08 0 [Note] WSREP: Using CRC-32C for message checksums. 2018-11-02 10:13:08 0 [Note] WSREP: backend: asio 2018-11-02 10:13:08 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 2018-11-02 10:13:08 0 [Warning] WSREP: access file(/dbdata//gvwstate.dat) failed(No such file or directory) 2018-11-02 10:13:08 0 [Note] WSREP: restore pc from disk failed 2018-11-02 10:13:08 0 [Note] WSREP: GMCast version 0 2018-11-02 10:13:08 0 [Note] WSREP: (75a27987, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2018-11-02 10:13:08 0 [Note] WSREP: (75a27987, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2018-11-02 10:13:08 0 [Note] WSREP: EVS version 0 2018-11-02 10:13:08 0 [Note] WSREP: gcomm: connecting to group 'cfpc_galera', peer '192.168.1.140:4567' 2018-11-02 10:13:08 0 [Note] WSREP: (75a27987, 'tcp://0.0.0.0:4567') connection established to 9dcdbc95 tcp://192.168.1.140:4567 2018-11-02 10:13:08 0 [Note] WSREP: (75a27987, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2018-11-02 10:13:09 0 [Note] WSREP: declaring 9dcdbc95 at tcp://192.168.1.140:4567 stable 2018-11-02 10:13:09 0 [Note] WSREP: Node 9dcdbc95 state prim
2018-11-02 10:13:09 0 [Note] WSREP: view(view_id(PRIM,75a27987,4) memb { 75a27987,0 9dcdbc95,0 } joined { } left { } partitioned { }) 2018-11-02 10:13:09 0 [Note] WSREP: save pc into disk 2018-11-02 10:13:09 0 [Note] WSREP: gcomm: connected 2018-11-02 10:13:09 0 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2018-11-02 10:13:09 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2018-11-02 10:13:09 0 [Note] WSREP: Opened channel 'cfpc_galera' 2018-11-02 10:13:09 0 [Note] WSREP: Waiting for SST to complete. 2018-11-02 10:13:09 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2 2018-11-02 10:13:09 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 763ba9e2-de3c-11e8-9ed7-8a60fe20a71d 2018-11-02 10:13:09 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 763ba9e2-de3c-11e8-9ed7-8a60fe20a71d 2018-11-02 10:13:09 0 [Note] WSREP: STATE EXCHANGE: got state msg: 763ba9e2-de3c-11e8-9ed7-8a60fe20a71d from 0 (galera2) 2018-11-02 10:13:09 0 [Note] WSREP: STATE EXCHANGE: got state msg: 763ba9e2-de3c-11e8-9ed7-8a60fe20a71d from 1 (galera1) 2018-11-02 10:13:09 0 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 3, members = 1/2 (joined/total), act_id = 4, last_appl. = -1, protocols = 0/9/3 (gcs/repl/appl), group UUID = 07112bf5-dce0-11e8-bd0f-fe56e4bea91e 2018-11-02 10:13:09 0 [Note] WSREP: Flow-control interval: [23, 23] 2018-11-02 10:13:09 0 [Note] WSREP: Trying to continue unpaused monitor 2018-11-02 10:13:09 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 4) 2018-11-02 10:13:09 2 [Note] WSREP: State transfer required: Group state: 07112bf5-dce0-11e8-bd0f-fe56e4bea91e:4 Local state: 00000000-0000-0000-0000-000000000000:-1 2018-11-02 10:13:09 2 [Note] WSREP: New cluster view: global state: 07112bf5-dce0-11e8-bd0f-fe56e4bea91e:4, view# 4: Primary, number of nodes: 2, my index: 0, protocol version 3 2018-11-02 10:13:09 2 [Warning] WSREP: Gap in state sequence. Need state transfer. 2018-11-02 10:13:09 0 [Note] WSREP: Running: 'wsrep_sst_mariabackup --role 'joiner' --address '192.168.1.141:4444' --datadir '/dbdata/' --parent '2998' --binlog '/maria_log/binary/maria-bin' ''' WSREP_SST: [INFO] Streaming with xbstream (20181102 10:13:10.481) WSREP_SST: [INFO] Using socat as streamer (20181102 10:13:10.486) WSREP_SST: [INFO] Stale sst_in_progress file: /dbdata//sst_in_progress (20181102 10:13:10.494) WSREP_SST: [INFO] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr stdio | mbstream -x; RC=( ${PIPESTATUS[@]} ) (20181102 10:13:10.564) 2018-11-02 10:13:10 2 [Note] WSREP: Prepared SST request: mariabackup|192.168.1.141:4444/xtrabackup_sst//1 2018-11-02 10:13:10 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2018-11-02 10:13:10 2 [Note] WSREP: REPL Protocols: 9 (4, 2) 2018-11-02 10:13:10 2 [Note] WSREP: Assign initial position for certification: 4, protocol version: 4 2018-11-02 10:13:10 0 [Note] WSREP: Service thread queue flushed.
2018-11-02 10:13:10 2 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (07112bf5-dce0-11e8-bd0f-fe56e4bea91e): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():482. IST will be unavailable. 2018-11-02 10:13:10 0 [Note] WSREP: Member 0.0 (galera2) requested state transfer from '*any*'. Selected 1.0 (galera1)(SYNCED) as donor. 2018-11-02 10:13:10 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 4) 2018-11-02 10:13:10 2 [Note] WSREP: Requesting state transfer: success, donor: 1 2018-11-02 10:13:10 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 07112bf5-dce0-11e8-bd0f-fe56e4bea91e:4 WSREP_SST: [INFO] WARNING: Stale temporary SST directory: /dbdata//.sst from previous state transfer. Removing (20181102 10:13:11.434) WSREP_SST: [INFO] Proceeding with SST (20181102 10:13:11.441) WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr stdio | mbstream -x; RC=( ${PIPESTATUS[@]} ) (20181102 10:13:11.444) WSREP_SST: [INFO] Cleaning the existing datadir and innodb-data/log directories (20181102 10:13:11.452) removed ‘/dbdata/aria_log_control’ removed ‘/dbdata/aria_log.00000001’ removed ‘/dbdata/ibdata1’ removed ‘/dbdata/ibdata2’ removed ‘/dbdata/ibdata3’ removed ‘/dbdata/ibdata4’ removed ‘/dbdata/ibdata5’ removed ‘/dbdata/ib_logfile1’ removed ‘/dbdata/ib_logfile2’ removed ‘/dbdata/ib_logfile3’ removed ‘/dbdata/ib_logfile4’ WSREP_SST: [INFO] Cleaning the binlog directory /maria_log/binary as well (20181102 10:13:11.473) removed ‘/maria_log/binary/maria-bin.000001’ WSREP_SST: [INFO] Waiting for SST streaming to complete! (20181102 10:13:11.480) 2018-11-02 10:13:11 0 [Note] WSREP: (75a27987, 'tcp://0.0.0.0:4567') turning message relay requesting off 2018-11-02 10:13:21 0 [Warning] WSREP: 1.0 (galera1): State transfer to 0.0 (galera2) failed: -22 (Invalid argument) 2018-11-02 10:13:21 0 [ERROR] WSREP: gcs/src/gcs_group.cpp:gcs_group_handle_join_msg():737: Will never receive state. Need to abort. 2018-11-02 10:13:21 0 [Note] WSREP: gcomm: terminating thread 2018-11-02 10:13:21 0 [Note] WSREP: gcomm: joining thread 2018-11-02 10:13:21 0 [Note] WSREP: gcomm: closing backend WSREP_SST: [ERROR] xtrabackup_checkpoints missing, failed innobackupex/SST on donor (20181102 10:13:21.557) WSREP_SST: [ERROR] Cleanup after exit with status:2 (20181102 10:13:21.609) 2018-11-02 10:13:21 0 [ERROR] WSREP: Process completed with error: wsrep_sst_mariabackup --role 'joiner' --address '192.168.1.141:4444' --datadir '/dbdata/' --parent '2998' --binlog '/maria_log/binary/maria-bin' '': 2 (No such file or directory) 2018-11-02 10:13:21 0 [ERROR] WSREP: Failed to read uuid:seqno and wsrep_gtid_domain_id from joiner script. 2018-11-02 10:13:21 0 [ERROR] WSREP: SST failed: 2 (No such file or directory) 2018-11-02 10:13:21 0 [ERROR] Aborting |