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
+ K8S_SVC_NAME=appc-dbhost
+ echo 'Using service name: appc-dbhost'
+ cp /usr/share/container-scripts/mysql/galera.cnf /etc/my.cnf.d/galera.cnf
Using service name: appc-dbhost
+ /usr/bin/peer-finder -on-start=/usr/share/container-scripts/mysql/configure-galera.sh -service=appc-dbhost
2021/05/20 01:52:09 Peer list updated
was []
now [onap-appc-db-0.appc-dbhost.onap.svc.cluster.local onap-appc-db-1.appc-dbhost.onap.svc.cluster.local]
2021/05/20 01:52:09 execing: /usr/share/container-scripts/mysql/configure-galera.sh with stdin: onap-appc-db-0.appc-dbhost.onap.svc.cluster.local
onap-appc-db-1.appc-dbhost.onap.svc.cluster.local
2021/05/20 01:52:09
2021/05/20 01:52:10 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
+ 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-05-20 1:52:10 139872464689408 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 50 ...
2021-05-20 1:52:11 139872464689408 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-05-20 1:52:11 139872464689408 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-05-20 1:52:11 139872464689408 [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-05-20 1:52:11 139872464689408 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: The InnoDB memory heap is disabled
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Using Linux native AIO
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Using SSE crc32 instructions
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Completed initialization of buffer pool
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: The first specified data file ./ibdata1 did not exist: a new database to be created!
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Setting file ./ibdata1 size to 12 MB
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Setting log file ./ib_logfile101 size to 48 MB
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Setting log file ./ib_logfile1 size to 48 MB
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Renaming log file ./ib_logfile101 to ./ib_logfile0
2021-05-20 1:52:11 139872464689408 [Warning] InnoDB: New log files created, LSN=45883
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Doublewrite buffer not found: creating new
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Doublewrite buffer created
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: 128 rollback segment(s) are active.
2021-05-20 1:52:11 139872464689408 [Warning] InnoDB: Creating foreign key constraint system tables.
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Foreign key constraint system tables created
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Creating tablespace and datafile system tables.
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Tablespace and datafile system tables created.
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Waiting for purge to start
2021-05-20 1:52:11 139872464689408 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 0
2021-05-20 1:52:11 139871679149824 [Note] InnoDB: Dumping buffer pool(s) not yet started
OK
Filling help tables...
2021-05-20 1:52:15 140028052990208 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 79 ...
2021-05-20 1:52:15 140028052990208 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-05-20 1:52:15 140028052990208 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-05-20 1:52:15 140028052990208 [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-05-20 1:52:15 140028052990208 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: The InnoDB memory heap is disabled
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Using Linux native AIO
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Using SSE crc32 instructions
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Completed initialization of buffer pool
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Highest supported file format is Barracuda.
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: 128 rollback segment(s) are active.
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Waiting for purge to start
2021-05-20 1:52:15 140028052990208 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616799
2021-05-20 1:52:15 140027271051008 [Note] InnoDB: Dumping buffer pool(s) not yet started
OK
Creating OpenGIS required SP-s...
2021-05-20 1:52:19 140687401711872 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB) starting as process 109 ...
2021-05-20 1:52:19 140687401711872 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-05-20 1:52:19 140687401711872 [Note] WSREP: wsrep_load(): loading provider library 'none'
2021-05-20 1:52:19 140687401711872 [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-05-20 1:52:20 140687401711872 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: The InnoDB memory heap is disabled
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Using Linux native AIO
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Using SSE crc32 instructions
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Completed initialization of buffer pool
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Highest supported file format is Barracuda.
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: 128 rollback segment(s) are active.
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Waiting for purge to start
2021-05-20 1:52:20 140687401711872 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616809
2021-05-20 1:52:20 140686619834112 [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-appc-db-1 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
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
2021-05-20 1:52:23 140486818818304 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 136 ...
2021-05-20 1:52:23 140486818818304 [Warning] You need to use --log-bin to make --binlog-format work.
2021-05-20 1:52:24 140486818818304 [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-05-20 1:52:24 140486818818304 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: The InnoDB memory heap is disabled
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Using Linux native AIO
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Using SSE crc32 instructions
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Completed initialization of buffer pool
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Highest supported file format is Barracuda.
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: 128 rollback segment(s) are active.
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Waiting for purge to start
2021-05-20 1:52:24 140486818818304 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1616819
2021-05-20 1:52:24 140486039828224 [Note] InnoDB: Dumping buffer pool(s) not yet started
2021-05-20 1:52:24 140486818818304 [Note] Plugin 'FEEDBACK' is disabled.
2021-05-20 1:52:24 140486818818304 [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 'Pedo3^LuyeRaro'
++ echo 'Pedo3^LuyeRaro'
++ sed -e 's/'\''/\\'\''/g; s/"/\\"/g'
+ mysql_root_password='Pedo3^LuyeRaro'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock
+ '[' '!' -z 'Pedo3^LuyeRaro' ']'
+ mysql+=(-p"${MYSQL_ROOT_PASSWORD}")
+ mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock '-pPedo3^LuyeRaro'
+ '[' '' ']'
+ '[' my-user -a PesiBiqa5#Puji ']'
++ prepare_password PesiBiqa5#Puji
++ echo PesiBiqa5#Puji
++ sed -e 's/'\''/\\'\''/g; s/"/\\"/g'
+ mysql_password=PesiBiqa5#Puji
+ echo 'CREATE USER '\''my-user'\''@'\''%'\'' IDENTIFIED BY '\''PesiBiqa5#Puji'\'' ;'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock '-pPedo3^LuyeRaro'
+ '[' '' ']'
+ echo 'FLUSH PRIVILEGES ;'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/var/lib/mysql/mysql-init.sock '-pPedo3^LuyeRaro'
+ kill -s TERM 136
+ wait 136
2021-05-20 1:52:28 140486817966848 [Note] mysqld: Normal shutdown
2021-05-20 1:52:28 140486817966848 [Note] Event Scheduler: Purging the queue. 0 events
2021-05-20 1:52:28 140486023042816 [Note] InnoDB: FTS optimize thread exiting.
2021-05-20 1:52:28 140486817966848 [Note] InnoDB: Starting shutdown...
2021-05-20 1:52:29 140486817966848 [Note] InnoDB: Waiting for page_cleaner to finish flushing of buffer pool
2021-05-20 1:52:31 140486817966848 [Note] InnoDB: Shutdown completed; log sequence number 1616829
2021-05-20 1:52:31 140486817966848 [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-05-20 1:52:31 140692008409344 [Note] mysqld (mysqld 10.1.24-MariaDB) starting as process 1 ...
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Read nil XID from storage engines, skipping position init
2021-05-20 1:52:31 140692008409344 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
2021-05-20 1:52:31 140692008409344 [Note] WSREP: wsrep_load(): Galera 25.3.20(r3703) by Codership Oy loaded successfully.
2021-05-20 1:52:31 140692008409344 [Note] WSREP: CRC-32C: using hardware acceleration.
2021-05-20 1:52:31 140692008409344 [Warning] WSREP: Could not open state file for reading: '/var/lib/mysql//grastate.dat'
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootsrap: 1
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = onap-appc-db-1.appc-dbhost.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_time
2021-05-20 1:52:31 140692008409344 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(00000000-0000-0000-0000-000000000000:-1)
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2021-05-20 1:52:31 140692008409344 [Note] WSREP: wsrep_sst_grab()
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Start replication
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2021-05-20 1:52:31 140692008409344 [Note] WSREP: protonet asio version 0
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Using CRC-32C for message checksums.
2021-05-20 1:52:31 140692008409344 [Note] WSREP: backend: asio
2021-05-20 1:52:31 140692008409344 [Note] WSREP: gcomm thread scheduling priority set to other:0
2021-05-20 1:52:31 140692008409344 [Warning] WSREP: access file(/var/lib/mysql//gvwstate.dat) failed(No such file or directory)
2021-05-20 1:52:31 140692008409344 [Note] WSREP: restore pc from disk failed
2021-05-20 1:52:31 140692008409344 [Note] WSREP: GMCast version 0
2021-05-20 1:52:31 140692008409344 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
2021-05-20 1:52:31 140692008409344 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
2021-05-20 1:52:31 140692008409344 [Note] WSREP: EVS version 0
2021-05-20 1:52:31 140692008409344 [Note] WSREP: gcomm: connecting to group 'appc-dbhost', peer 'onap-appc-db-0.appc-dbhost.onap.svc.cluster.local:,onap-appc-db-1.appc-dbhost.onap.svc.cluster.local:'
2021-05-20 1:52:31 140692008409344 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') connection established to 0a1d8d05 tcp://10.233.71.60:4567
2021-05-20 1:52:31 140692008409344 [Warning] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') address 'tcp://10.233.71.60:4567' points to own listening address, blacklisting
2021-05-20 1:52:31 140692008409344 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') connection established to 96ab84c3 tcp://10.233.78.209:4567
2021-05-20 1:52:31 140692008409344 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers:
2021-05-20 1:52:31 140692008409344 [Note] WSREP: declaring 96ab84c3 at tcp://10.233.78.209:4567 stable
2021-05-20 1:52:31 140692008409344 [Note] WSREP: Node 96ab84c3 state prim
2021-05-20 1:52:31 140692008409344 [Note] WSREP: view(view_id(PRIM,0a1d8d05,2) memb {
0a1d8d05,0
96ab84c3,0
} joined {
} left {
} partitioned {
})
2021-05-20 1:52:31 140692008409344 [Note] WSREP: save pc into disk
2021-05-20 1:52:32 140692008409344 [Note] WSREP: gcomm: connected
2021-05-20 1:52:32 140692008409344 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
2021-05-20 1:52:32 140692008409344 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2021-05-20 1:52:32 140692008409344 [Note] WSREP: Opened channel 'appc-dbhost'
2021-05-20 1:52:32 140692008409344 [Note] WSREP: Waiting for SST to complete.
2021-05-20 1:52:32 140691686549248 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
2021-05-20 1:52:32 140691686549248 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 0a6a8ff2-b90e-11eb-8b2e-1bda88269cee
2021-05-20 1:52:32 140691686549248 [Note] WSREP: STATE EXCHANGE: sent state msg: 0a6a8ff2-b90e-11eb-8b2e-1bda88269cee
2021-05-20 1:52:32 140691686549248 [Note] WSREP: STATE EXCHANGE: got state msg: 0a6a8ff2-b90e-11eb-8b2e-1bda88269cee from 0 (onap-appc-db-1)
2021-05-20 1:52:32 140691686549248 [Note] WSREP: STATE EXCHANGE: got state msg: 0a6a8ff2-b90e-11eb-8b2e-1bda88269cee from 1 (onap-appc-db-0)
2021-05-20 1:52:32 140691686549248 [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 = 96ac1682-b90d-11eb-8122-0bf6a5fceee5
2021-05-20 1:52:32 140691686549248 [Note] WSREP: Flow-control interval: [23, 23]
2021-05-20 1:52:32 140691686549248 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 0)
2021-05-20 1:52:32 140692007959296 [Note] WSREP: State transfer required:
Group state: 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0
Local state: 00000000-0000-0000-0000-000000000000:-1
2021-05-20 1:52:32 140692007959296 [Note] WSREP: New cluster view: global state: 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0, view# 2: Primary, number of nodes: 2, my index: 0, protocol version 3
2021-05-20 1:52:32 140692007959296 [Warning] WSREP: Gap in state sequence. Need state transfer.
2021-05-20 1:52:32 140691657193216 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'joiner' --address 'onap-appc-db-1.appc-dbhost.onap.svc.cluster.local' --datadir '/var/lib/mysql/' --parent '1' '' '
2021-05-20 1:52:34 140691694941952 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') connection to peer 0a1d8d05 with addr tcp://10.233.71.60:4567 timed out, no messages seen in PT3S
WSREP_SST: [INFO] Streaming with xbstream (20210520 01:52:34.632)
WSREP_SST: [INFO] Using socat as streamer (20210520 01:52:34.634)
WSREP_SST: [INFO] Evaluating timeout -k 110 100 socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210520 01:52:34.736)
2021-05-20 1:52:35 140691694941952 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') turning message relay requesting off
2021-05-20 1:52:35 140692007959296 [Note] WSREP: Prepared SST request: xtrabackup-v2|onap-appc-db-1.appc-dbhost.onap.svc.cluster.local:4444/xtrabackup_sst//1
2021-05-20 1:52:35 140692007959296 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-05-20 1:52:35 140692007959296 [Note] WSREP: REPL Protocols: 7 (3, 2)
2021-05-20 1:52:35 140692007959296 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2021-05-20 1:52:35 140691744855808 [Note] WSREP: Service thread queue flushed.
2021-05-20 1:52:35 140692007959296 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state UUID (00000000-0000-0000-0000-000000000000) does not match group state UUID (96ac1682-b90d-11eb-8122-0bf6a5fceee5): 1 (Operation not permitted)
at galera/src/replicator_str.cpp:prepare_for_IST():482. IST will be unavailable.
2021-05-20 1:52:35 140691686549248 [Note] WSREP: Member 0.0 (onap-appc-db-1) requested state transfer from '*any*'. Selected 1.0 (onap-appc-db-0)(SYNCED) as donor.
2021-05-20 1:52:35 140691686549248 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 0)
2021-05-20 1:52:35 140692007959296 [Note] WSREP: Requesting state transfer: success, donor: 1
2021-05-20 1:52:35 140692007959296 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0) -> new(96ac1682-b90d-11eb-8122-0bf6a5fceee5:0)
WSREP_SST: [INFO] Proceeding with SST (20210520 01:52:36.435)
WSREP_SST: [INFO] Evaluating socat -u TCP-LISTEN:4444,reuseaddr stdio | xbstream -x; RC=( ${PIPESTATUS[@]} ) (20210520 01:52:36.437)
WSREP_SST: [INFO] Cleaning the existing datadir and innodb-data/log directories (20210520 01:52:36.438)
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! (20210520 01:52:36.568)
WSREP_SST: [INFO] Preparing the backup at /var/lib/mysql//.sst (20210520 01:52:48.053)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --apply-log $rebuildcmd ${DATA} &>${DATA}/innobackup.prepare.log (20210520 01:52:48.056)
2021-05-20 1:52:48 140691686549248 [Note] WSREP: 1.0 (onap-appc-db-0): State transfer to 0.0 (onap-appc-db-1) complete.
2021-05-20 1:52:48 140691686549248 [Note] WSREP: Member 1.0 (onap-appc-db-0) synced with group.
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/ (20210520 01:52:51.432)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check --move-back --force-non-empty-directories ${DATA} &>${DATA}/innobackup.move.log (20210520 01:52:51.436)
WSREP_SST: [INFO] Move successful, removing /var/lib/mysql//.sst (20210520 01:52:51.688)
WSREP_SST: [INFO] Galera co-ords from recovery: 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0 (20210520 01:52:51.704)
WSREP_SST: [INFO] Total time on joiner: 0 seconds (20210520 01:52:51.709)
WSREP_SST: [INFO] Removing the sst_in_progress file (20210520 01:52:51.712)
2021-05-20 1:52:51 140692008409344 [Note] WSREP: SST complete, seqno: 0
2021-05-20 1:52:51 140692008409344 [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-05-20 1:52:51 140692008409344 [Note] InnoDB: Using mutexes to ref count buffer pool pages
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: The InnoDB memory heap is disabled
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Compressed tables use zlib 1.2.7
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Using Linux native AIO
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Using SSE crc32 instructions
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Initializing buffer pool, size = 128.0M
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Completed initialization of buffer pool
2021-05-20 1:52:51 140692008409344 [Note] InnoDB: Highest supported file format is Barracuda.
2021-05-20 1:52:52 140692008409344 [Note] InnoDB: 128 rollback segment(s) are active.
2021-05-20 1:52:52 140692008409344 [Note] InnoDB: Waiting for purge to start
2021-05-20 1:52:52 140692008409344 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.0 started; log sequence number 1617430
2021-05-20 1:52:52 140690998687488 [Note] InnoDB: Dumping buffer pool(s) not yet started
2021-05-20 1:52:52 140692008409344 [Note] Plugin 'FEEDBACK' is disabled.
2021-05-20 1:52:52 140692008409344 [Note] Server socket created on IP: '0.0.0.0'.
2021-05-20 1:52:52 140692008409344 [Note] WSREP: Signalling provider to continue.
2021-05-20 1:52:52 140692008409344 [Note] WSREP: SST received: 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0
2021-05-20 1:52:52 140691686549248 [Note] WSREP: 0.0 (onap-appc-db-1): State transfer from 1.0 (onap-appc-db-0) complete.
2021-05-20 1:52:52 140691686549248 [Note] WSREP: Shifting JOINER -> JOINED (TO: 0)
2021-05-20 1:52:52 140691686549248 [Note] WSREP: Member 0.0 (onap-appc-db-1) synced with group.
2021-05-20 1:52:52 140691686549248 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2021-05-20 1:52:52 140692007959296 [Note] WSREP: Synchronized with group, ready for connections
2021-05-20 1:52:52 140692007959296 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-05-20 1:52:52 140692008409344 [Note] mysqld: ready for connections.
Version: '10.1.24-MariaDB' socket: '/var/lib/mysql/mysql.sock' port: 3306 MariaDB Server
2021-05-20 1:54:24 140691694941952 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') connection established to 4db6dda1 tcp://10.233.76.119:4567
2021-05-20 1:54:24 140691694941952 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') turning message relay requesting on, nonlive peers:
2021-05-20 1:54:25 140691694941952 [Note] WSREP: declaring 4db6dda1 at tcp://10.233.76.119:4567 stable
2021-05-20 1:54:25 140691694941952 [Note] WSREP: declaring 96ab84c3 at tcp://10.233.78.209:4567 stable
2021-05-20 1:54:25 140691694941952 [Note] WSREP: Node 0a1d8d05 state prim
2021-05-20 1:54:25 140691694941952 [Note] WSREP: view(view_id(PRIM,0a1d8d05,3) memb {
0a1d8d05,0
4db6dda1,0
96ab84c3,0
} joined {
} left {
} partitioned {
})
2021-05-20 1:54:25 140691694941952 [Note] WSREP: save pc into disk
2021-05-20 1:54:25 140691686549248 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 3
2021-05-20 1:54:25 140691686549248 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 4e10bcb0-b90e-11eb-9fed-22f62f881593
2021-05-20 1:54:25 140691686549248 [Note] WSREP: STATE EXCHANGE: sent state msg: 4e10bcb0-b90e-11eb-9fed-22f62f881593
2021-05-20 1:54:25 140691686549248 [Note] WSREP: STATE EXCHANGE: got state msg: 4e10bcb0-b90e-11eb-9fed-22f62f881593 from 0 (onap-appc-db-1)
2021-05-20 1:54:25 140691686549248 [Note] WSREP: STATE EXCHANGE: got state msg: 4e10bcb0-b90e-11eb-9fed-22f62f881593 from 2 (onap-appc-db-0)
2021-05-20 1:54:25 140691686549248 [Note] WSREP: STATE EXCHANGE: got state msg: 4e10bcb0-b90e-11eb-9fed-22f62f881593 from 1 (onap-appc-db-2)
2021-05-20 1:54:25 140691686549248 [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 = 96ac1682-b90d-11eb-8122-0bf6a5fceee5
2021-05-20 1:54:25 140691686549248 [Note] WSREP: Flow-control interval: [28, 28]
2021-05-20 1:54:25 140692007959296 [Note] WSREP: New cluster view: global state: 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0, view# 3: Primary, number of nodes: 3, my index: 0, protocol version 3
2021-05-20 1:54:25 140692007959296 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-05-20 1:54:25 140692007959296 [Note] WSREP: REPL Protocols: 7 (3, 2)
2021-05-20 1:54:25 140692007959296 [Note] WSREP: Assign initial position for certification: 0, protocol version: 3
2021-05-20 1:54:25 140691744855808 [Note] WSREP: Service thread queue flushed.
2021-05-20 1:54:28 140691694941952 [Note] WSREP: (0a1d8d05, 'tcp://0.0.0.0:4567') turning message relay requesting off
2021-05-20 1:54:29 140691686549248 [Note] WSREP: Member 1.0 (onap-appc-db-2) requested state transfer from '*any*'. Selected 0.0 (onap-appc-db-1)(SYNCED) as donor.
2021-05-20 1:54:29 140691686549248 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 0)
2021-05-20 1:54:29 140692007959296 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2021-05-20 1:54:29 140690927384320 [Note] WSREP: Running: 'wsrep_sst_xtrabackup-v2 --role 'donor' --address 'onap-appc-db-2.appc-dbhost.onap.svc.cluster.local:4444/xtrabackup_sst//1' --socket '/var/lib/mysql/mysql.sock' --datadir '/var/lib/mysql/' '' --gtid '96ac1682-b90d-11eb-8122-0bf6a5fceee5:0' --gtid-domain-id '0''
2021-05-20 1:54:29 140692007959296 [Note] WSREP: sst_donor_thread signaled with 0
WSREP_SST: [INFO] Streaming with xbstream (20210520 01:54:30.293)
WSREP_SST: [INFO] Using socat as streamer (20210520 01:54:30.295)
WSREP_SST: [INFO] Using /tmp/tmp.4C32PFX8lW as xtrabackup temporary directory (20210520 01:54:30.344)
WSREP_SST: [INFO] Using /tmp/tmp.VRE50HRgDr as innobackupex temporary directory (20210520 01:54:30.348)
WSREP_SST: [INFO] Streaming GTID file before SST (20210520 01:54:30.354)
WSREP_SST: [INFO] Evaluating xbstream -c ${INFO_FILE} | socat -u stdio TCP:onap-appc-db-2.appc-dbhost.onap.svc.cluster.local:4444; RC=( ${PIPESTATUS[@]} ) (20210520 01:54:30.360)
WSREP_SST: [INFO] Sleeping before data transfer for SST (20210520 01:54:30.441)
WSREP_SST: [INFO] Streaming the backup to joiner at onap-appc-db-2.appc-dbhost.onap.svc.cluster.local 4444 (20210520 01:54:40.446)
WSREP_SST: [INFO] Evaluating innobackupex --no-version-check $tmpopts $INNOEXTRA --galera-info --stream=$sfmt $itmpdir 2>${DATA}/innobackup.backup.log | socat -u stdio TCP:onap-appc-db-2.appc-dbhost.onap.svc.cluster.local:4444; RC=( ${PIPESTATUS[@]} ) (20210520 01:54:40.536)
2021-05-20 1:54:41 140691930745600 [Note] WSREP: Provider paused at 96ac1682-b90d-11eb-8122-0bf6a5fceee5:0 (7)
2021-05-20 1:54:42 140691930745600 [Note] WSREP: resuming provider at 7
2021-05-20 1:54:42 140691930745600 [Note] WSREP: Provider resumed.
2021-05-20 1:54:42 140691686549248 [Note] WSREP: 0.0 (onap-appc-db-1): State transfer to 1.0 (onap-appc-db-2) complete.
2021-05-20 1:54:42 140691686549248 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 0)
2021-05-20 1:54:42 140691686549248 [Note] WSREP: Member 0.0 (onap-appc-db-1) synced with group.
2021-05-20 1:54:42 140691686549248 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2021-05-20 1:54:42 140692007959296 [Note] WSREP: Synchronized with group, ready for connections
2021-05-20 1:54:42 140692007959296 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
WSREP_SST: [INFO] Total time on donor: 0 seconds (20210520 01:54:42.433)
WSREP_SST: [INFO] Cleaning up temporary directories (20210520 01:54:42.439)
2021-05-20 1:54:47 140691686549248 [Note] WSREP: 1.0 (onap-appc-db-2): State transfer from 0.0 (onap-appc-db-1) complete.
2021-05-20 1:54:47 140691686549248 [Note] WSREP: Member 1.0 (onap-appc-db-2) synced with group.