docker pxc 5.7.26 start failed
General
Escalation
General
Escalation
Description
Environment
None
Activity
Kamil Holubicki March 1, 2023 at 8:32 AM
Kamil Holubicki
March 1, 2023 at 8:32 AM
Works fine on 5.7.40 as well.
David Bennett January 28, 2020 at 5:28 AM
David Bennett
January 28, 2020 at 5:28 AM
I cannot reproduce with 5.7.28
Lalit Choudhary August 27, 2019 at 8:05 AM
Lalit Choudhary
August 27, 2019 at 8:05 AM
Thank you for the test, @vue boot
Validated as described.
reproduciable command:
#docker run -d -p 23306:3306 --name pn1 -e MYSQL_ROOT_PASSWORD=abc123456 -e CLUSTER_NAME=pxc -e XTRABACKUP_PASSWORD=abc123456 percona/percona-xtradb-cluster:5.7.26
issue not reproduciable with previous pxc versions, tested with 5.7.23 and 5.7.25 in my test which works fine.
#docker run -d -p 23306:3306 --name pn1 -e MYSQL_ROOT_PASSWORD=abc123456 -e CLUSTER_NAME=pxc -e XTRABACKUP_PASSWORD=abc123456 percona/percona-xtradb-cluster:5.7.25
docker --version
Docker version 18.09.5, build e8ff056
vue boot August 27, 2019 at 12:57 AM
vue boot
August 27, 2019 at 12:57 AM
Same as before
//代码占位符
[root@i-1a50owm0 ~]# docker run -d -p 23306:3306 --name pn1 -e MYSQL_ROOT_PASSWORD=abc123456 -e CLUSTER_NAME=pxc -e XTRABACKUP_PASSWORD=abc123456 percona/percona-xtradb-cluster:latest
Unable to find image 'percona/percona-xtradb-cluster:latest' locally
latest: Pulling from percona/percona-xtradb-cluster
d8d02d457314: Pull complete
7120a9be1b8e: Pull complete
794786a5c82c: Pull complete
d3cfc742d37c: Pull complete
e5abcc5d348f: Pull complete
7a047f4883af: Pull complete
48eb3f2d7a6f: Pull complete
38c6a1e9a47e: Pull complete
731a4112e1a7: Pull complete
84b8c29bf799: Pull complete
52c70c375579: Pull complete
Digest: sha256:89766545d131a3932fb2ad96a70859362241bb216d5d045b2cc507b62174eb5f
Status: Downloaded newer image for percona/percona-xtradb-cluster:latest
8721eda5b8387ed21e29d34287b6fd65859359c583a6c7495d4f5c1c013a4d42
[root@i-1a50owm0 ~]# docker logs -f pn1
+ '[' m = - ']'
+ CFG=/etc/mysql/node.cnf
+ wantHelp=
+ for arg in '"$@"'
+ case "$arg" in
+ file_env XTRABACKUP_PASSWORD xtrabackup
+ set +o xtrace
+ file_env CLUSTERCHECK_PASSWORD clustercheck
+ set +o xtrace
+ '[' -n '' ']'
+ '[' -n '' ']'
+ : checking incoming cluster parameters
++ hostname -I
++ awk ' { print $1 } '
+ NODE_IP=172.17.0.3
+ sed -r 's|^[#]?wsrep_node_address=.*$|wsrep_node_address=172.17.0.3|' /etc/mysql/node.cnf
+ sed -r 's|^[#]?wsrep_sst_auth=.*$|wsrep_sst_auth='\''xtrabackup:abc123456'\''|' /etc/mysql/node.cnf
+ [[ -n '' ]]
+ [[ -n pxc ]]
+ sed -r 's|^[#]?wsrep_cluster_name=.*$|wsrep_cluster_name=pxc|' /etc/mysql/node.cnf
+ '[' -z '' ']'
+ '[' mysqld = mysqld -a -z '' ']'
+ _check_config mysqld
+ toRun=("$@" --verbose --help --wsrep-provider='none')
++ mysqld --verbose --help --wsrep-provider=none
+ errors=
++ _get_config datadir mysqld
++ local conf=datadir
++ shift
++ awk '$1 == "datadir" && /^[^ \t]/ { sub(/^[^ \t]+[ \t]+/, ""); print; exit }'
+++ mktemp -u
++ mysqld --verbose --help --wsrep-provider=none --log-bin-index=/tmp/tmp.RWjHNNS5oi
+ DATADIR=/var/lib/mysql/
+ '[' '!' -d /var/lib/mysql//mysql ']'
+ file_env MYSQL_ROOT_PASSWORD
+ set +o xtrace
+ '[' -z abc123456 -a -z '' -a -z '' ']'
+ rm -rf
+ mkdir -p /var/lib/mysql/
+ echo 'Initializing database'
Initializing database
+ mysqld --initialize-insecure --skip-ssl
2019-08-27T00:54:45.437018Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2019-08-27T00:54:45.437235Z 0 [Warning] WSREP: Node is running in bootstrap/initialize mode. Disabling pxc_strict_mode checks
2019-08-27T00:54:46.897884Z 0 [Warning] InnoDB: New log files created, LSN=45790
2019-08-27T00:54:47.039960Z 0 [Warning] InnoDB: Creating foreign key constraint system tables.
2019-08-27T00:54:47.095131Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: 441468a9-c865-11e9-9be8-0242ac110003.
2019-08-27T00:54:47.099222Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened.
2019-08-27T00:54:47.299836Z 1 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option.
+ echo 'Database initialized'
Database initialized
++ _get_config socket mysqld
++ local conf=socket
++ shift
++ awk '$1 == "socket" && /^[^ \t]/ { sub(/^[^ \t]+[ \t]+/, ""); print; exit }'
+++ mktemp -u
++ mysqld --verbose --help --wsrep-provider=none --log-bin-index=/tmp/tmp.t2JuHU66yU
+ SOCKET=/tmp/mysql.sock
+ pid=57
+ mysql=(mysql --protocol=socket -uroot -hlocalhost --socket="${SOCKET}" --password="")
+ for i in '{120..0}'
+ mysqld --skip-networking --socket=/tmp/mysql.sock
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
2019-08-27T00:54:55.454302Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details).
2019-08-27T00:54:55.457038Z 0 [Note] mysqld (mysqld 5.7.26-29-57) starting as process 57 ...
2019-08-27T00:54:55.459931Z 0 [Note] WSREP: Setting wsrep_ready to false
2019-08-27T00:54:55.460116Z 0 [Note] WSREP: No pre-stored wsrep-start position found. Skipping position initialization.
2019-08-27T00:54:55.460203Z 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera3/libgalera_smm.so'
2019-08-27T00:54:55.467439Z 0 [Note] WSREP: wsrep_load(): Galera 3.37(rff05089) by Codership Oy <info@codership.com> loaded successfully.
2019-08-27T00:54:55.468046Z 0 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
2019-08-27T00:54:55.469917Z 0 [Warning] WSREP: Could not open state file for reading: '/var/lib/mysql//grastate.dat'
2019-08-27T00:54:55.470053Z 0 [Warning] WSREP: No persistent state found. Bootstraping with default state
2019-08-27T00:54:55.470185Z 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1
2019-08-27T00:54:55.476734Z 0 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = 172.17.0.3; base_port = 4567; cert.log_conflicts = no; cert.optimistic_pa = yes; 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 = 10; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 4; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/mysql/; gcache.freeze_purge_at_seqno = -1; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 128M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1; gcs.fc_limit = 100; gcs.fc_master_slave = no; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = false; pc.recovery = true; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = PT30S; pc.weight = 1; protonet.backend = asio; protonet.version = 0; repl.causal_read_timeout = PT30S; repl.commit_order = 3; repl.key_format = FLAT8; repl.max_ws_size = 2147483647; repl.proto_max = 9; socket.checksum = 2; socket.recv_buf_size = 212992;
2019-08-27T00:54:55.500968Z 0 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1
2019-08-27T00:54:55.503702Z 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
2019-08-27T00:54:55.503790Z 0 [Note] WSREP: Preparing to initiate SST/IST
2019-08-27T00:54:55.503798Z 0 [Note] WSREP: Starting replication
2019-08-27T00:54:55.503815Z 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
2019-08-27T00:54:55.504369Z 0 [Note] WSREP: Using CRC-32C for message checksums.
2019-08-27T00:54:55.504707Z 0 [Note] WSREP: gcomm thread scheduling priority set to other:0
2019-08-27T00:54:55.505056Z 0 [Warning] WSREP: Fail to access the file (/var/lib/mysql//gvwstate.dat) error (No such file or directory). It is possible if node is booting for first time or re-booting after a graceful shutdown
2019-08-27T00:54:55.505077Z 0 [Note] WSREP: Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown
2019-08-27T00:54:55.505727Z 0 [Note] WSREP: GMCast version 0
2019-08-27T00:54:55.505907Z 0 [Note] WSREP: (4917ad39, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567
2019-08-27T00:54:55.505925Z 0 [Note] WSREP: (4917ad39, 'tcp://0.0.0.0:4567') multicast: , ttl: 1
2019-08-27T00:54:55.506772Z 0 [Note] WSREP: EVS version 0
2019-08-27T00:54:55.506941Z 0 [Note] WSREP: gcomm: connecting to group 'pxc', peer ''
2019-08-27T00:54:55.507010Z 0 [Note] WSREP: start_prim is enabled, turn off pc_recovery
2019-08-27T00:54:55.507396Z 0 [Note] WSREP: Node 4917ad39 state primary
2019-08-27T00:54:55.507456Z 0 [Note] WSREP: Current view of cluster as seen by this node
view (view_id(PRIM,4917ad39,1)
memb {
4917ad39,0
}
joined {
}
left {
}
partitioned {
}
)
2019-08-27T00:54:55.507504Z 0 [Note] WSREP: Save the discovered primary-component to disk
2019-08-27T00:54:55.511607Z 0 [Note] WSREP: gcomm: connected
2019-08-27T00:54:55.511738Z 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
2019-08-27T00:54:55.512091Z 0 [Note] WSREP: Waiting for SST/IST to complete.
2019-08-27T00:54:55.512620Z 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
2019-08-27T00:54:55.513405Z 0 [Note] WSREP: Starting new group from scratch: 4918e27a-c865-11e9-80ea-92c8a74b00bb
2019-08-27T00:54:55.513862Z 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 4918f108-c865-11e9-8699-97070470ebad
2019-08-27T00:54:55.513893Z 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 4918f108-c865-11e9-8699-97070470ebad
2019-08-27T00:54:55.513922Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: 4918f108-c865-11e9-8699-97070470ebad from 0 (8721eda5b838)
2019-08-27T00:54:55.513933Z 0 [Note] WSREP: Quorum results:
version = 4,
component = PRIMARY,
conf_id = 0,
members = 1/1 (primary/total),
act_id = 0,
last_appl. = -1,
protocols = 0/9/3 (gcs/repl/appl),
group UUID = 4918e27a-c865-11e9-80ea-92c8a74b00bb
2019-08-27T00:54:55.513942Z 0 [Note] WSREP: Flow-control interval: [100, 100]
2019-08-27T00:54:55.513946Z 0 [Note] WSREP: Trying to continue unpaused monitor
2019-08-27T00:54:55.513951Z 0 [Note] WSREP: Restored state OPEN -> JOINED (0)
2019-08-27T00:54:55.514002Z 0 [Note] WSREP: Member 0.0 (8721eda5b838) synced with group.
2019-08-27T00:54:55.514009Z 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
2019-08-27T00:54:55.514560Z 2 [Note] WSREP: REPL Protocols: 9 (4, 2)
2019-08-27T00:54:55.514604Z 2 [Note] WSREP: New cluster view: global state: 4918e27a-c865-11e9-80ea-92c8a74b00bb:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3
2019-08-27T00:54:55.514614Z 2 [Note] WSREP: Setting wsrep_ready to true
2019-08-27T00:54:55.514661Z 0 [Note] WSREP: SST complete, seqno: 0
2019-08-27T00:54:55.516591Z 0 [Note] InnoDB: PUNCH HOLE support available
2019-08-27T00:54:55.516621Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
2019-08-27T00:54:55.516628Z 0 [Note] InnoDB: Uses event mutexes
2019-08-27T00:54:55.516653Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
2019-08-27T00:54:55.516678Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.7
2019-08-27T00:54:55.516685Z 0 [Note] InnoDB: Using Linux native AIO
2019-08-27T00:54:55.517120Z 0 [Note] InnoDB: Number of pools: 1
2019-08-27T00:54:55.517310Z 0 [Note] InnoDB: Not using CPU crc32 instructions
2019-08-27T00:54:55.520275Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
2019-08-27T00:54:55.526374Z 0 [Note] InnoDB: Completed initialization of buffer pool
2019-08-27T00:54:55.530043Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
2019-08-27T00:54:55.543112Z 0 [Note] InnoDB: Crash recovery did not find the parallel doublewrite buffer at /var/lib/mysql/xb_doublewrite
2019-08-27T00:54:55.544605Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
2019-08-27T00:54:55.587110Z 0 [Note] InnoDB: Created parallel doublewrite buffer at /var/lib/mysql/xb_doublewrite, size 3932160 bytes
2019-08-27T00:54:55.617764Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
2019-08-27T00:54:55.617860Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2019-08-27T00:54:55.683574Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
2019-08-27T00:54:55.685007Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
2019-08-27T00:54:55.685030Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
2019-08-27T00:54:55.685488Z 0 [Note] InnoDB: Waiting for purge to start
2019-08-27T00:54:55.736045Z 0 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.7.26-29 started; log sequence number 2530701
2019-08-27T00:54:55.736451Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
2019-08-27T00:54:55.736663Z 0 [Note] Plugin 'FEDERATED' is disabled.
2019-08-27T00:54:55.752989Z 0 [Note] InnoDB: Buffer pool(s) load completed at 190827 0:54:55
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
MySQL init process in progress...
+ sleep 1
2019-08-27T00:54:56.246565Z 0 [Note] Auto generated SSL certificates are placed in data directory.
2019-08-27T00:54:56.248237Z 0 [Warning] CA certificate ca.pem is self signed.
2019-08-27T00:54:56.248521Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory.
2019-08-27T00:54:56.274129Z 0 [Note] Event Scheduler: Loaded 0 events
2019-08-27T00:54:56.274862Z 0 [Note] mysqld: ready for connections.
Version: '5.7.26-29-57' socket: '/tmp/mysql.sock' port: 0 Percona XtraDB Cluster (GPL), Release rel29, Revision 03540a3, WSREP version 31.37, wsrep_31.37
2019-08-27T00:54:56.278799Z 2 [Note] WSREP: Initialized wsrep sidno 2
2019-08-27T00:54:56.278928Z 2 [Note] WSREP: Auto Increment Offset/Increment re-align with cluster membership change (Offset: 1 -> 1) (Increment: 1 -> 1)
2019-08-27T00:54:56.279039Z 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
2019-08-27T00:54:56.279176Z 2 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4
2019-08-27T00:54:56.279307Z 0 [Note] WSREP: Service thread queue flushed.
2019-08-27T00:54:56.282565Z 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 4918e27a-c865-11e9-80ea-92c8a74b00bb:0
2019-08-27T00:54:56.287489Z 2 [Note] WSREP: Synchronized with group, ready for connections
2019-08-27T00:54:56.287594Z 2 [Note] WSREP: Setting wsrep_ready to true
2019-08-27T00:54:56.287622Z 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'SELECT 1'
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
MySQL init process in progress...
+ echo 'MySQL init process in progress...'
+ sleep 1
+ for i in '{120..0}'
+ echo 'SELECT 1'
+ mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password=
+ echo 'MySQL init process in progress...'
+ sleep 1
MySQL init process in progress...
+ for i in '{120..0}'
+ echo 'SELECT 1'Lalit Choudhary August 26, 2019 at 2:25 PM
Lalit Choudhary
August 26, 2019 at 2:25 PM
could you please use percona/percona-xtradb-cluster:latest tag
Done
Details
Details
Assignee
Unassigned
UnassignedReporter
vue boot
vue bootLabels
Components
Affects versions
Priority
HighCreated August 21, 2019 at 6:32 AM
Updated March 6, 2024 at 10:07 PM
Resolved March 1, 2023 at 8:32 AM
version 5.7.21 is ok, but 5.7.26 can't start
//代码占位符 + '[' m = - ']' + CFG=/etc/mysql/node.cnf + wantHelp= + for arg in '"$@"' + case "$arg" in + file_env XTRABACKUP_PASSWORD xtrabackup + set +o xtrace + file_env CLUSTERCHECK_PASSWORD clustercheck + set +o xtrace + '[' -n '' ']' + '[' -n '' ']' + : checking incoming cluster parameters ++ hostname -i ++ awk ' { print $1 } ' + NODE_IP=10.0.0.10 + sed -r 's|^[#]?wsrep_node_address=.*$|wsrep_node_address=10.0.0.10|' /etc/mysql/node.cnf + sed -r 's|^[#]?wsrep_sst_auth=.*$|wsrep_sst_auth='\''xtrabackup:abc123456'\''|' /etc/mysql/node.cnf + [[ -n '' ]] + [[ -n PXC1 ]] + sed -r 's|^[#]?wsrep_cluster_name=.*$|wsrep_cluster_name=PXC1|' /etc/mysql/node.cnf + '[' -z '' ']' + '[' mysqld = mysqld -a -z '' ']' + _check_config mysqld + toRun=("$@" --verbose --help --wsrep-provider='none') ++ mysqld --verbose --help --wsrep-provider=none + errors= ++ _get_config datadir mysqld ++ local conf=datadir ++ shift ++ awk '$1 == "datadir" && /^[^ \t]/ { sub(/^[^ \t]+[ \t]+/, ""); print; exit }' +++ mktemp -u ++ mysqld --verbose --help --wsrep-provider=none --log-bin-index=/tmp/tmp.oASah2Mg8m + DATADIR=/var/lib/mysql/ + '[' '!' -d /var/lib/mysql//mysql ']' + file_env MYSQL_ROOT_PASSWORD + set +o xtrace + '[' -z abc123456 -a -z '' -a -z '' ']' + rm -rf + mkdir -p /var/lib/mysql/ + echo 'Initializing database' + mysqld --initialize-insecure --skip-ssl Initializing database 2019-08-21T01:57:02.861437Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2019-08-21T01:57:02.861512Z 0 [Warning] WSREP: Node is running in bootstrap/initialize mode. Disabling pxc_strict_mode checks 2019-08-21T01:57:02.972040Z 0 [Warning] InnoDB: New log files created, LSN=45790 2019-08-21T01:57:02.996624Z 0 [Warning] InnoDB: Creating foreign key constraint system tables. 2019-08-21T01:57:03.052471Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: f867c2bf-c3b6-11e9-a9ea-02420a00000a. 2019-08-21T01:57:03.054029Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened. 2019-08-21T01:57:03.143495Z 1 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option. Database initialized + echo 'Database initialized' ++ _get_config socket mysqld ++ local conf=socket ++ shift ++ awk '$1 == "socket" && /^[^ \t]/ { sub(/^[^ \t]+[ \t]+/, ""); print; exit }' +++ mktemp -u ++ mysqld --verbose --help --wsrep-provider=none --log-bin-index=/tmp/tmp.EIpJk591hc + SOCKET=/tmp/mysql.sock + pid=57 + mysql=(mysql --protocol=socket -uroot -hlocalhost --socket="${SOCKET}" --password="") + for i in '{120..0}' + mysqld --skip-networking --socket=/tmp/mysql.sock + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... 2019-08-21T01:57:10.893469Z 0 [Warning] TIMESTAMP with implicit DEFAULT value is deprecated. Please use --explicit_defaults_for_timestamp server option (see documentation for more details). 2019-08-21T01:57:10.928790Z 0 [Note] mysqld (mysqld 5.7.26-29-57) starting as process 57 ... 2019-08-21T01:57:10.931702Z 0 [Note] WSREP: Setting wsrep_ready to false 2019-08-21T01:57:10.931726Z 0 [Note] WSREP: No pre-stored wsrep-start position found. Skipping position initialization. 2019-08-21T01:57:10.931731Z 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera3/libgalera_smm.so' 2019-08-21T01:57:10.934832Z 0 [Note] WSREP: wsrep_load(): Galera 3.37(rff05089) by Codership Oy <info@codership.com> loaded successfully. 2019-08-21T01:57:10.934924Z 0 [Note] WSREP: CRC-32C: using hardware acceleration. 2019-08-21T01:57:10.935272Z 0 [Warning] WSREP: Could not open state file for reading: '/var/lib/mysql//grastate.dat' 2019-08-21T01:57:10.935314Z 0 [Warning] WSREP: No persistent state found. Bootstraping with default state 2019-08-21T01:57:10.935408Z 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1 2019-08-21T01:57:10.946769Z 0 [Note] WSREP: Passing config to GCS: base_dir = /var/lib/mysql/; base_host = 10.0.0.10; base_port = 4567; cert.log_conflicts = no; cert.optimistic_pa = yes; 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 = 10; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 4; evs.view_forget_timeout = PT24H; gcache.dir = /var/lib/mysql/; gcache.freeze_purge_at_seqno = -1; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /var/lib/mysql//galera.cache; gcache.page_size = 128M; gcache.recover = no; gcache.size = 128M; gcomm.thread_prio = ; gcs.fc_debug = 0; gcs.fc_factor = 1; gcs.fc_limit = 100; gcs.fc_master_slave = no; gcs.max_packet_size = 64500; gcs.max_throttle = 0.25; gcs.recv_q_hard_limit = 9223372036854775807; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version = 0; pc.announce_timeout = PT3S; pc.checksum = false; pc.ignore_quorum = false; pc.ignore_sb = false; pc.npvo = false; pc.recovery = true; pc.version = 0; pc.wait_prim = true; pc.wait_prim_timeout = PT30S; pc.weight = 1; protonet.backend = asio; protonet.version = 0; repl.causal_read_timeout = PT30S; repl.commit_order = 3; repl.key_format = FLAT8; repl.max_ws_size = 2147483647; repl.proto_max = 9; socket.checksum = 2; socket.recv_buf_size = 212992; 2019-08-21T01:57:11.111587Z 0 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1 2019-08-21T01:57:11.111819Z 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2019-08-21T01:57:11.111881Z 0 [Note] WSREP: Preparing to initiate SST/IST 2019-08-21T01:57:11.111886Z 0 [Note] WSREP: Starting replication 2019-08-21T01:57:11.111896Z 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2019-08-21T01:57:11.112091Z 0 [Note] WSREP: Using CRC-32C for message checksums. 2019-08-21T01:57:11.112234Z 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 2019-08-21T01:57:11.112340Z 0 [Warning] WSREP: Fail to access the file (/var/lib/mysql//gvwstate.dat) error (No such file or directory). It is possible if node is booting for first time or re-booting after a graceful shutdown 2019-08-21T01:57:11.112346Z 0 [Note] WSREP: Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown 2019-08-21T01:57:11.112545Z 0 [Note] WSREP: GMCast version 0 2019-08-21T01:57:11.112653Z 0 [Note] WSREP: (fd359a96, 'tcp://0.0.0.0:4567') listening at tcp://0.0.0.0:4567 2019-08-21T01:57:11.112657Z 0 [Note] WSREP: (fd359a96, 'tcp://0.0.0.0:4567') multicast: , ttl: 1 2019-08-21T01:57:11.113109Z 0 [Note] WSREP: EVS version 0 2019-08-21T01:57:11.113247Z 0 [Note] WSREP: gcomm: connecting to group 'PXC1', peer '' 2019-08-21T01:57:11.113269Z 0 [Note] WSREP: start_prim is enabled, turn off pc_recovery 2019-08-21T01:57:11.114936Z 0 [Note] WSREP: Node fd359a96 state primary 2019-08-21T01:57:11.115018Z 0 [Note] WSREP: Current view of cluster as seen by this node view (view_id(PRIM,fd359a96,1) memb { fd359a96,0 } joined { } left { } partitioned { } ) 2019-08-21T01:57:11.115026Z 0 [Note] WSREP: Save the discovered primary-component to disk 2019-08-21T01:57:11.116072Z 0 [Note] WSREP: gcomm: connected 2019-08-21T01:57:11.116216Z 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2019-08-21T01:57:11.117080Z 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1 2019-08-21T01:57:11.117497Z 0 [Note] WSREP: Waiting for SST/IST to complete. 2019-08-21T01:57:11.117624Z 0 [Note] WSREP: Starting new group from scratch: fd366210-c3b6-11e9-92d2-0627ad544b2f 2019-08-21T01:57:11.118408Z 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: fd366876-c3b6-11e9-86bf-4fa5192eee59 2019-08-21T01:57:11.118439Z 0 [Note] WSREP: STATE EXCHANGE: sent state msg: fd366876-c3b6-11e9-86bf-4fa5192eee59 2019-08-21T01:57:11.118444Z 0 [Note] WSREP: STATE EXCHANGE: got state msg: fd366876-c3b6-11e9-86bf-4fa5192eee59 from 0 (80efe7691194) 2019-08-21T01:57:11.118450Z 0 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 0, members = 1/1 (primary/total), act_id = 0, last_appl. = -1, protocols = 0/9/3 (gcs/repl/appl), group UUID = fd366210-c3b6-11e9-92d2-0627ad544b2f 2019-08-21T01:57:11.118454Z 0 [Note] WSREP: Flow-control interval: [100, 100] 2019-08-21T01:57:11.118456Z 0 [Note] WSREP: Trying to continue unpaused monitor 2019-08-21T01:57:11.118458Z 0 [Note] WSREP: Restored state OPEN -> JOINED (0) 2019-08-21T01:57:11.118468Z 0 [Note] WSREP: Member 0.0 (80efe7691194) synced with group. 2019-08-21T01:57:11.118470Z 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0) 2019-08-21T01:57:11.119536Z 2 [Note] WSREP: REPL Protocols: 9 (4, 2) 2019-08-21T01:57:11.119592Z 2 [Note] WSREP: New cluster view: global state: fd366210-c3b6-11e9-92d2-0627ad544b2f:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3 2019-08-21T01:57:11.119615Z 2 [Note] WSREP: Setting wsrep_ready to true 2019-08-21T01:57:11.119647Z 0 [Note] WSREP: SST complete, seqno: 0 2019-08-21T01:57:11.134729Z 0 [Note] InnoDB: PUNCH HOLE support available 2019-08-21T01:57:11.134786Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2019-08-21T01:57:11.134790Z 0 [Note] InnoDB: Uses event mutexes 2019-08-21T01:57:11.134792Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier 2019-08-21T01:57:11.134794Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.7 2019-08-21T01:57:11.134796Z 0 [Note] InnoDB: Using Linux native AIO 2019-08-21T01:57:11.137654Z 0 [Note] InnoDB: Number of pools: 1 2019-08-21T01:57:11.138192Z 0 [Note] InnoDB: Using CPU crc32 instructions 2019-08-21T01:57:11.152581Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M 2019-08-21T01:57:11.206234Z 0 [Note] InnoDB: Completed initialization of buffer pool 2019-08-21T01:57:11.230008Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority(). 2019-08-21T01:57:11.257881Z 0 [Note] InnoDB: Crash recovery did not find the parallel doublewrite buffer at /var/lib/mysql/xb_doublewrite 2019-08-21T01:57:11.274901Z 0 [Note] InnoDB: Highest supported file format is Barracuda. 2019-08-21T01:57:11.300916Z 0 [Note] InnoDB: Created parallel doublewrite buffer at /var/lib/mysql/xb_doublewrite, size 3932160 bytes 2019-08-21T01:57:11.335624Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables 2019-08-21T01:57:11.335734Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ... 2019-08-21T01:57:11.342045Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB. 2019-08-21T01:57:11.342794Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active. 2019-08-21T01:57:11.342815Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active. 2019-08-21T01:57:11.358228Z 0 [Note] InnoDB: Waiting for purge to start 2019-08-21T01:57:11.409251Z 0 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.7.26-29 started; log sequence number 2530701 2019-08-21T01:57:11.424368Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool 2019-08-21T01:57:11.426141Z 0 [Note] Plugin 'FEDERATED' is disabled. 2019-08-21T01:57:11.496930Z 0 [Note] InnoDB: Buffer pool(s) load completed at 190821 1:57:11 + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... 2019-08-21T01:57:11.716898Z 0 [Note] Auto generated SSL certificates are placed in data directory. 2019-08-21T01:57:11.717284Z 0 [Warning] CA certificate ca.pem is self signed. 2019-08-21T01:57:11.717327Z 0 [Note] Skipping generation of RSA key pair as key files are present in data directory. 2019-08-21T01:57:11.737131Z 0 [Note] Event Scheduler: Loaded 0 events 2019-08-21T01:57:11.739311Z 0 [Note] mysqld: ready for connections. Version: '5.7.26-29-57' socket: '/tmp/mysql.sock' port: 0 Percona XtraDB Cluster (GPL), Release rel29, Revision 03540a3, WSREP version 31.37, wsrep_31.37 2019-08-21T01:57:11.754368Z 2 [Note] WSREP: Initialized wsrep sidno 2 2019-08-21T01:57:11.754389Z 2 [Note] WSREP: Auto Increment Offset/Increment re-align with cluster membership change (Offset: 1 -> 1) (Increment: 1 -> 1) 2019-08-21T01:57:11.754401Z 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-08-21T01:57:11.754417Z 2 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4 2019-08-21T01:57:11.754650Z 0 [Note] WSREP: Service thread queue flushed. 2019-08-21T01:57:11.755609Z 2 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> fd366210-c3b6-11e9-92d2-0627ad544b2f:0 2019-08-21T01:57:11.756624Z 2 [Note] WSREP: Synchronized with group, ready for connections 2019-08-21T01:57:11.756641Z 2 [Note] WSREP: Setting wsrep_ready to true 2019-08-21T01:57:11.756645Z 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' MySQL init process in progress... + sleep 1 + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'SELECT 1' MySQL init process in progress... + echo 'MySQL init process in progress...' + sleep 1 + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 MySQL init process in progress... + for i in '{120..0}' + echo 'SELECT 1' + mysql --protocol=socket -uroot -hlocalhost --socket=/tmp/mysql.sock --password= + echo 'MySQL init process in progress...' + sleep 1 ......... MySQL init process in progress... + '[' 0 = 0 ']' + echo 'MySQL init process failed.' MySQL init process failed.