By type
Galera: Finding peers + CONTAINER_SCRIPTS_DIR=/usr/share/container-scripts/mysql + EXTRA_DEFAULTS_FILE=/etc/my.cnf.d/galera.cnf + '[' -z onap ']' + echo 'Galera: Finding peers' ++ hostname -f ++ cut -d. -f2 + K8S_SVC_NAME=vfc-mariadb + echo 'Using service name: vfc-mariadb' Using service name: vfc-mariadb + cp /usr/share/container-scripts/mysql/galera.cnf /etc/my.cnf.d/galera.cnf + /usr/bin/peer-finder -on-start=/usr/share/container-scripts/mysql/configure-galera.sh -service=vfc-mariadb 2021/04/14 02:16:15 Peer list updated was [] now [onap-vfc-mariadb-0.vfc-mariadb.onap.svc.cluster.local onap-vfc-mariadb-1.vfc-mariadb.onap.svc.cluster.local onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local] 2021/04/14 02:16:15 execing: /usr/share/container-scripts/mysql/configure-galera.sh with stdin: onap-vfc-mariadb-0.vfc-mariadb.onap.svc.cluster.local onap-vfc-mariadb-1.vfc-mariadb.onap.svc.cluster.local onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local 2021/04/14 02:16:15 2021/04/14 02:16:16 Peer finder exiting + '[' '!' -d /var/lib/mysql/mysql ']' Configure first time mysql + echo 'Configure first time mysql' + /usr/share/container-scripts/mysql/configure-mysql.sh Running mysql_install_db ... + echo 'Running mysql_install_db ...' + mysql_install_db --datadir=/var/lib/mysql Installing MariaDB/MySQL system tables in '/var/lib/mysql' ... 2021-04-14 2:16:16 140168198777088 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 50 ... 2021-04-14 2:16:16 140168198777088 [Note] WSREP: Read nil XID from storage engines, skipping position init 2021-04-14 2:16:16 140168198777088 [Note] WSREP: wsrep_load(): loading provider library 'none' 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB. 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: The InnoDB memory heap is disabled 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Compressed tables use zlib 1.2.7 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Using Linux native AIO 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Using SSE crc32 instructions 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Completed initialization of buffer pool 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: The first specified data file ./ibdata1 did not exist: a new database to be created! 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Setting file ./ibdata1 size to 12 MB 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Setting log file ./ib_logfile101 size to 48 MB 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Setting log file ./ib_logfile1 size to 48 MB 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Renaming log file ./ib_logfile101 to ./ib_logfile0 2021-04-14 2:16:16 140168198777088 [Warning] InnoDB: New log files created, LSN=45883 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Doublewrite buffer not found: creating new 2021-04-14 2:16:16 140168198777088 [Note] InnoDB: Doublewrite buffer created 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: 128 rollback segment(s) are active. 2021-04-14 2:16:17 140168198777088 [Warning] InnoDB: Creating foreign key constraint system tables. 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: Foreign key constraint system tables created 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: Creating tablespace and datafile system tables. 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: Tablespace and datafile system tables created. 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: Waiting for purge to start 2021-04-14 2:16:17 140168198777088 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 0 2021-04-14 2:16:17 140167415330560 [Note] InnoDB: Dumping buffer pool(s) not yet started OK Filling help tables... 2021-04-14 2:16:21 140251627305216 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 79 ... 2021-04-14 2:16:21 140251627305216 [Note] WSREP: Read nil XID from storage engines, skipping position init 2021-04-14 2:16:21 140251627305216 [Note] WSREP: wsrep_load(): loading provider library 'none' 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB. 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: The InnoDB memory heap is disabled 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Compressed tables use zlib 1.2.7 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Using Linux native AIO 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Using SSE crc32 instructions 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Completed initialization of buffer pool 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Highest supported file format is Barracuda. 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: 128 rollback segment(s) are active. 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Waiting for purge to start 2021-04-14 2:16:21 140251627305216 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616799 2021-04-14 2:16:21 140250848425728 [Note] InnoDB: Dumping buffer pool(s) not yet started OK Creating OpenGIS required SP-s... 2021-04-14 2:16:25 140064461670656 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 108 ... 2021-04-14 2:16:25 140064461670656 [Note] WSREP: Read nil XID from storage engines, skipping position init 2021-04-14 2:16:25 140064461670656 [Note] WSREP: wsrep_load(): loading provider library 'none' 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB. 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: The InnoDB memory heap is disabled 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Compressed tables use zlib 1.2.7 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Using Linux native AIO 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Using SSE crc32 instructions 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Completed initialization of buffer pool 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Highest supported file format is Barracuda. 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: 128 rollback segment(s) are active. 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Waiting for purge to start 2021-04-14 2:16:25 140064461670656 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616809 2021-04-14 2:16:25 140063681804032 [Note] InnoDB: Dumping buffer pool(s) not yet started OK To start mysqld at boot time you have to copy support-files/mysql.server to the right place for your system PLEASE REMEMBER TO SET A PASSWORD FOR THE MariaDB root USER ! To do so, start the server, then issue the following commands: '/usr/bin/mysqladmin' -u root password 'new-password' '/usr/bin/mysqladmin' -u root -h onap-vfc-mariadb-2 password 'new-password' Alternatively you can run: '/usr/bin/mysql_secure_installation' which will also give you the option of removing the test databases and anonymous user created by default. This is strongly recommended for production servers. See the MariaDB Knowledgebase at http://mariadb.com/kb or the MySQL manual for more instructions. You can start the MariaDB daemon with: cd '/usr' ; /usr/bin/mysqld_safe --datadir='/var/lib/mysql' You can test the MariaDB daemon with mysql-test-run.pl cd '/usr/mysql-test' ; perl mysql-test-run.pl Please report any problems at http://mariadb.org/jira The latest information about MariaDB is available at http://mariadb.org/. You can find additional information about the MySQL part at: http://dev.mysql.com Consider joining MariaDB's strong and vibrant community: https://mariadb.org/get-involved/ Finished mysql_install_db + echo 'Finished mysql_install_db' + pid=136 + mysqld --skip-networking --socket=/var/lib/mysql/mysql-init.sock --wsrep_on=OFF + mysql=(mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock) + for i in '{30..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... 2021-04-14 2:16:29 140249958611200 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 136 ... 2021-04-14 2:16:29 140249958611200 [Warning] You need to use --log-bin to make --binlog-format work. 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB. 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: The InnoDB memory heap is disabled 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Compressed tables use zlib 1.2.7 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Using Linux native AIO 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Using SSE crc32 instructions 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2021-04-14 2:16:29 140249958611200 [Note] InnoDB: Completed initialization of buffer pool 2021-04-14 2:16:30 140249958611200 [Note] InnoDB: Highest supported file format is Barracuda. 2021-04-14 2:16:30 140249958611200 [Note] InnoDB: 128 rollback segment(s) are active. 2021-04-14 2:16:30 140249958611200 [Note] InnoDB: Waiting for purge to start 2021-04-14 2:16:30 140249958611200 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616819 2021-04-14 2:16:30 140249179092736 [Note] InnoDB: Dumping buffer pool(s) not yet started 2021-04-14 2:16:30 140249958611200 [Note] Plugin 'FEEDBACK' is disabled. 2021-04-14 2:16:30 140249958611200 [Note] mysqld: ready for connections. Version: '10.1.24-MariaDB' socket: '/var/lib/mysql/mysql-init.sock' port: 0 MariaDB Server + for i in '{30..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock + break + '[' 29 = 0 ']' + '[' -z '' ']' + mysql_tzinfo_to_sql /usr/share/zoneinfo + sed 's/Local time zone must be set--see zic manual page/FCTY/' + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock mysql ++ prepare_password YuraJayi0+Fumu ++ echo YuraJayi0+Fumu ++ sed -e 's/'\''/\\'\''/g; s/"/\\"/g' + mysql_root_password=YuraJayi0+Fumu + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock + '[' '!' -z YuraJayi0+Fumu ']' + mysql+=(-p"${MYSQL_ROOT_PASSWORD}") + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock -pYuraJayi0+Fumu + '[' '' ']' + '[' my-user -a BuvaRiwo8=Joru ']' ++ prepare_password BuvaRiwo8=Joru ++ echo BuvaRiwo8=Joru ++ sed -e 's/'\''/\\'\''/g; s/"/\\"/g' + mysql_password=BuvaRiwo8=Joru + echo 'CREATE USER '\''my-user'\''@'\''%'\'' IDENTIFIED BY '\''BuvaRiwo8=Joru'\'' ;' + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock -pYuraJayi0+Fumu + '[' '' ']' + echo 'FLUSH PRIVILEGES ;' + mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock -pYuraJayi0+Fumu + kill -s TERM 136 + wait 136 2021-04-14 2:16:33 140249957759744 [Note] mysqld: Normal shutdown 2021-04-14 2:16:33 140249957759744 [Note] Event Scheduler: Purging the queue. 0 events 2021-04-14 2:16:34 140249153926912 [Note] InnoDB: FTS optimize thread exiting. 2021-04-14 2:16:34 140249957759744 [Note] InnoDB: Starting shutdown... 2021-04-14 2:16:34 140249957759744 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool 2021-04-14 2:16:35 140249957759744 [Note] InnoDB: Shutdown completed; log sequence number 1616829 2021-04-14 2:16:35 140249957759744 [Note] mysqld: Shutdown complete + echo + echo 'MySQL init process done. Ready for start up.' + echo MySQL init process done. Ready for start up. + exec mysqld 2021-04-14 2:16:36 139863155026176 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 1 ... 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Read nil XID from storage engines, skipping position init 2021-04-14 2:16:36 139863155026176 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so' 2021-04-14 2:16:36 139863155026176 [Note] WSREP: wsrep_load(): Galera 25.3.20(r3703) by Codership Oy
loaded successfully. 2021-04-14 2:16:36 139863155026176 [Note] WSREP: CRC-32C: using hardware acceleration. 2021-04-14 2:16:36 139863155026176 [Warning] WSREP: Could not open state file for reading: '/var/lib/mysql//grastate.dat' 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootsrap: 1 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local; 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_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_ 2021-04-14 2:16:36 139863155026176 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(00000000-0000-0000-0000-000000000000:-1) 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2021-04-14 2:16:36 139863155026176 [Note] WSREP: wsrep_sst_grab() 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Start replication 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2021-04-14 2:16:36 139863155026176 [Note] WSREP: protonet asio version 0 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Using CRC-32C for message checksums. 2021-04-14 2:16:36 139863155026176 [Note] WSREP: backend: asio 2021-04-14 2:16:36 139863155026176 [Note] WSREP: gcomm thread scheduling priority set to other:0 2021-04-14 2:16:36 139863155026176 [Warning] WSREP: access file(/var/lib/mysql//gvwstate.dat) failed(No such file or directory) 2021-04-14 2:16:36 139863155026176 [Note] WSREP: restore pc from disk failed 2021-04-14 2:16:36 139863155026176 [Note] WSREP: GMCast version 0 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2021-04-14 2:16:36 139863155026176 [Note] WSREP: EVS version 0 2021-04-14 2:16:36 139863155026176 [Note] WSREP: gcomm: connecting to group 'vfc-mariadb', peer 'onap-vfc-mariadb-0.vfc-mariadb.onap.svc.cluster.local:,onap-vfc-mariadb-1.vfc-mariadb.onap.svc.cluster.local:,onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local:' 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') connection established to 7074099f tcp://10.233.76.225:4567 2021-04-14 2:16:36 139863155026176 [Warning] WSREP: (7074099f, 'tcp://0.0.0.0:4567') address 'tcp://10.233.76.225:4567' points to own listening address, blacklisting 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') connection established to 2ff5e7a4 tcp://10.233.71.195:4567 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: 2021-04-14 2:16:36 139863155026176 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') connection established to 4a9dd25e tcp://10.233.73.176:4567 2021-04-14 2:16:36 139863155026176 [Note] WSREP: declaring 2ff5e7a4 at tcp://10.233.71.195:4567 stable 2021-04-14 2:16:36 139863155026176 [Note] WSREP: declaring 4a9dd25e at tcp://10.233.73.176:4567 stable 2021-04-14 2:16:36 139863155026176 [Note] WSREP: Node 2ff5e7a4 state prim 2021-04-14 2:16:36 139863155026176 [Note] WSREP: view(view_id(PRIM,2ff5e7a4,3) memb { 2ff5e7a4,0 4a9dd25e,0 7074099f,0 } joined { } left { } partitioned { }) 2021-04-14 2:16:36 139863155026176 [Note] WSREP: save pc into disk 2021-04-14 2:16:37 139863155026176 [Note] WSREP: gcomm: connected 2021-04-14 2:16:37 139863155026176 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2021-04-14 2:16:37 139863155026176 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2021-04-14 2:16:37 139863155026176 [Note] WSREP: Opened channel 'vfc-mariadb' 2021-04-14 2:16:37 139862833358592 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 2, memb_num = 3 2021-04-14 2:16:37 139862833358592 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID. 2021-04-14 2:16:37 139862833358592 [Note] WSREP: STATE EXCHANGE: sent state msg: 70ce04aa-9cc7-11eb-9a26-06589c821e93 2021-04-14 2:16:37 139862833358592 [Note] WSREP: STATE EXCHANGE: got state msg: 70ce04aa-9cc7-11eb-9a26-06589c821e93 from 0 (onap-vfc-mariadb-0) 2021-04-14 2:16:37 139862833358592 [Note] WSREP: STATE EXCHANGE: got state msg: 70ce04aa-9cc7-11eb-9a26-06589c821e93 from 1 (onap-vfc-mariadb-1) 2021-04-14 2:16:37 139863155026176 [Note] WSREP: Waiting for SST to complete. 2021-04-14 2:16:37 139862833358592 [Note] WSREP: STATE EXCHANGE: got state msg: 70ce04aa-9cc7-11eb-9a26-06589c821e93 from 2 (onap-vfc-mariadb-2) 2021-04-14 2:16:37 139862833358592 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 2, members = 2/3 (joined/total), act_id = 0, last_appl. = -1, protocols = 0/7/3 (gcs/repl/appl), group UUID = 2ff6811f-9cc7-11eb-9528-c78d6d499dad 2021-04-14 2:16:37 139862833358592 [Note] WSREP: Flow-control interval: [28, 28] 2021-04-14 2:16:37 139862833358592 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0) 2021-04-14 2:16:37 139863154576128 [Note] WSREP: State transfer required: Group state: 2ff6811f-9cc7-11eb-9528-c78d6d499dad:0 Local state: 00000000-0000-0000-0000-000000000000:-1 2021-04-14 2:16:37 139863154576128 [Note] WSREP: New cluster view: global state: 2ff6811f-9cc7-11eb-9528-c78d6d499dad:0, view# 3: Primary, number of nodes: 3, my index: 2, protocol version 3 2021-04-14 2:16:37 139863154576128 [Warning] WSREP: Gap in state sequence. Need state transfer. 2021-04-14 2:16:37 139862804002560 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'joiner' --address 'onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local' --datadir '/var/lib/mysql/' --parent '1' '' ' 2021-04-14 2:16:39 139862841751296 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') connection to peer 7074099f with addr tcp://10.233.76.225:4567 timed out, no messages seen in PT3S 2021-04-14 2:16:39 139862841751296 [Note] WSREP: (7074099f, 'tcp://0.0.0.0:4567') turning message relay requesting off WSREP_SST: [INFO] Streaming with xbstream (20210414 02:16:39.868) WSREP_SST: [INFO] Using socat as streamer (20210414 02:16:39.873) WSREP_SST: [INFO] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210414 02:16:40.085) 2021-04-14 2:16:40 139863154576128 [Note] WSREP: Prepared SST request: xtrabackup-v2|onap-vfc-mariadb-2.vfc-mariadb.onap.svc.cluster.local:4444/xtrabackup_sst//1 2021-04-14 2:16:40 139863154576128 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2021-04-14 2:16:40 139863154576128 [Note] WSREP: REPL Protocols: 7 (3, 2) 2021-04-14 2:16:40 139863154576128 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3 2021-04-14 2:16:40 139862891665152 [Note] WSREP: Service thread queue flushed. 2021-04-14 2:16:40 139863154576128 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (2ff6811f-9cc7-11eb-9528-c78d6d499dad): 1 (Operation not permitted) at galera/src/replicator_str.cpp:prepare_for_IST():482. IST will be unavailable. 2021-04-14 2:16:40 139862833358592 [Note] WSREP: Member 2.0 (onap-vfc-mariadb-2) requested state transfer from '*any*'. Selected 0.0 (onap-vfc-mariadb-0)(SYNCED) as donor. 2021-04-14 2:16:40 139862833358592 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0) 2021-04-14 2:16:40 139863154576128 [Note] WSREP: Requesting state transfer: success, donor: 0 2021-04-14 2:16:40 139863154576128 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(2ff6811f-9cc7-11eb-9528-c78d6d499dad:0) WSREP_SST: [INFO] Proceeding with SST (20210414 02:16:41.572) WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210414 02:16:41.574) WSREP_SST: [INFO] Cleaning the existing datadir and innodb-data/log directories (20210414 02:16:41.575) removed '/var/lib/mysql/mysql/db.frm' removed '/var/lib/mysql/mysql/db.MYI' removed '/var/lib/mysql/mysql/db.MYD' removed '/var/lib/mysql/mysql/host.frm' removed '/var/lib/mysql/mysql/host.MYI' removed '/var/lib/mysql/mysql/host.MYD' removed '/var/lib/mysql/mysql/user.frm' removed '/var/lib/mysql/mysql/user.MYI' removed '/var/lib/mysql/mysql/user.MYD' removed '/var/lib/mysql/mysql/roles_mapping.frm' removed '/var/lib/mysql/mysql/roles_mapping.MYI' removed '/var/lib/mysql/mysql/roles_mapping.MYD' removed '/var/lib/mysql/mysql/func.frm' removed '/var/lib/mysql/mysql/func.MYI' removed '/var/lib/mysql/mysql/func.MYD' removed '/var/lib/mysql/mysql/plugin.frm' removed '/var/lib/mysql/mysql/plugin.MYI' removed '/var/lib/mysql/mysql/plugin.MYD' removed '/var/lib/mysql/mysql/servers.frm' removed '/var/lib/mysql/mysql/servers.MYI' removed '/var/lib/mysql/mysql/servers.MYD' removed '/var/lib/mysql/mysql/tables_priv.frm' removed '/var/lib/mysql/mysql/tables_priv.MYI' removed '/var/lib/mysql/mysql/tables_priv.MYD' removed '/var/lib/mysql/mysql/columns_priv.frm' removed '/var/lib/mysql/mysql/columns_priv.MYI' removed '/var/lib/mysql/mysql/columns_priv.MYD' removed '/var/lib/mysql/mysql/help_topic.frm' removed '/var/lib/mysql/mysql/help_topic.MYI' removed '/var/lib/mysql/mysql/help_topic.MYD' removed '/var/lib/mysql/mysql/help_category.frm' removed '/var/lib/mysql/mysql/help_category.MYI' removed '/var/lib/mysql/mysql/help_category.MYD' removed '/var/lib/mysql/mysql/help_relation.frm' removed '/var/lib/mysql/mysql/help_relation.MYI' removed '/var/lib/mysql/mysql/help_relation.MYD' removed '/var/lib/mysql/mysql/help_keyword.frm' removed '/var/lib/mysql/mysql/help_keyword.MYI' removed '/var/lib/mysql/mysql/help_keyword.MYD' removed '/var/lib/mysql/mysql/time_zone_name.frm' removed '/var/lib/mysql/mysql/time_zone_name.MYI' removed '/var/lib/mysql/mysql/time_zone_name.MYD' removed '/var/lib/mysql/mysql/time_zone.frm' removed '/var/lib/mysql/mysql/time_zone.MYI' removed '/var/lib/mysql/mysql/time_zone.MYD' removed '/var/lib/mysql/mysql/time_zone_transition_type.MYI' removed '/var/lib/mysql/mysql/time_zone_transition_type.MYD' removed '/var/lib/mysql/mysql/time_zone_transition_type.frm' removed '/var/lib/mysql/mysql/time_zone_leap_second.frm' removed '/var/lib/mysql/mysql/time_zone_leap_second.MYI' removed '/var/lib/mysql/mysql/time_zone_leap_second.MYD' removed '/var/lib/mysql/mysql/proc.frm' removed '/var/lib/mysql/mysql/proc.MYI' removed '/var/lib/mysql/mysql/proc.MYD' removed '/var/lib/mysql/mysql/procs_priv.frm' removed '/var/lib/mysql/mysql/procs_priv.MYI' removed '/var/lib/mysql/mysql/procs_priv.MYD' removed '/var/lib/mysql/mysql/general_log.frm' removed '/var/lib/mysql/mysql/general_log.CSM' removed '/var/lib/mysql/mysql/general_log.CSV' removed '/var/lib/mysql/mysql/slow_log.frm' removed '/var/lib/mysql/mysql/slow_log.CSM' removed '/var/lib/mysql/mysql/slow_log.CSV' removed '/var/lib/mysql/mysql/event.frm' removed '/var/lib/mysql/mysql/event.MYI' removed '/var/lib/mysql/mysql/event.MYD' removed '/var/lib/mysql/mysql/innodb_table_stats.frm' removed '/var/lib/mysql/mysql/innodb_table_stats.ibd' removed '/var/lib/mysql/mysql/innodb_index_stats.frm' removed '/var/lib/mysql/mysql/innodb_index_stats.ibd' removed '/var/lib/mysql/mysql/proxies_priv.frm' removed '/var/lib/mysql/mysql/proxies_priv.MYI' removed '/var/lib/mysql/mysql/proxies_priv.MYD' removed '/var/lib/mysql/mysql/table_stats.frm' removed '/var/lib/mysql/mysql/table_stats.MYI' removed '/var/lib/mysql/mysql/table_stats.MYD' removed '/var/lib/mysql/mysql/column_stats.frm' removed '/var/lib/mysql/mysql/column_stats.MYI' removed '/var/lib/mysql/mysql/column_stats.MYD' removed '/var/lib/mysql/mysql/index_stats.frm' removed '/var/lib/mysql/mysql/index_stats.MYI' removed '/var/lib/mysql/mysql/index_stats.MYD' removed '/var/lib/mysql/mysql/gtid_slave_pos.frm' removed '/var/lib/mysql/mysql/gtid_slave_pos.ibd' removed '/var/lib/mysql/mysql/time_zone_transition.MYI' removed '/var/lib/mysql/mysql/time_zone_transition.MYD' removed '/var/lib/mysql/mysql/time_zone_transition.frm' removed directory: '/var/lib/mysql/mysql' removed '/var/lib/mysql/aria_log_control' removed '/var/lib/mysql/aria_log.00000001' removed '/var/lib/mysql/ibdata1' removed '/var/lib/mysql/ib_logfile1' removed '/var/lib/mysql/ib_logfile0' removed '/var/lib/mysql/performance_schema/db.opt' removed directory: '/var/lib/mysql/performance_schema' removed '/var/lib/mysql/multi-master.info' WSREP_SST: [INFO] Waiting for SST streaming to complete! (20210414 02:16:41.729) 2021-04-14 2:16:53 139862833358592 [Note] WSREP: 0.0 (onap-vfc-mariadb-0): State transfer to 2.0 (onap-vfc-mariadb-2) complete. 2021-04-14 2:16:53 139862833358592 [Note] WSREP: Member 0.0 (onap-vfc-mariadb-0) synced with group. WSREP_SST: [INFO] Preparing the backup at /var/lib/mysql//.sst (20210414 02:16:53.230) WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --apply-log $rebuildcmd ${DATA} &>${DATA}/innobackup.prepare.log (20210414 02:16:53.234) 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/ (20210414 02:16:56.725) WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --move-back --force-non-empty-directories ${DATA} &>${DATA}/innobackup.move.log (20210414 02:16:56.730) WSREP_SST: [INFO] Move successful, removing /var/lib/mysql//.sst (20210414 02:16:57.059) WSREP_SST: [INFO] Galera co-ords from recovery: 2ff6811f-9cc7-11eb-9528-c78d6d499dad:0 (20210414 02:16:57.084) WSREP_SST: [INFO] Total time on joiner: 0 seconds (20210414 02:16:57.093) WSREP_SST: [INFO] Removing the sst_in_progress file (20210414 02:16:57.100) 2021-04-14 2:16:57 139863155026176 [Note] WSREP: SST complete, seqno: 0 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB. 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: The InnoDB memory heap is disabled 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Compressed tables use zlib 1.2.7 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Using Linux native AIO 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Using SSE crc32 instructions 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Initializing buffer pool, size = 128.0M 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Completed initialization of buffer pool 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Highest supported file format is Barracuda. 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: 128 rollback segment(s) are active. 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Waiting for purge to start 2021-04-14 2:16:57 139863155026176 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1617430 2021-04-14 2:16:57 139862145496832 [Note] InnoDB: Dumping buffer pool(s) not yet started 2021-04-14 2:16:57 139863155026176 [Note] Plugin 'FEEDBACK' is disabled. 2021-04-14 2:16:57 139863155026176 [Note] Server socket created on IP: '0.0.0.0'. 2021-04-14 2:16:57 139863155026176 [Note] WSREP: Signalling provider to continue. 2021-04-14 2:16:57 139863155026176 [Note] WSREP: SST received: 2ff6811f-9cc7-11eb-9528-c78d6d499dad:0 2021-04-14 2:16:57 139862833358592 [Note] WSREP: 2.0 (onap-vfc-mariadb-2): State transfer from 0.0 (onap-vfc-mariadb-0) complete. 2021-04-14 2:16:57 139862833358592 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0) 2021-04-14 2:16:57 139862833358592 [Note] WSREP: Member 2.0 (onap-vfc-mariadb-2) synced with group. 2021-04-14 2:16:57 139862833358592 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0) 2021-04-14 2:16:57 139863154576128 [Note] WSREP: Synchronized with group, ready for connections 2021-04-14 2:16:57 139863154576128 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2021-04-14 2:16:57 139863155026176 [Note] mysqld: ready for connections. Version: '10.1.24-MariaDB' socket: '/var/lib/mysql/mysql.sock' port: 3306 MariaDB Server 2021-04-14 2:19:46 139863077362432 [Warning] IP address '10.233.78.68' could not be resolved: Name or service not known