By type
+ CONTAINER_SCRIPTS_DIR=/usr/share/container-scripts/mysql
+ EXTRA_DEFAULTS_FILE=/etc/my.cnf.d/galera.cnf
+ '[' -z onap ']'
+ echo 'Galera: Finding peers'
Galera: Finding peers
++ hostname -f
++ cut -d. -f2
Using service name: vfc-mariadb
+ K8S_SVC_NAME=vfc-mariadb
+ echo '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/06/07 02:29:29 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]
2021/06/07 02:29:29 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
2021/06/07 02:29:29
2021/06/07 02:29:30 Peer finder exiting
+ '[' '!' -d /var/lib/mysql/mysql ']'
+ echo 'Configure first time mysql'
+ /usr/share/container-scripts/mysql/configure-mysql.sh
Configure first time mysql
+ echo 'Running mysql_install_db ...'
+ mysql_install_db --datadir=/var/lib/mysql
Running mysql_install_db ...
Installing MariaDB/MySQL system tables in '/var/lib/mysql' ...
2021-06-07 2:29:31 140571434412288 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 51 ...
2021-06-07 2:29:31 140571434412288 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-06-07 2:29:31 140571434412288 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-06-07 2:29:31 140571434412288 [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-06-07 2:29:31 140571434412288 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: The InnoDB memory heap is disabled
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Using Linux native AIO
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Using SSE crc32 instructions
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Completed initialization of buffer pool
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: The first specified data file ./ibdata1 did not exist: a new database to be created!
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Setting file ./ibdata1 size to 12 MB
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Setting log file ./ib_logfile101 size to 48 MB
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Setting log file ./ib_logfile1 size to 48 MB
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Renaming log file ./ib_logfile101 to ./ib_logfile0
2021-06-07 2:29:31 140571434412288 [Warning] InnoDB: New log files created, LSN=45883
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Doublewrite buffer not found: creating new
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Doublewrite buffer created
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: 128 rollback segment(s) are active.
2021-06-07 2:29:31 140571434412288 [Warning] InnoDB: Creating foreign key constraint system tables.
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Foreign key constraint system tables created
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Creating tablespace and datafile system tables.
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Tablespace and datafile system tables created.
2021-06-07 2:29:31 140571434412288 [Note] InnoDB: Waiting for purge to start
2021-06-07 2:29:32 140571434412288 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 0
2021-06-07 2:29:32 140570651522816 [Note] InnoDB: Dumping buffer pool(s) not yet started
OK
Filling help tables...
2021-06-07 2:29:36 140403841440000 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 80 ...
2021-06-07 2:29:36 140403841440000 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-06-07 2:29:36 140403841440000 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-06-07 2:29:36 140403841440000 [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-06-07 2:29:36 140403841440000 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: The InnoDB memory heap is disabled
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Using Linux native AIO
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Using SSE crc32 instructions
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Completed initialization of buffer pool
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Highest supported file format is Barracuda.
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: 128 rollback segment(s) are active.
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Waiting for purge to start
2021-06-07 2:29:36 140403841440000 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616799
2021-06-07 2:29:36 140403059717888 [Note] InnoDB: Dumping buffer pool(s) not yet started
OK
Creating OpenGIS required SP-s...
2021-06-07 2:29:40 140515443398912 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 110 ...
2021-06-07 2:29:40 140515443398912 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-06-07 2:29:40 140515443398912 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-06-07 2:29:40 140515443398912 [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-06-07 2:29:40 140515443398912 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: The InnoDB memory heap is disabled
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Using Linux native AIO
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Using SSE crc32 instructions
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Completed initialization of buffer pool
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Highest supported file format is Barracuda.
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: 128 rollback segment(s) are active.
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Waiting for purge to start
2021-06-07 2:29:40 140515443398912 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616809
2021-06-07 2:29:40 140514661758720 [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-1 password 'new-password'
Alternatively you can run:
'/usr/bin/mysql_secure_installation'
which will also give you the option of removing the test
+ echo 'Finished mysql_install_db'
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
+ pid=137
+ 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
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
2021-06-07 2:29:44 140310835112192 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 137 ...
2021-06-07 2:29:44 140310835112192 [Warning] You need to use --log-bin to make --binlog-format work.
2021-06-07 2:29:44 140310835112192 [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-06-07 2:29:45 140310835112192 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: The InnoDB memory heap is disabled
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Using Linux native AIO
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Using SSE crc32 instructions
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Completed initialization of buffer pool
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Highest supported file format is Barracuda.
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: 128 rollback segment(s) are active.
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Waiting for purge to start
2021-06-07 2:29:45 140310835112192 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616819
2021-06-07 2:29:45 140310055220992 [Note] InnoDB: Dumping buffer pool(s) not yet started
2021-06-07 2:29:45 140310835112192 [Note] Plugin 'FEEDBACK' is disabled.
2021-06-07 2:29:45 140310835112192 [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 137
+ wait 137
2021-06-07 2:29:51 140310834260736 [Note] mysqld: Normal shutdown
2021-06-07 2:29:51 140310834260736 [Note] Event Scheduler: Purging the queue. 0 events
2021-06-07 2:29:51 140310038435584 [Note] InnoDB: FTS optimize thread exiting.
2021-06-07 2:29:51 140310834260736 [Note] InnoDB: Starting shutdown...
2021-06-07 2:29:51 140310834260736 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool
2021-06-07 2:29:52 140310834260736 [Note] InnoDB: Shutdown completed; log sequence number 1616829
2021-06-07 2:29:52 140310834260736 [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-06-07 2:29:53 140409269750016 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 1 ...
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-06-07 2:29:53 140409269750016 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
2021-06-07 2:29:53 140409269750016 [Note] WSREP: wsrep_load(): Galera 25.3.20(r3703) by Codership Oy loaded successfully.
2021-06-07 2:29:53 140409269750016 [Note] WSREP: CRC-32C: using hardware acceleration.
2021-06-07 2:29:53 140409269750016 [Warning] WSREP: Could not open state file for reading: '/var/lib/mysql//grastate.dat'
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootsrap: 1
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = onap-vfc-mariadb-1.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-06-07 2:29:53 140409269750016 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(00000000-0000-0000-0000-000000000000:-1)
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2021-06-07 2:29:53 140409269750016 [Note] WSREP: wsrep_sst_grab()
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Start replication
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2021-06-07 2:29:53 140409269750016 [Note] WSREP: protonet asio version 0
2021-06-07 2:29:53 140409269750016 [Note] WSREP: Using CRC-32C for message checksums.
2021-06-07 2:29:53 140409269750016 [Note] WSREP: backend: asio
2021-06-07 2:29:53 140409269750016 [Note] WSREP: gcomm thread scheduling priority set to other:0
2021-06-07 2:29:53 140409269750016 [Warning] WSREP: access file(/var/lib/mysql//gvwstate.dat) failed(No such file or directory)
2021-06-07 2:29:53 140409269750016 [Note] WSREP: restore pc from disk failed
2021-06-07 2:29:53 140409269750016 [Note] WSREP: GMCast version 0
2021-06-07 2:29:53 140409269750016 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
2021-06-07 2:29:53 140409269750016 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
2021-06-07 2:29:53 140409269750016 [Note] WSREP: EVS version 0
2021-06-07 2:29:53 140409269750016 [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:'
2021-06-07 2:29:53 140409269750016 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') connection established to 3de3c5f4 tcp://10.233.78.191:4567
2021-06-07 2:29:53 140409269750016 [Warning] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') address 'tcp://10.233.78.191:4567' points to own listening address, blacklisting
2021-06-07 2:29:53 140409269750016 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') connection established to 243b8981 tcp://10.233.72.232:4567
2021-06-07 2:29:53 140409269750016 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers:
2021-06-07 2:29:54 140409269750016 [Note] WSREP: declaring 243b8981 at tcp://10.233.72.232:4567 stable
2021-06-07 2:29:54 140409269750016 [Note] WSREP: Node 243b8981 state prim
2021-06-07 2:29:54 140409269750016 [Note] WSREP: view(view_id(PRIM,243b8981,2) memb {
243b8981,0
3de3c5f4,0
} joined {
} left {
} partitioned {
})
2021-06-07 2:29:54 140409269750016 [Note] WSREP: save pc into disk
2021-06-07 2:29:54 140409269750016 [Note] WSREP: gcomm: connected
2021-06-07 2:29:54 140409269750016 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2021-06-07 2:29:54 140409269750016 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2021-06-07 2:29:54 140409269750016 [Note] WSREP: Opened channel 'vfc-mariadb'
2021-06-07 2:29:54 140409269750016 [Note] WSREP: Waiting for SST to complete.
2021-06-07 2:29:54 140408948516608 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
2021-06-07 2:29:54 140408948516608 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2021-06-07 2:29:54 140408948516608 [Note] WSREP: STATE EXCHANGE: sent state msg: 3e32043a-c738-11eb-85fb-9a4d3dd09d9c
2021-06-07 2:29:54 140408948516608 [Note] WSREP: STATE EXCHANGE: got state msg: 3e32043a-c738-11eb-85fb-9a4d3dd09d9c from 0 (onap-vfc-mariadb-0)
2021-06-07 2:29:54 140408948516608 [Note] WSREP: STATE EXCHANGE: got state msg: 3e32043a-c738-11eb-85fb-9a4d3dd09d9c from 1 (onap-vfc-mariadb-1)
2021-06-07 2:29:54 140408948516608 [Note] WSREP: Quorum results:
version = 4,
component = PRIMARY,
conf_id = 1,
members = 1/2 (joined/total),
act_id = 0,
last_appl. = -1,
protocols = 0/7/3 (gcs/repl/appl),
group UUID = 243c5168-c738-11eb-8c7a-2a706f886582
2021-06-07 2:29:54 140408948516608 [Note] WSREP: Flow-control interval: [23, 23]
2021-06-07 2:29:54 140408948516608 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2021-06-07 2:29:54 140409269299968 [Note] WSREP: State transfer required:
Group state: 243c5168-c738-11eb-8c7a-2a706f886582:0
Local state: 00000000-0000-0000-0000-000000000000:-1
2021-06-07 2:29:54 140409269299968 [Note] WSREP: New cluster view: global state: 243c5168-c738-11eb-8c7a-2a706f886582:0, view# 2: Primary, number of nodes: 2, my index: 1, protocol version 3
2021-06-07 2:29:54 140409269299968 [Warning] WSREP: Gap in state sequence. Need state transfer.
2021-06-07 2:29:54 140408919160576 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'joiner' --address 'onap-vfc-mariadb-1.vfc-mariadb.onap.svc.cluster.local' --datadir '/var/lib/mysql/' --parent '1' '' '
2021-06-07 2:29:56 140408956909312 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') connection to peer 3de3c5f4 with addr tcp://10.233.78.191:4567 timed out, no messages seen in PT3S
2021-06-07 2:29:57 140408956909312 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') turning message relay requesting off
WSREP_SST: [INFO] Streaming with xbstream (20210607 02:29:57.646)
WSREP_SST: [INFO] Using socat as streamer (20210607 02:29:57.649)
WSREP_SST: [INFO] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210607 02:29:57.850)
2021-06-07 2:29:58 140409269299968 [Note] WSREP: Prepared SST request: xtrabackup-v2|onap-vfc-mariadb-1.vfc-mariadb.onap.svc.cluster.local:4444/xtrabackup_sst//1
2021-06-07 2:29:58 140409269299968 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-06-07 2:29:58 140409269299968 [Note] WSREP: REPL Protocols: 7 (3, 2)
2021-06-07 2:29:58 140409269299968 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2021-06-07 2:29:58 140409006823168 [Note] WSREP: Service thread queue flushed.
2021-06-07 2:29:58 140409269299968 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (243c5168-c738-11eb-8c7a-2a706f886582): 1 (Operation not permitted)
at galera/src/replicator_str.cpp:prepare_for_IST():482. IST will be unavailable.
2021-06-07 2:29:58 140408948516608 [Note] WSREP: Member 1.0 (onap-vfc-mariadb-1) requested state transfer from '*any*'. Selected 0.0 (onap-vfc-mariadb-0)(SYNCED) as donor.
2021-06-07 2:29:58 140408948516608 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2021-06-07 2:29:58 140409269299968 [Note] WSREP: Requesting state transfer: success, donor: 0
2021-06-07 2:29:58 140409269299968 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(243c5168-c738-11eb-8c7a-2a706f886582:0)
WSREP_SST: [INFO] Proceeding with SST (20210607 02:29:59.341)
WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210607 02:29:59.345)
WSREP_SST: [INFO] Cleaning the existing datadir and innodb-data/log directories (20210607 02:29:59.347)
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! (20210607 02:29:59.594)
2021-06-07 2:30:11 140408948516608 [Note] WSREP: 0.0 (onap-vfc-mariadb-0): State transfer to 1.0 (onap-vfc-mariadb-1) complete.
2021-06-07 2:30:11 140408948516608 [Note] WSREP: Member 0.0 (onap-vfc-mariadb-0) synced with group.
WSREP_SST: [INFO] Preparing the backup at /var/lib/mysql//.sst (20210607 02:30:11.211)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --apply-log $rebuildcmd ${DATA} &>${DATA}/innobackup.prepare.log (20210607 02:30:11.215)
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/ (20210607 02:30:14.833)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --move-back --force-non-empty-directories ${DATA} &>${DATA}/innobackup.move.log (20210607 02:30:14.838)
WSREP_SST: [INFO] Move successful, removing /var/lib/mysql//.sst (20210607 02:30:15.311)
WSREP_SST: [INFO] Galera co-ords from recovery: 243c5168-c738-11eb-8c7a-2a706f886582:0 (20210607 02:30:15.340)
WSREP_SST: [INFO] Total time on joiner: 0 seconds (20210607 02:30:15.350)
WSREP_SST: [INFO] Removing the sst_in_progress file (20210607 02:30:15.356)
2021-06-07 2:30:15 140409269750016 [Note] WSREP: SST complete, seqno: 0
2021-06-07 2:30:15 140409269750016 [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-06-07 2:30:15 140409269750016 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: The InnoDB memory heap is disabled
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Using Linux native AIO
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Using SSE crc32 instructions
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Completed initialization of buffer pool
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Highest supported file format is Barracuda.
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: 128 rollback segment(s) are active.
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Waiting for purge to start
2021-06-07 2:30:15 140409269750016 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1617430
2021-06-07 2:30:15 140408260654848 [Note] InnoDB: Dumping buffer pool(s) not yet started
2021-06-07 2:30:15 140409269750016 [Note] Plugin 'FEEDBACK' is disabled.
2021-06-07 2:30:15 140409269750016 [Note] Server socket created on IP: '0.0.0.0'.
2021-06-07 2:30:15 140409269750016 [Note] WSREP: Signalling provider to continue.
2021-06-07 2:30:15 140409269750016 [Note] WSREP: SST received: 243c5168-c738-11eb-8c7a-2a706f886582:0
2021-06-07 2:30:15 140408948516608 [Note] WSREP: 1.0 (onap-vfc-mariadb-1): State transfer from 0.0 (onap-vfc-mariadb-0) complete.
2021-06-07 2:30:15 140408948516608 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2021-06-07 2:30:15 140408948516608 [Note] WSREP: Member 1.0 (onap-vfc-mariadb-1) synced with group.
2021-06-07 2:30:15 140408948516608 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2021-06-07 2:30:15 140409269299968 [Note] WSREP: Synchronized with group, ready for connections
2021-06-07 2:30:15 140409269299968 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-06-07 2:30:15 140409269750016 [Note] mysqld: ready for connections.
Version: '10.1.24-MariaDB' socket: '/var/lib/mysql/mysql.sock' port: 3306 MariaDB Server
2021-06-07 2:32:59 140408956909312 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers: tcp://10.233.71.216:4567
2021-06-07 2:32:59 140408956909312 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') connection established to acc37083 tcp://10.233.71.216:4567
2021-06-07 2:33:00 140408956909312 [Note] WSREP: declaring 243b8981 at tcp://10.233.72.232:4567 stable
2021-06-07 2:33:00 140408956909312 [Note] WSREP: declaring acc37083 at tcp://10.233.71.216:4567 stable
2021-06-07 2:33:00 140408956909312 [Note] WSREP: Node 243b8981 state prim
2021-06-07 2:33:00 140408956909312 [Note] WSREP: view(view_id(PRIM,243b8981,3) memb {
243b8981,0
3de3c5f4,0
acc37083,0
} joined {
} left {
} partitioned {
})
2021-06-07 2:33:00 140408956909312 [Note] WSREP: save pc into disk
2021-06-07 2:33:00 140408948516608 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 3
2021-06-07 2:33:00 140408948516608 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
2021-06-07 2:33:00 140408948516608 [Note] WSREP: STATE EXCHANGE: sent state msg: ad145299-c738-11eb-98c4-ea33ef6a81a5
2021-06-07 2:33:00 140408948516608 [Note] WSREP: STATE EXCHANGE: got state msg: ad145299-c738-11eb-98c4-ea33ef6a81a5 from 0 (onap-vfc-mariadb-0)
2021-06-07 2:33:00 140408948516608 [Note] WSREP: STATE EXCHANGE: got state msg: ad145299-c738-11eb-98c4-ea33ef6a81a5 from 1 (onap-vfc-mariadb-1)
2021-06-07 2:33:00 140408948516608 [Note] WSREP: STATE EXCHANGE: got state msg: ad145299-c738-11eb-98c4-ea33ef6a81a5 from 2 (onap-vfc-mariadb-2)
2021-06-07 2:33:00 140408948516608 [Note] WSREP: Quorum results:
version = 4,
component = PRIMARY,
conf_id = 2,
members = 2/3 (joined/total),
act_id = 0,
last_appl. = 0,
protocols = 0/7/3 (gcs/repl/appl),
group UUID = 243c5168-c738-11eb-8c7a-2a706f886582
2021-06-07 2:33:00 140408948516608 [Note] WSREP: Flow-control interval: [28, 28]
2021-06-07 2:33:00 140409269299968 [Note] WSREP: New cluster view: global state: 243c5168-c738-11eb-8c7a-2a706f886582:0, view# 3: Primary, number of nodes: 3, my index: 1, protocol version 3
2021-06-07 2:33:00 140409269299968 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-06-07 2:33:00 140409269299968 [Note] WSREP: REPL Protocols: 7 (3, 2)
2021-06-07 2:33:00 140409269299968 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2021-06-07 2:33:00 140409006823168 [Note] WSREP: Service thread queue flushed.
2021-06-07 2:33:02 140408956909312 [Note] WSREP: (3de3c5f4, 'tcp://0.0.0.0:4567') turning message relay requesting off
2021-06-07 2:33:04 140408948516608 [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-06-07 2:33:17 140408948516608 [Note] WSREP: 0.0 (onap-vfc-mariadb-0): State transfer to 2.0 (onap-vfc-mariadb-2) complete.
2021-06-07 2:33:17 140408948516608 [Note] WSREP: Member 0.0 (onap-vfc-mariadb-0) synced with group.
2021-06-07 2:33:22 140408948516608 [Note] WSREP: 2.0 (onap-vfc-mariadb-2): State transfer from 0.0 (onap-vfc-mariadb-0) complete.
2021-06-07 2:33:22 140408948516608 [Note] WSREP: Member 2.0 (onap-vfc-mariadb-2) synced with group.
2021-06-07 2:41:55 140409151859456 [Warning] IP address '10.233.75.135' could not be resolved: Name or service not known