+ '[' m = - ']'
+ CFG=/etc/mysql/node.cnf
+ wantHelp=
+ for arg in "$@"
+ case "$arg" in
+ vault_secret=/etc/mysql/vault-keyring-secret/keyring_vault.conf
+ '[' -f /etc/mysql/vault-keyring-secret/keyring_vault.conf ']'
+ file_env XTRABACKUP_PASSWORD xtrabackup
+ set +o xtrace
+ file_env CLUSTERCHECK_PASSWORD clustercheck
+ set +o xtrace
++ hostname -f
+ NODE_NAME=localhost
+ NODE_PORT=3306
+ '[' -n '' ']'
+ '[' -n '' ']'
+ : checking incoming cluster parameters
++ hostname -I
++ awk ' { print $1 } '
+ NODE_IP=158.255.5.54
+ sed -r 's|^[#]?wsrep_node_address=.*$|wsrep_node_address=158.255.5.54|' /etc/mysql/node.cnf
+ sed -r 's|^[#]?wsrep_node_incoming_address=.*$|wsrep_node_incoming_address=localhost:3306|' /etc/mysql/node.cnf
+ [[ -n 94.228.168.114,185.106.94.77 ]]
+ sed -r 's|^[#]?wsrep_cluster_address=.*$|wsrep_cluster_address=gcomm://94.228.168.114,185.106.94.77|' /etc/mysql/node.cnf
+ [[ -n pxc-cluster1 ]]
+ sed -r 's|^[#]?wsrep_cluster_name=.*$|wsrep_cluster_name=pxc-cluster1|' /etc/mysql/node.cnf
+ '[' '!' -z '' ']'
+ '[' '!' -z '' ']'
+ '[' '!' -z '' ']'
+ CALL_HOME_OPTIONAL_PARAMS+=' -t 7'
+ '[' '!' -z '' ']'
+ CALL_HOME_OPTIONAL_PARAMS+=' -c 2'
+ '[' -z 94.228.168.114,185.106.94.77 ']'
+ '[' mysqld = mysqld -a -z '' ']'
+ mysqld --version
+ tee /tmp/version_info
/usr/sbin/mysqld  Ver 8.0.36-28.1 for Linux on x86_64 (Percona XtraDB Cluster (GPL), Release rel28, Revision bfb687f, WSREP version 26.1.4.3)
++ _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.KjLpo1FqS6
+ DATADIR=/var/lib/mysql/
+ '[' -f /var/lib/mysql//version_info ']'
+ diff /tmp/version_info /var/lib/mysql//version_info
+ mysqld --version
+ grep -v wsrep_sst_auth /etc/mysql/node.cnf
[mysqld]

default_authentication_plugin=mysql_native_password
datadir=/var/lib/mysql
socket=/tmp/mysql.sock
skip-host-cache

#coredumper
#server_id=0
binlog_format=ROW
default_storage_engine=InnoDB

innodb_flush_log_at_trx_commit  = 2
innodb_flush_method             = O_DIRECT
innodb_file_per_table           = 1
innodb_autoinc_lock_mode=2

bind_address = 0.0.0.0

wsrep_slave_threads=2
wsrep_cluster_address=gcomm://94.228.168.114,185.106.94.77
wsrep_provider=/usr/lib64/galera4/libgalera_smm.so

wsrep_cluster_name=pxc-cluster1
wsrep_node_address=158.255.5.54
wsrep_node_incoming_address=localhost:3306

wsrep_sst_method=xtrabackup-v2

[client]
socket=/tmp/mysql.sock

