Log of wsrep recovery (--wsrep-recover): 2017-09-01 13:38:34 17602 [Warning] Buffered warning: option 'table_definition_cache': unsigned value 1824288 adjusted to 524288 2017-09-01 13:38:34 17602 [Warning] Buffered warning: option 'table_open_cache': unsigned value 1824288 adjusted to 524288 2017-09-01 13:38:34 17602 [Warning] Using unique option prefix myisam-recover instead of myisam-recover-options is deprecated and will be removed in a future release. Please use the full name instead. 2017-09-01 13:38:34 17602 [Note] Plugin 'FEDERATED' is disabled. /usr/sbin/mysqld: Unknown error 1146 2017-09-01 13:38:34 17602 [ERROR] Can't open the mysql.plugin table. Please run mysql_upgrade to create it. 2017-09-01 13:38:34 7f7e0bd4c8c0 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator. 2017-09-01 13:38:34 7f7e0bd4c8c0 InnoDB: Warning: Using innodb_locks_unsafe_for_binlog is DEPRECATED. This option may be removed in future releases. Please use READ COMMITTED transaction isolation level instead, see http://dev.mysql.com/doc/refman/5.6/en/set-transaction.html. 2017-09-01 13:38:34 17602 [Note] InnoDB: Using atomics to ref count buffer pool pages 2017-09-01 13:38:34 17602 [Note] InnoDB: The InnoDB memory heap is disabled 2017-09-01 13:38:34 17602 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-09-01 13:38:34 17602 [Note] InnoDB: Memory barrier is not used 2017-09-01 13:38:34 17602 [Note] InnoDB: Compressed tables use zlib 1.2.7 2017-09-01 13:38:34 17602 [Note] InnoDB: Using Linux native AIO 2017-09-01 13:38:34 17602 [Note] InnoDB: Using CPU crc32 instructions 2017-09-01 13:38:34 17602 [Warning] InnoDB: innodb_file_io_threads is deprecated. Please use innodb_read_io_threads and innodb_write_io_threads instead 2017-09-01 13:38:34 17602 [Note] InnoDB: Initializing buffer pool, size = 10.0G 2017-09-01 13:38:41 17602 [Note] InnoDB: Completed initialization of buffer pool 2017-09-01 13:38:46 17602 [Note] InnoDB: Highest supported file format is Barracuda. 2017-09-01 13:38:46 17602 [Note] InnoDB: 128 rollback segment(s) are active. 2017-09-01 13:38:46 17602 [Note] InnoDB: Waiting for purge to start 2017-09-01 13:38:46 17602 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1606538 2017-09-01 13:38:46 17602 [Warning] InnoDB: Skipping buffer pool dump/restore during wsrep recovery. 2017-09-01 13:38:46 17602 [ERROR] Aborting 2017-09-01 13:38:46 17602 [Note] Binlog end 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'partition' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'BLACKHOLE' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'ARCHIVE' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CHANGED_PAGES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_FIELDS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_INDEXES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_SYS_TABLES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_CONFIG' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_DELETED' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_METRICS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMPMEM' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMP_RESET' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_CMP' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_LOCK_WAITS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_LOCKS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'INNODB_TRX' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'XTRADB_ZIP_DICT_COLS' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'XTRADB_ZIP_DICT' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'XTRADB_RSEG' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'XTRADB_INTERNAL_HASH_TABLES' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'XTRADB_READ_VIEW' 2017-09-01 13:38:46 17602 [Note] Shutting down plugin 'InnoDB' 2017-09-01 13:38:46 17602 [Note] InnoDB: FTS optimize thread exiting. 2017-09-01 13:38:46 17602 [Note] InnoDB: Starting shutdown... 2017-09-01 13:38:47 17602 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool 2017-09-01 13:38:49 17602 [Note] InnoDB: Shutdown completed; log sequence number 1606548 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'CSV' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'MyISAM' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'MRG_MYISAM' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'MEMORY' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'sha256_password' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'mysql_old_password' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'mysql_native_password' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'wsrep' 2017-09-01 13:38:49 17602 [Note] Shutting down plugin 'binlog' 2017-09-01 13:38:49 17602 [Note] 2017-09-01 13:39:43 17707 [Warning] Buffered warning: option 'table_definition_cache': unsigned value 1824288 adjusted to 524288 2017-09-01 13:39:43 17707 [Warning] Buffered warning: option 'table_open_cache': unsigned value 1824288 adjusted to 524288 2017-09-01 13:39:43 17707 [Note] WSREP: Read nil XID from storage engines, skipping position init 2017-09-01 13:39:43 17707 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/libgalera_smm.so' 2017-09-01 13:39:47 17707 [Note] WSREP: wsrep_load(): Galera 3.20(r7e383f7) by Codership Oy loaded successfully. 2017-09-01 13:39:47 17707 [Note] WSREP: CRC-32C: using hardware acceleration. 2017-09-01 13:39:47 17707 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootsrap: 1 2017-09-01 13:39:48 17707 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = 192.168.11.6; 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 = /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.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 = PT 2017-09-01 13:39:49 17707 [Note] WSREP: GCache history reset: old(af81d29b-8a9a-11e7-92d4-962488332acf:0) -> new(00000000-0000-0000-0000-000000000000:-1) 2017-09-01 13:39:49 17707 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2017-09-01 13:39:49 17707 [Note] WSREP: wsrep_sst_grab() 2017-09-01 13:39:49 17707 [Note] WSREP: Start replication 2017-09-01 13:39:49 17707 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2017-09-01 13:39:49 17707 [Note] WSREP: protonet asio version 0 2017-09-01 13:39:49 17707 [Note] WSREP: Using CRC-32C for message checksums. 2017-09-01 13:39:49 17707 [Note] WSREP: backend: asio 2017-09-01 13:39:49 17707 [Note] WSREP: gcomm thread scheduling priority set to other:0 2017-09-01 13:39:49 17707 [Warning] WSREP: access file(/var/lib/mysql//gvwstate.dat) failed(No such file or directory) 2017-09-01 13:39:49 17707 [Note] WSREP: restore pc from disk failed 2017-09-01 13:39:49 17707 [Note] WSREP: GMCast version 0 2017-09-01 13:39:49 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2017-09-01 13:39:49 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2017-09-01 13:39:49 17707 [Note] WSREP: EVS version 0 2017-09-01 13:39:49 17707 [Note] WSREP: gcomm: connecting to group 'galera_cluster', peer '192.168.11.5:,192.168.11.20:' 2017-09-01 13:39:49 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') connection established to af813e7a tcp://192.168.11.5:4567 2017-09-01 13:39:50 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2017-09-01 13:39:50 17707 [Note] WSREP: declaring af813e7a at tcp://192.168.11.5:4567 stable 2017-09-01 13:39:50 17707 [Note] WSREP: Node af813e7a state prim 2017-09-01 13:39:50 17707 [Note] WSREP: view(view_id(PRIM,06be8576,24) memb { 06be8576,0 af813e7a,0 } joined { } left { } partitioned { }) 2017-09-01 13:39:50 17707 [Note] WSREP: save pc into disk 2017-09-01 13:39:50 17707 [Note] WSREP: discarding pending addr without UUID: tcp://192.168.11.20:4567 2017-09-01 13:39:51 17707 [Note] WSREP: gcomm: connected 2017-09-01 13:39:51 17707 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2017-09-01 13:39:51 17707 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2017-09-01 13:39:51 17707 [Note] WSREP: Opened channel 'galera_cluster' 2017-09-01 13:39:51 17707 [Note] WSREP: Waiting for SST to complete. 2017-09-01 13:39:51 17707 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.60011S), skipping check 2017-09-01 13:39:51 17707 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2 2017-09-01 13:39:51 17707 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 2017-09-01 13:39:51 17707 [Note] WSREP: STATE EXCHANGE: sent state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 2017-09-01 13:39:51 17707 [Note] WSREP: STATE EXCHANGE: got state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 from 0 (Node2) 2017-09-01 13:39:51 17707 [Note] WSREP: STATE EXCHANGE: got state msg: 07b2e8c3-8f1b-11e7-8d2c-63e62142bb61 from 1 (Node1) 2017-09-01 13:39:51 17707 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 23, members = 1/2 (joined/total), act_id = 16744963, last_appl. = -1, protocols = 0/7/3 (gcs/repl/appl), group UUID = af81d29b-8a9a-11e7-92d4-962488332acf 2017-09-01 13:39:51 17707 [Note] WSREP: Flow-control interval: [23, 23] 2017-09-01 13:39:51 17707 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 16744963) 2017-09-01 13:39:51 17707 [Note] WSREP: State transfer required: Group state: af81d29b-8a9a-11e7-92d4-962488332acf:16744963 Local state: 00000000-0000-0000-0000-000000000000:-1 2017-09-01 13:39:51 17707 [Note] WSREP: New cluster view: global state: af81d29b-8a9a-11e7-92d4-962488332acf:16744963, view# 24: Primary, number of nodes: 2, my index: 0, protocol version 3 2017-09-01 13:39:51 17707 [Warning] WSREP: Gap in state sequence. Need state transfer. 2017-09-01 13:39:51 17707 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'joiner' --address '192.168.11.6' --datadir '/var/lib/mysql/' --defaults-file '/etc/my.cnf' --defaults-group-suffix '' --parent '17707' '' ' 2017-09-01 13:39:52 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') turning message relay requesting off WSREP_SST: [INFO] Streaming with xbstream (2017-09-01 13:39:53) WSREP_SST: [INFO] Using socat as streamer (2017-09-01 13:39:53) WSREP_SST: [INFO] Stale sst_in_progress file: /var/lib/mysql//sst_in_progress (2017-09-01 13:39:53) WSREP_SST: [INFO] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10 stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (2017-09-01 13:39:54) 2017-09-01 13:39:54 17707 [Note] WSREP: Prepared SST request: xtrabackup-v2|192.168.11.6:4444/xtrabackup_sst//1 2017-09-01 13:39:54 17707 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2017-09-01 13:39:54 17707 [Note] WSREP: REPL Protocols: 7 (3, 2) 2017-09-01 13:39:54 17707 [Note] WSREP: Assign initial position for certification: 16744963, protocol version: 3 2017-09-01 13:39:54 17707 [Note] WSREP: Service thread queue flushed. 2017-09-01 13:39:54 17707 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (af81d29b-8a9a-11e7-92d4-962488332acf): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():535. IST will be unavailable. 2017-09-01 13:39:54 17707 [Note] WSREP: Member 0.0 (Node2) requested state transfer from 'Node1'. Selected 1.0 (Node1)(SYNCED) as donor. 2017-09-01 13:39:54 17707 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 16744965) 2017-09-01 13:39:54 17707 [Note] WSREP: Requesting state transfer: success, donor: 1 2017-09-01 13:39:54 17707 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(af81d29b-8a9a-11e7-92d4-962488332acf:16744963) WSREP_SST: [INFO] WARNING: Stale temporary SST directory: /var/lib/mysql//.sst from previous state transfer. Removing (2017-09-01 13:40:15) WSREP_SST: [INFO] Proceeding with SST (2017-09-01 13:40:15) WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr,retry=20,interval=5,forever,reuseaddr,keepalive,keepidle=30,keepintvl=30,keepcnt=10 stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (2017-09-01 13:40:15) WSREP_SST: [INFO] Cleaning the existing datadir and innodb-data/log directories (2017-09-01 13:40:15) removed ‘/var/lib/mysql/gcache.page.000000’ removed directory: ‘/var/lib/mysql/mysql’ removed directory: ‘/var/lib/mysql/test’ removed ‘/var/lib/mysql/ibdata1’ removed ‘/var/lib/mysql/ib_logfile1’ removed ‘/var/lib/mysql/ib_logfile0’ WSREP_SST: [INFO] Waiting for SST streaming to complete! (2017-09-01 13:40:15) 2017-09-01 14:37:19 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000000 of size 134217728 bytes 2017-09-01 15:01:32 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000001 of size 134217728 bytes 2017-09-01 15:17:20 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000002 of size 134217728 bytes 2017-09-01 15:38:45 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000003 of size 134217728 bytes 2017-09-01 15:40:55 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000004 of size 134217728 bytes 2017-09-01 15:43:09 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000005 of size 134217728 bytes 2017-09-01 15:46:15 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000006 of size 134217728 bytes 2017-09-01 15:49:46 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000007 of size 134217728 bytes 2017-09-01 15:52:47 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000008 of size 134217728 bytes 2017-09-01 15:56:23 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000009 of size 134217728 bytes 2017-09-01 15:59:04 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000010 of size 134217728 bytes 2017-09-01 16:01:21 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000011 of size 134217728 bytes 2017-09-01 16:03:30 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000012 of size 134217728 bytes 2017-09-01 16:06:18 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000013 of size 134217728 bytes 2017-09-01 16:08:56 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000014 of size 134217728 bytes 2017-09-01 16:11:02 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000015 of size 134217728 bytes 2017-09-01 16:13:27 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000016 of size 134217728 bytes 2017-09-01 16:15:28 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000017 of size 134217728 bytes 2017-09-01 16:17:54 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000018 of size 134217728 bytes 2017-09-01 16:20:38 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000019 of size 134217728 bytes 2017-09-01 16:23:15 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000020 of size 134217728 bytes 2017-09-01 16:27:09 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000021 of size 134217728 bytes 2017-09-01 16:31:21 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000022 of size 134217728 bytes 2017-09-01 16:34:50 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000023 of size 134217728 bytes 2017-09-01 16:37:47 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000024 of size 134217728 bytes 2017-09-01 16:40:24 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000025 of size 134217728 bytes 2017-09-01 16:42:49 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000026 of size 134217728 bytes 2017-09-01 16:58:18 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000027 of size 134217728 bytes 2017-09-01 17:18:47 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000028 of size 134217728 bytes 2017-09-01 17:41:28 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000029 of size 134217728 bytes 2017-09-01 18:06:28 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000030 of size 134217728 bytes 2017-09-01 18:29:29 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000031 of size 134217728 bytes 2017-09-01 18:41:49 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000032 of size 134217728 bytes 2017-09-01 18:52:37 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000033 of size 134217728 bytes 2017-09-01 19:11:12 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000034 of size 134217728 bytes 2017-09-01 19:29:05 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000035 of size 134217728 bytes 2017-09-01 19:53:52 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000036 of size 134217728 bytes 2017-09-01 20:20:37 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000037 of size 134217728 bytes 2017-09-01 20:39:14 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000038 of size 134217728 bytes 2017-09-01 21:10:12 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000039 of size 134217728 bytes 2017-09-01 21:40:50 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000040 of size 134217728 bytes 2017-09-01 22:17:41 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000041 of size 134217728 bytes 2017-09-01 23:57:26 17707 [Note] WSREP: 1.0 (Node1): State transfer to 0.0 (Node2) complete. 2017-09-01 23:57:26 17707 [Note] WSREP: Member 1.0 (Node1) synced with group. WSREP_SST: [INFO] Preparing the backup at /var/lib/mysql//.sst (2017-09-01 23:57:26) WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --use-memory=20G --apply-log $rebuildcmd ${DATA} &>${DATA}/innobackup.prepare.log (2017-09-01 23:57:26) 2017-09-02 00:00:37 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000042 of size 134217728 bytes 2017-09-02 00:12:27 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000043 of size 134217728 bytes 2017-09-02 00:18:40 17707 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.96349S), skipping check 2017-09-02 00:18:40 17707 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.77632S), skipping check 2017-09-02 00:30:49 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000044 of size 134217728 bytes 2017-09-02 00:33:57 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000045 of size 134217728 bytes 2017-09-02 00:37:32 17707 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.24806S), skipping check 2017-09-02 00:38:41 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000046 of size 134217728 bytes rm: cannot remove ‘/var/lib/mysql//innobackup.prepare.log’: No such file or directory rm: cannot remove ‘/var/lib/mysql//innobackup.move.log’: No such file or directory WSREP_SST: [INFO] Moving the backup to /var/lib/mysql/ (2017-09-02 01:20:04) WSREP_SST: [INFO] Evaluating innobackupex --defaults-file=/etc/my.cnf --defaults-group=mysqld --no-version-check --datadir=/var/lib/mysql/ --move-back --force-non-empty-directories ${DATA} &>${DATA}/innobackup.move.log (2017-09-02 01:20:04) WSREP_SST: [INFO] Move successful, removing /var/lib/mysql//.sst (2017-09-02 01:31:11) WSREP_SST: [INFO] Galera co-ords from recovery: af81d29b-8a9a-11e7-92d4-962488332acf:19134115 (2017-09-02 01:31:13) WSREP_SST: [INFO] Total time on joiner: 0 seconds (2017-09-02 01:31:13) WSREP_SST: [INFO] Removing the sst_in_progress file (2017-09-02 01:31:13) 2017-09-02 01:31:14 17707 [Note] WSREP: SST complete, seqno: 19134115 2017-09-02 01:31:14 17707 [Warning] Using unique option prefix myisam-recover instead of myisam-recover-options is deprecated and will be removed in a future release. Please use the full name instead. 2017-09-02 01:31:14 17707 [Note] Plugin 'FEDERATED' is disabled. 2017-09-02 01:31:14 7fbf9b8a08c0 InnoDB: Warning: Using innodb_additional_mem_pool_size is DEPRECATED. This option may be removed in future releases, together with the option innodb_use_sys_malloc and with the InnoDB's internal memory allocator. 2017-09-02 01:31:14 7fbf9b8a08c0 InnoDB: Warning: Using innodb_locks_unsafe_for_binlog is DEPRECATED. This option may be removed in future releases. Please use READ COMMITTED transaction isolation level instead, see http://dev.mysql.com/doc/refman/5.6/en/set-transaction.html. 2017-09-02 01:31:14 17707 [Note] InnoDB: Using atomics to ref count buffer pool pages 2017-09-02 01:31:14 17707 [Note] InnoDB: The InnoDB memory heap is disabled 2017-09-02 01:31:14 17707 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-09-02 01:31:14 17707 [Note] InnoDB: Memory barrier is not used 2017-09-02 01:31:14 17707 [Note] InnoDB: Compressed tables use zlib 1.2.7 2017-09-02 01:31:14 17707 [Note] InnoDB: Using Linux native AIO 2017-09-02 01:31:14 17707 [Note] InnoDB: Using CPU crc32 instructions 2017-09-02 01:31:14 17707 [Warning] InnoDB: innodb_file_io_threads is deprecated. Please use innodb_read_io_threads and innodb_write_io_threads instead 2017-09-02 01:31:14 17707 [Note] InnoDB: Initializing buffer pool, size = 10.0G 2017-09-02 01:31:15 17707 [Note] InnoDB: Completed initialization of buffer pool 2017-09-02 01:31:16 17707 [Note] InnoDB: Highest supported file format is Barracuda. 2017-09-02 01:32:27 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000047 of size 134217728 bytes 2017-09-02 02:44:02 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000048 of size 134217728 bytes 2017-09-02 03:03:40 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000049 of size 134217728 bytes 2017-09-02 03:21:14 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000050 of size 134217728 bytes 2017-09-02 03:40:19 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000051 of size 134217728 bytes 2017-09-02 03:44:41 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000052 of size 134217728 bytes 2017-09-02 03:49:21 17707 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000053 of size 134217728 bytes 2017-09-02 03:53:24 17707 [Note] InnoDB: 128 rollback segment(s) are active. 2017-09-02 03:53:24 17707 [Note] InnoDB: Waiting for purge to start 2017-09-02 03:53:24 17707 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 7400309372985 2017-09-02 03:53:24 17707 [ERROR] Aborting 2017-09-02 03:53:24 17707 [Note] WSREP: Signalling cancellation of the SST request. 2017-09-02 03:53:24 17707 [Note] WSREP: SST request was cancelled 2017-09-02 03:53:24 17707 [Note] WSREP: Closing send monitor... 2017-09-02 03:53:24 17707 [Note] WSREP: Closed send monitor. 2017-09-02 03:53:24 17707 [Note] WSREP: gcomm: terminating thread 2017-09-02 03:53:24 17707 [Note] WSREP: gcomm: joining thread 2017-09-02 03:53:24 17707 [Note] WSREP: gcomm: closing backend 2017-09-02 03:53:26 17707 [Note] WSREP: Service disconnected. 2017-09-02 03:53:26 17707 [Note] WSREP: Waiting to close threads...... 2017-09-02 03:53:26 17707 [Note] WSREP: rollbacker thread exiting 2017-09-02 03:53:27 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') connection to peer af813e7a with addr tcp://192.168.11.5:4567 timed out, no messages seen in PT3S 2017-09-02 03:53:27 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: tcp://192.168.11.5:4567 2017-09-02 03:53:29 17707 [Note] WSREP: (06be8576, 'tcp://0.0.0.0:4567') reconnecting to af813e7a (tcp://192.168.11.5:4567), attempt 0 2017-09-02 03:53:30 17707 [Note] WSREP: evs::proto(06be8576, LEAVING, view_id(REG,06be8576,24)) suspecting node: af813e7a 2017-09-02 03:53:30 17707 [Note] WSREP: evs::proto(06be8576, LEAVING, view_id(REG,06be8576,24)) suspected node without join message, declaring inactive 2017-09-02 03:53:30 17707 [Note] WSREP: view(view_id(NON_PRIM,06be8576,24) memb { 06be8576,0 } joined { } left { } partitioned { af813e7a,0 }) 2017-09-02 03:53:30 17707 [Note] WSREP: view((empty)) 2017-09-02 03:53:30 17707 [Note] WSREP: gcomm: closed 2017-09-02 03:53:30 17707 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1 2017-09-02 03:53:30 17707 [Note] WSREP: Flow-control interval: [16, 16] 2017-09-02 03:53:30 17707 [Note] WSREP: Received NON-PRIMARY. 2017-09-02 03:53:30 17707 [Note] WSREP: Shifting JOINER -> OPEN (TO: 19478746) 2017-09-02 03:53:30 17707 [Note] WSREP: Received self-leave message. 2017-09-02 03:53:30 17707 [Note] WSREP: Flow-control interval: [0, 0] 2017-09-02 03:53:30 17707 [Note] WSREP: Received SELF-LEAVE. Closing connection. 2017-09-02 03:53:30 17707 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 19478746) 2017-09-02 03:53:30 17707 [Note] WSREP: RECV thread exiting 0: Success 2017-09-02 03:53:30 17707 [Note] WSREP: recv_thread() joined. 2017-09-02 03:53:30 17707 [Note] WSREP: Closing replication queue. 2017-09-02 03:53:30 17707 [Note] WSREP: Closing slave action queue. 2017-09-02 03:53:31 17707 [Note] WSREP: Some threads may fail to exit. 2017-09-02 03:53:31 17707 [Note] Binlog end 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'partition' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'BLACKHOLE' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'ARCHIVE' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CHANGED_PAGES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_FIELDS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_INDEXES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_SYS_TABLES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_CONFIG' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_DELETED' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_METRICS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMPMEM' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMP_RESET' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_CMP' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_LOCK_WAITS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_LOCKS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'INNODB_TRX' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'XTRADB_ZIP_DICT_COLS' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'XTRADB_ZIP_DICT' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'XTRADB_RSEG' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'XTRADB_INTERNAL_HASH_TABLES' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'XTRADB_READ_VIEW' 2017-09-02 03:53:31 17707 [Note] Shutting down plugin 'InnoDB' 2017-09-02 03:53:31 17707 [Note] InnoDB: FTS optimize thread exiting. 2017-09-02 03:53:31 17707 [Note] InnoDB: Starting shutdown... 2017-09-02 03:53:32 17707 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool 2017-09-02 03:53:36 17707 [Note] InnoDB: Shutdown completed; log sequence number 7400309372995 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'CSV' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'MyISAM' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'MRG_MYISAM' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'MEMORY' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'sha256_password' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'mysql_old_password' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'mysql_native_password' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'wsrep' 2017-09-02 03:53:36 17707 [Note] Shutting down plugin 'binlog' 2017-09-02 03:53:36 17707 [Note] Error in my_thread_global_end(): 1 threads didn't exit