[sst]
progress=/var/lib/mysql/sst_in_progress
+ wsrep_start_position_opt=
+ '[' mysqld = mysqld -a -z '' ']'
++ _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.rHwS4C4L2g
+ DATADIR=/var/lib/mysql/
+ grastate_loc=/var/lib/mysql//grastate.dat
+ '[' -s /var/lib/mysql//grastate.dat -a -d /var/lib/mysql//mysql ']'
++ grep uuid: /var/lib/mysql//grastate.dat
++ tr -d ' '
++ cut -d: -f2
+ uuid=783e720c-f02a-11ee-8c0c-9eee74c188cd
++ grep seqno: /var/lib/mysql//grastate.dat
++ tr -d ' '
++ cut -d: -f2
+ seqno=322458
+ '[' -n 322458 ']'
+ '[' 322458 -ne -1 ']'
+ echo 'Skipping wsrep-recover for 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458 pair'
+ echo 'Assigning 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458 to wsrep_start_position'
+ wsrep_start_position_opt=--wsrep_start_position=783e720c-f02a-11ee-8c0c-9eee74c188cd:322458
Skipping wsrep-recover for 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458 pair
Assigning 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458 to wsrep_start_position
+ '[' -z --wsrep_start_position=783e720c-f02a-11ee-8c0c-9eee74c188cd:322458 -a -d /var/lib/mysql//mysql ']'
+ /call-home.sh -f PRODUCT_FAMILY_PXC -v 8.0.36-28-1 -d DOCKER -s el8 -t 7 -c 2
+ exec mysqld --wsrep_start_position=783e720c-f02a-11ee-8c0c-9eee74c188cd:322458
2024-04-30T08:43:29.053761Z 0 [Warning] [MY-011068] [Server] The syntax '--skip-host-cache' is deprecated and will be removed in a future release. Please use SET GLOBAL host_cache_size=0 instead.
2024-04-30T08:43:29.053790Z 0 [Warning] [MY-011070] [Server] 'binlog_format' is deprecated and will be removed in a future release.
2024-04-30T08:43:29.053842Z 0 [Warning] [MY-011068] [Server] The syntax 'wsrep_slave_threads' is deprecated and will be removed in a future release. Please use wsrep_applier_threads instead.
2024-04-30T08:43:29.059144Z 0 [Warning] [MY-010097] [Server] Insecure configuration for --secure-log-path: Current value does not restrict location of generated files. Consider setting it to a valid, non-empty path.
2024-04-30T08:43:29.060271Z 0 [Warning] [MY-010918] [Server] 'default_authentication_plugin' is deprecated and will be removed in a future release. Please use authentication_policy instead.
2024-04-30T08:43:29.060313Z 0 [System] [MY-010116] [Server] /usr/sbin/mysqld (mysqld 8.0.36-28.1) starting as process 1
2024-04-30T08:43:29.067554Z 0 [Warning] [MY-010068] [Server] CA certificate /cert/ca.pem is self signed.
2024-04-30T08:43:29.067617Z 0 [System] [MY-013602] [Server] Channel mysql_main configured to support TLS. Encrypted connections are now supported for this channel.
2024-04-30T08:43:29.067631Z 0 [Note] [MY-000000] [WSREP] New joining cluster node configured to use specified SSL artifacts
2024-04-30T08:43:29.067720Z 0 [Note] [MY-000000] [Galera] Loading provider /usr/lib64/galera4/libgalera_smm.so initial position: 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458
2024-04-30T08:43:29.067781Z 0 [Note] [MY-000000] [Galera] wsrep_load(): loading provider library '/usr/lib64/galera4/libgalera_smm.so'
2024-04-30T08:43:29.070231Z 0 [Note] [MY-000000] [Galera] wsrep_load(): Galera 4.17(aa2461d) by Codership Oy <info@codership.com> (modified by Percona <https://percona.com/>) loaded successfully.
2024-04-30T08:43:29.070358Z 0 [Note] [MY-000000] [Galera] CRC-32C: using 64-bit x86 acceleration.
2024-04-30T08:43:29.071015Z 0 [Warning] [MY-000000] [Galera] SSL compression is not effective. The option socket.ssl_compression is deprecated and will be removed in future releases.
2024-04-30T08:43:29.071040Z 0 [Warning] [MY-000000] [Galera] Parameter 'socket.ssl_compression' is deprecated and will be removed in future versions
2024-04-30T08:43:29.071981Z 0 [Note] [MY-000000] [Galera] Found saved state: 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458, safe_to_bootstrap: 0
2024-04-30T08:43:29.072222Z 0 [Note] [MY-000000] [Galera] GCache DEBUG: opened preamble:
Version: 2
UUID: 783e720c-f02a-11ee-8c0c-9eee74c188cd
Seqno: 92133 - 322458
Offset: 1920
Synced: 1
EncVersion: 1
Encrypted: 0
MasterKeyConst UUID: 4e03a756-f984-11ee-856d-8a85071d7e85
MasterKey UUID: 00000000-0000-0000-0000-000000000000
MasterKey ID: 0
2024-04-30T08:43:29.072249Z 0 [Note] [MY-000000] [Galera] Recovering GCache ring buffer: version: 2, UUID: 783e720c-f02a-11ee-8c0c-9eee74c188cd, offset: 1920
2024-04-30T08:43:29.072508Z 0 [Note] [MY-000000] [Galera] GCache::RingBuffer initial scan...  0.0% (        0/134217752 bytes) complete.
2024-04-30T08:43:29.285792Z 0 [Note] [MY-000000] [Galera] GCache::RingBuffer initial scan...100.0% (134217752/134217752 bytes) complete.
2024-04-30T08:43:29.300438Z 0 [Note] [MY-000000] [Galera] Recovering GCache ring buffer: found gapless sequence 92133-322458
2024-04-30T08:43:29.300632Z 0 [Note] [MY-000000] [Galera] GCache::RingBuffer unused buffers scan...  0.0% (        0/117733896 bytes) complete.
2024-04-30T08:43:29.326830Z 0 [Note] [MY-000000] [Galera] GCache::RingBuffer unused buffers scan...100.0% (117733896/117733896 bytes) complete.
2024-04-30T08:43:29.326899Z 0 [Note] [MY-000000] [Galera] Recovering GCache ring buffer: found 501/230827 locked buffers
2024-04-30T08:43:29.326910Z 0 [Note] [MY-000000] [Galera] Recovering GCache ring buffer: free space: 16573784/134217728
2024-04-30T08:43:29.329703Z 0 [Note] [MY-000000] [Galera] Passing config to GCS: allocator.disk_pages_encryption = no; allocator.encryption_cache_page_size = 32K; allocator.encryption_cache_size = 16777216; base_dir = /var/lib/mysql/; base_host = 158.255.5.54; base_port = 4567; cert.log_conflicts = no; cert.optimistic_pa = 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 = 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.encryption = no; gcache.encryption_cache_page_size = 32K; gcache.encryption_cache_size = 16777216; gcache.freeze_purge_at_seqno = -1; gcache.keep_pages_count = 0; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = galera.cache; gcache.page_size = 128M; gcache.recover = yes; gcache.size = 128M; gcomm.thread_prio = ; gcs.fc_auto_evict_threshold = 0.75; gcs.fc_auto_evict_window = 0; gcs.fc_debug = 0; gcs.fc_factor = 1.0; gcs.fc_limit = 100; gcs.fc_master_slave = no; gcs.fc_single_primary = 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.wait_restored_prim_timeout = PT0S; 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 = 11; socket.checksum = 2; socket.recv_buf_size = auto; socket.send_buf_size = auto; socket.ssl = YES; socket.ssl_ca = /cert/ca.pem; socket.ssl_cert = /cert/server-cert.pem; socket.ssl_cipher = ; socket.ssl_compression = YES; socket.ssl_key = /cert/server-key.pem; socket.ssl_reload = 1; 
2024-04-30T08:43:29.370019Z 0 [Note] [MY-000000] [Galera] Service thread queue flushed.
2024-04-30T08:43:29.370191Z 0 [Note] [MY-000000] [Galera] ####### Assign initial position for certification: 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458, protocol version: -1
2024-04-30T08:43:29.370485Z 0 [Note] [MY-000000] [WSREP] Starting replication
2024-04-30T08:43:29.370517Z 0 [Note] [MY-000000] [Galera] Connecting with bootstrap option: 0
2024-04-30T08:43:29.370540Z 0 [Note] [MY-000000] [Galera] Setting GCS initial position to 783e720c-f02a-11ee-8c0c-9eee74c188cd:322458
2024-04-30T08:43:29.370656Z 0 [Note] [MY-000000] [Galera] protonet asio version 0
2024-04-30T08:43:29.371474Z 0 [Note] [MY-000000] [Galera] Using CRC-32C for message checksums.
2024-04-30T08:43:29.371516Z 0 [Note] [MY-000000] [Galera] backend: asio
2024-04-30T08:43:29.371672Z 0 [Note] [MY-000000] [Galera] gcomm thread scheduling priority set to other:0 
2024-04-30T08:43:29.372086Z 0 [Note] [MY-000000] [Galera] 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
2024-04-30T08:43:29.372114Z 0 [Note] [MY-000000] [Galera] Restoring primary-component from disk failed. Either node is booting for first time or re-booting after a graceful shutdown
2024-04-30T08:43:29.372380Z 0 [Note] [MY-000000] [Galera] GMCast version 0
2024-04-30T08:43:29.372616Z 0 [Note] [MY-000000] [Galera] (b7d057f3-a174, 'ssl://0.0.0.0:4567') listening at ssl://0.0.0.0:4567
2024-04-30T08:43:29.372638Z 0 [Note] [MY-000000] [Galera] (b7d057f3-a174, 'ssl://0.0.0.0:4567') multicast: , ttl: 1
2024-04-30T08:43:29.373066Z 0 [Note] [MY-000000] [Galera] EVS version 1
2024-04-30T08:43:29.373257Z 0 [Note] [MY-000000] [Galera] gcomm: connecting to group 'pxc-cluster1', peer '94.228.168.114:,185.106.94.77:'
2024-04-30T08:43:29.588898Z 0 [Note] [MY-000000] [Galera] (b7d057f3-a174, 'ssl://0.0.0.0:4567') connection established to 9e5f206b-b5a2 ssl://185.106.94.77:4567
2024-04-30T08:43:29.595613Z 0 [Note] [MY-000000] [Galera] (b7d057f3-a174, 'ssl://0.0.0.0:4567') connection established to ee91a61b-b92a ssl://94.228.168.114:4567
2024-04-30T08:43:30.035591Z 0 [Note] [MY-000000] [Galera] EVS version upgrade 0 -> 1
2024-04-30T08:43:30.035660Z 0 [Note] [MY-000000] [Galera] declaring 9e5f206b-b5a2 at ssl://185.106.94.77:4567 stable
2024-04-30T08:43:30.035672Z 0 [Note] [MY-000000] [Galera] declaring ee91a61b-b92a at ssl://94.228.168.114:4567 stable
2024-04-30T08:43:30.035711Z 0 [Note] [MY-000000] [Galera] PC protocol upgrade 0 -> 1
2024-04-30T08:43:30.089954Z 0 [Warning] [MY-000000] [Galera] no nodes coming from prim view, prim not possible
2024-04-30T08:43:30.090030Z 0 [Note] [MY-000000] [Galera] Current view of cluster as seen by this node
view (view_id(NON_PRIM,9e5f206b-b5a2,604)
memb {
        9e5f206b-b5a2,0
        b7d057f3-a174,0
        ee91a61b-b92a,0
        }
joined {
        }
left {
        }
partitioned {
        1d7e697a-9726,0
        }
)
2024-04-30T08:43:32.875444Z 0 [Note] [MY-000000] [Galera] (b7d057f3-a174, 'ssl://0.0.0.0:4567') turning message relay requesting off
2024-04-30T08:44:00.383740Z 0 [ERROR] [MY-000000] [Galera] failed to open gcomm backend connection: 110: failed to reach primary view (pc.wait_prim_timeout): 110 (Connection timed out)
         at /mnt/jenkins/workspace/pxc80-autobuild-RELEASE/test/rpmbuild/BUILD/Percona-XtraDB-Cluster-8.0.36/percona-xtradb-cluster-galera/gcomm/src/pc.cpp:connect():176
2024-04-30T08:44:00.383819Z 0 [ERROR] [MY-000000] [Galera] /mnt/jenkins/workspace/pxc80-autobuild-RELEASE/test/rpmbuild/BUILD/Percona-XtraDB-Cluster-8.0.36/percona-xtradb-cluster-galera/gcs/src/gcs_core.cpp:gcs_core_open():219: Failed to open backend connection: -110 (Connection timed out)
2024-04-30T08:44:01.384135Z 0 [Note] [MY-000000] [Galera] gcomm: terminating thread
2024-04-30T08:44:01.384211Z 0 [Note] [MY-000000] [Galera] gcomm: joining thread
2024-04-30T08:44:01.385510Z 0 [ERROR] [MY-000000] [Galera] /mnt/jenkins/workspace/pxc80-autobuild-RELEASE/test/rpmbuild/BUILD/Percona-XtraDB-Cluster-8.0.36/percona-xtradb-cluster-galera/gcs/src/gcs.cpp:gcs_open():1880: Failed to open channel 'pxc-cluster1' at 'gcomm://94.228.168.114,185.106.94.77': -110 (Connection timed out)
2024-04-30T08:44:01.385558Z 0 [ERROR] [MY-000000] [Galera] gcs connect failed: Connection timed out
2024-04-30T08:44:01.385572Z 0 [ERROR] [MY-000000] [WSREP] Provider/Node (gcomm://94.228.168.114,185.106.94.77) failed to establish connection with cluster (reason: 7)
2024-04-30T08:44:01.385610Z 0 [ERROR] [MY-010119] [Server] Aborting
2024-04-30T08:44:01.387054Z 0 [System] [MY-010910] [Server] /usr/sbin/mysqld: Shutdown complete (mysqld 8.0.36-28.1)  Percona XtraDB Cluster (GPL), Release rel28, Revision bfb687f, WSREP version 26.1.4.3.
2024-04-30T08:44:01.390775Z 0 [ERROR] [MY-010065] [Server] Failed to shutdown components infrastructure.
2024-04-30T08:44:01.391192Z 0 [Note] [MY-000000] [Galera] dtor state: CLOSED
2024-04-30T08:44:01.391236Z 0 [Note] [MY-000000] [Galera] MemPool(TrxHandleSlave): hit ratio: 0, misses: 0, in use: 0, in pool: 0
2024-04-30T08:44:01.393787Z 0 [Note] [MY-000000] [Galera] apply mon: entered 0
2024-04-30T08:44:01.396610Z 0 [Note] [MY-000000] [Galera] apply mon: entered 0
2024-04-30T08:44:01.400123Z 0 [Note] [MY-000000] [Galera] apply mon: entered 0
2024-04-30T08:44:01.400197Z 0 [Note] [MY-000000] [Galera] cert index usage at exit 0
2024-04-30T08:44:01.400206Z 0 [Note] [MY-000000] [Galera] cert trx map usage at exit 0
2024-04-30T08:44:01.400213Z 0 [Note] [MY-000000] [Galera] deps set usage at exit 0
2024-04-30T08:44:01.400228Z 0 [Note] [MY-000000] [Galera] avg deps dist 0
2024-04-30T08:44:01.400235Z 0 [Note] [MY-000000] [Galera] avg cert interval 0
2024-04-30T08:44:01.400247Z 0 [Note] [MY-000000] [Galera] cert index size 0
2024-04-30T08:44:01.400311Z 0 [Note] [MY-000000] [Galera] Service thread queue flushed.
2024-04-30T08:44:01.400356Z 0 [Note] [MY-000000] [Galera] wsdb trx map usage 0 conn query map usage 0
2024-04-30T08:44:01.400373Z 0 [Note] [MY-000000] [Galera] MemPool(LocalTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
2024-04-30T08:44:01.400602Z 0 [Note] [MY-000000] [Galera] Shifting CLOSED -> DESTROYED (TO: 0)
2024-04-30T08:44:01.401443Z 0 [Note] [MY-000000] [Galera] Flushing memory map to disk...