Uploaded image for project: 'MariaDB Server'
  1. MariaDB Server
  2. MDEV-19950

Galera test failure on galera_ssl_upgrade

Details

    Description

      galera.galera_ssl_upgrade 'innodb'       w1 [ fail ]
              Test ended at 2019-07-03 14:55:39
       
      CURRENT_TEST: galera.galera_ssl_upgrade
       
       
      Server [mysqld.1 - pid: 17851, winpid: 17851, exit: 256] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2019-07-03 14:55:24 3068925888 [Note] /usr/sbin/mysqld (mysqld 10.2.26-MariaDB-10.2.26+maria~xenial-log) starting as process 17363 ...
      2019-07-03 14:55:24 3068925888 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4186)
      2019-07-03 14:55:24 3068925888 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
      2019-07-03 14:55:24 3068925888 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members.
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Read nil XID from storage engines, skipping position init
      2019-07-03 14:55:24 3068925888 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/galera/libgalera_smm.so'
      2019-07-03 14:55:24 3068925888 [Note] WSREP: wsrep_load(): Galera 25.3.26(r3857) by Codership Oy <info@codership.com> loaded successfully.
      2019-07-03 14:55:24 3068925888 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
      2019-07-03 14:55:24 3068925888 [Warning] WSREP: Could not open state file for reading: '/dev/shm/var/1/mysqld.1/data//grastate.dat'
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Passing config to GCS: base_dir = /dev/shm/var/1/mysqld.1/data/; base_host = 127.0.0.1; base_port = 16002; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /dev/shm/var/1/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /dev/shm/var/1/mysqld.1/data//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 = 2147483647; gcs.recv_q_soft_limit = 0.25; gcs.sync_donor = no; gmcast.segment = 0; gmcast.version =
      2019-07-03 14:55:24 3068925888 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: wsrep_sst_grab()
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Start replication
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: protonet asio version 0
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Using CRC-32C for message checksums.
      2019-07-03 14:55:24 3068925888 [Note] WSREP: initializing ssl context
      2019-07-03 14:55:24 3068925888 [Note] WSREP: backend: asio
      2019-07-03 14:55:24 3068925888 [Note] WSREP: gcomm thread scheduling priority set to other:0 
      2019-07-03 14:55:24 3068925888 [Warning] WSREP: access file(/dev/shm/var/1/mysqld.1/data//gvwstate.dat) failed(No such file or directory)
      2019-07-03 14:55:24 3068925888 [Note] WSREP: restore pc from disk failed
      2019-07-03 14:55:24 3068925888 [Note] WSREP: GMCast version 0
      2019-07-03 14:55:24 3068925888 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') listening at ssl://0.0.0.0:16002
      2019-07-03 14:55:24 3068925888 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') multicast: , ttl: 1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: EVS version 0
      2019-07-03 14:55:24 3068925888 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer ''
      2019-07-03 14:55:24 3068925888 [Note] WSREP: start_prim is enabled, turn off pc_recovery
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Node 1da4dceb state prim
      2019-07-03 14:55:24 3068925888 [Note] WSREP: view(view_id(PRIM,1da4dceb,1) memb {
      	1da4dceb,0
      } joined {
      } left {
      } partitioned {
      })
      2019-07-03 14:55:24 3068925888 [Note] WSREP: save pc into disk
      2019-07-03 14:55:24 3068925888 [Note] WSREP: gcomm: connected
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Opened channel 'my_wsrep_cluster'
      2019-07-03 14:55:24 2814298944 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
      2019-07-03 14:55:24 3068925888 [Note] WSREP: Waiting for SST to complete.
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Starting new group from scratch: 1da53af8-9dc4-11e9-9c3d-03e2256d0c61
      2019-07-03 14:55:24 2814298944 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 1da542de-9dc4-11e9-85db-bb407681ed0b
      2019-07-03 14:55:24 2814298944 [Note] WSREP: STATE EXCHANGE: sent state msg: 1da542de-9dc4-11e9-85db-bb407681ed0b
      2019-07-03 14:55:24 2814298944 [Note] WSREP: STATE EXCHANGE: got state msg: 1da542de-9dc4-11e9-85db-bb407681ed0b from 0 (ubuntu-xenial-i386)
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Quorum results:
      	version    = 4,
      	component  = PRIMARY,
      	conf_id    = 0,
      	members    = 1/1 (joined/total),
      	act_id     = 0,
      	last_appl. = -1,
      	protocols  = 0/9/3 (gcs/repl/appl),
      	group UUID = 1da53af8-9dc4-11e9-9c3d-03e2256d0c61
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Flow-control interval: [16, 16]
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Trying to continue unpaused monitor
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Restored state OPEN -> JOINED (0)
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Member 0.0 (ubuntu-xenial-i386) synced with group.
      2019-07-03 14:55:24 2814298944 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
      2019-07-03 14:55:24 2805603136 [Note] WSREP: New cluster view: global state: 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3
      2019-07-03 14:55:24 3068925888 [Note] WSREP: SST complete, seqno: 0
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'partition' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'SEQUENCE' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Uses event mutexes
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Compressed tables use zlib 1.2.8
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Using Linux native AIO
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Number of pools: 1
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Using generic crc32 instructions
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Completed initialization of buffer pool
      2019-07-03 14:55:24 2711448384 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Highest supported file format is Barracuda.
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Creating shared tablespace for temporary tables
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: Waiting for purge to start
      2019-07-03 14:55:24 2822691648 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:57652 local endpoint ssl://127.0.0.1:16002 cipher: ECDHE-RSA-AES256-GCM-SHA384 compression: none
      2019-07-03 14:55:24 2822691648 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') connection established to 1da7c7a9 ssl://127.0.0.1:16005
      2019-07-03 14:55:24 2822691648 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: 
      2019-07-03 14:55:24 3068925888 [Note] InnoDB: 5.7.26 started; log sequence number 1633840
      2019-07-03 14:55:24 2615085888 [Note] InnoDB: Loading buffer pool(s) from /dev/shm/var/1/mysqld.1/data/ib_buffer_pool
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2019-07-03 14:55:24 2615085888 [Note] InnoDB: Buffer pool(s) load completed at 190703 14:55:24
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_CMP' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'FEEDBACK' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'user_variables' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2019-07-03 14:55:24 3068925888 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2019-07-03 14:55:24 3068925888 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-startup-interval=20'
      2019-07-03 14:55:24 3068925888 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-first-interval=60'
      2019-07-03 14:55:24 3068925888 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-interval=60'
      2019-07-03 14:55:24 3068925888 [Warning] /usr/sbin/mysqld: unknown option '--loose-pam-debug'
      2019-07-03 14:55:24 3068925888 [Warning] /usr/sbin/mysqld: unknown variable 'loose-debug-sync-timeout=300'
      2019-07-03 14:55:24 3068925888 [Note] Server socket created on IP: '127.0.0.1'.
      2019-07-03 14:55:24 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:24 2805603136 [Note] WSREP: REPL Protocols: 9 (4, 2)
      2019-07-03 14:55:24 2805603136 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4
      2019-07-03 14:55:24 2853116736 [Note] WSREP: Service thread queue flushed.
      2019-07-03 14:55:24 2805603136 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0
      2019-07-03 14:55:24 2805603136 [Note] WSREP: Synchronized with group, ready for connections
      2019-07-03 14:55:24 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:24 3068925888 [Note] Reading of all Master_info entries succeeded
      2019-07-03 14:55:24 3068925888 [Note] Added new Master_info '' to hash table
      2019-07-03 14:55:24 3068925888 [Note] /usr/sbin/mysqld: ready for connections.
      Version: '10.2.26-MariaDB-10.2.26+maria~xenial-log'  socket: '/dev/shm/var/tmp/1/mysqld.1.sock'  port: 16000  mariadb.org binary distribution
      2019-07-03 14:55:25 2822691648 [Note] WSREP: declaring 1da7c7a9 at ssl://127.0.0.1:16005 stable
      2019-07-03 14:55:25 2822691648 [Note] WSREP: Node 1da4dceb state prim
      2019-07-03 14:55:25 2822691648 [Note] WSREP: view(view_id(PRIM,1da4dceb,2) memb {
      	1da4dceb,0
      	1da7c7a9,0
      } joined {
      } left {
      } partitioned {
      })
      2019-07-03 14:55:25 2822691648 [Note] WSREP: save pc into disk
      2019-07-03 14:55:25 2814298944 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
      2019-07-03 14:55:25 2814298944 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 1df4c04f-9dc4-11e9-b0f2-4774d19dc93d
      2019-07-03 14:55:25 2814298944 [Note] WSREP: STATE EXCHANGE: sent state msg: 1df4c04f-9dc4-11e9-b0f2-4774d19dc93d
      2019-07-03 14:55:25 2814298944 [Note] WSREP: STATE EXCHANGE: got state msg: 1df4c04f-9dc4-11e9-b0f2-4774d19dc93d from 0 (ubuntu-xenial-i386)
      2019-07-03 14:55:25 2814298944 [Note] WSREP: STATE EXCHANGE: got state msg: 1df4c04f-9dc4-11e9-b0f2-4774d19dc93d from 1 (ubuntu-xenial-i386)
      2019-07-03 14:55:25 2814298944 [Note] WSREP: Quorum results:
      	version    = 4,
      	component  = PRIMARY,
      	conf_id    = 1,
      	members    = 1/2 (joined/total),
      	act_id     = 0,
      	last_appl. = 0,
      	protocols  = 0/9/3 (gcs/repl/appl),
      	group UUID = 1da53af8-9dc4-11e9-9c3d-03e2256d0c61
      2019-07-03 14:55:25 2814298944 [Note] WSREP: Flow-control interval: [23, 23]
      2019-07-03 14:55:25 2814298944 [Note] WSREP: Trying to continue unpaused monitor
      2019-07-03 14:55:25 2805603136 [Note] WSREP: New cluster view: global state: 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0, view# 2: Primary, number of nodes: 2, my index: 0, protocol version 3
      2019-07-03 14:55:25 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:25 2805603136 [Note] WSREP: REPL Protocols: 9 (4, 2)
      2019-07-03 14:55:25 2805603136 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4
      2019-07-03 14:55:25 2853116736 [Note] WSREP: Service thread queue flushed.
      2019-07-03 14:55:25 2814298944 [Note] WSREP: Member 1.0 (ubuntu-xenial-i386) requested state transfer from '*any*'. Selected 0.0 (ubuntu-xenial-i386)(SYNCED) as donor.
      2019-07-03 14:55:25 2814298944 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 0)
      2019-07-03 14:55:25 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:25 2581515072 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'donor' --address '127.0.0.1:16007/rsync_sst' --socket '/dev/shm/var/tmp/1/mysqld.1.sock' --datadir '/dev/shm/var/1/mysqld.1/data/'  --defaults-file '/dev/shm/var/1/my.cnf' --defaults-group-suffix '.1'    '' --gtid '1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0' --gtid-domain-id '0''
      2019-07-03 14:55:25 2805603136 [Note] WSREP: sst_donor_thread signaled with 0
      2019-07-03 14:55:25 2581515072 [Note] WSREP: Flushing tables for SST...
      2019-07-03 14:55:25 2581515072 [Note] WSREP: Provider paused at 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0 (5)
      2019-07-03 14:55:25 2581515072 [Note] WSREP: Tables flushed.
      2019-07-03 14:55:26 2581515072 [Note] WSREP: resuming provider at 5
      2019-07-03 14:55:26 2581515072 [Note] WSREP: Provider resumed.
      2019-07-03 14:55:26 2814298944 [Note] WSREP: 0.0 (ubuntu-xenial-i386): State transfer to 1.0 (ubuntu-xenial-i386) complete.
      2019-07-03 14:55:26 2814298944 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 0)
      2019-07-03 14:55:26 2814298944 [Note] WSREP: Member 0.0 (ubuntu-xenial-i386) synced with group.
      2019-07-03 14:55:26 2814298944 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
      2019-07-03 14:55:26 2805603136 [Note] WSREP: Synchronized with group, ready for connections
      2019-07-03 14:55:26 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:27 2814298944 [Note] WSREP: 1.0 (ubuntu-xenial-i386): State transfer from 0.0 (ubuntu-xenial-i386) complete.
      2019-07-03 14:55:27 2814298944 [Note] WSREP: Member 1.0 (ubuntu-xenial-i386) synced with group.
      2019-07-03 14:55:28 2822691648 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') turning message relay requesting off
      2019-07-03 14:55:30 2763602752 [Note] /usr/sbin/mysqld (initiated by: root[root] @ localhost [127.0.0.1]): Normal shutdown
      2019-07-03 14:55:30 2763602752 [Note] WSREP: Stop replication
      2019-07-03 14:55:30 2763602752 [Note] WSREP: Closing send monitor...
      2019-07-03 14:55:30 2763602752 [Note] WSREP: Closed send monitor.
      2019-07-03 14:55:30 2763602752 [Note] WSREP: gcomm: terminating thread
      2019-07-03 14:55:30 2763602752 [Note] WSREP: gcomm: joining thread
      2019-07-03 14:55:30 2763602752 [Note] WSREP: gcomm: closing backend
      2019-07-03 14:55:33 2763602752 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') connection to peer 1da7c7a9 with addr ssl://127.0.0.1:16005 timed out, no messages seen in PT3S
      2019-07-03 14:55:33 2763602752 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: ssl://127.0.0.1:16005 
      2019-07-03 14:55:34 2763602752 [Note] WSREP: (1da4dceb, 'ssl://0.0.0.0:16002') reconnecting to 1da7c7a9 (ssl://127.0.0.1:16005), attempt 0
      2019-07-03 14:55:34 2763602752 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:16005 local endpoint ssl://127.0.0.1:45788 cipher: ECDHE-RSA-AES256-GCM-SHA384 compression: none
      2019-07-03 14:55:35 2763602752 [Note] WSREP: evs::proto(1da4dceb, LEAVING, view_id(REG,1da4dceb,2)) suspecting node: 1da7c7a9
      2019-07-03 14:55:35 2763602752 [Note] WSREP: evs::proto(1da4dceb, LEAVING, view_id(REG,1da4dceb,2)) suspected node without join message, declaring inactive
      2019-07-03 14:55:35 2763602752 [Note] WSREP: view(view_id(NON_PRIM,1da4dceb,2) memb {
      	1da4dceb,0
      } joined {
      } left {
      } partitioned {
      	1da7c7a9,0
      })
      2019-07-03 14:55:35 2763602752 [Note] WSREP: view((empty))
      2019-07-03 14:55:35 2763602752 [Note] WSREP: gcomm: closed
      2019-07-03 14:55:35 2814298944 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Flow-control interval: [16, 16]
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Trying to continue unpaused monitor
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Received NON-PRIMARY.
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 0)
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Received self-leave message.
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Flow-control interval: [0, 0]
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Trying to continue unpaused monitor
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Received SELF-LEAVE. Closing connection.
      2019-07-03 14:55:35 2814298944 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 0)
      2019-07-03 14:55:35 2814298944 [Note] WSREP: RECV thread exiting 0: Success
      2019-07-03 14:55:35 2763602752 [Note] WSREP: recv_thread() joined.
      2019-07-03 14:55:35 2763602752 [Note] WSREP: Closing replication queue.
      2019-07-03 14:55:35 2763602752 [Note] WSREP: Closing slave action queue.
      2019-07-03 14:55:35 2805603136 [Note] WSREP: New cluster view: global state: 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
      2019-07-03 14:55:35 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:35 2805603136 [Note] WSREP: New cluster view: global state: 1da53af8-9dc4-11e9-9c3d-03e2256d0c61:0, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
      2019-07-03 14:55:35 2805603136 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      2019-07-03 14:55:35 2805603136 [Note] WSREP: applier thread exiting (code:0)
      2019-07-03 14:55:37 2805906240 [Note] WSREP: rollbacker thread exiting
      2019-07-03 14:55:37 2763602752 [Note] Event Scheduler: Purging the queue. 0 events
      2019-07-03 14:55:37 2657049408 [Note] InnoDB: FTS optimize thread exiting.
      2019-07-03 14:55:37 2763602752 [Note] WSREP: dtor state: CLOSED
      2019-07-03 14:55:37 2763602752 [Note] WSREP: apply mon: entered 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: apply mon: entered 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: mon: entered 9 oooe fraction 0 oool fraction 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: cert index usage at exit 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: cert trx map usage at exit 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: deps set usage at exit 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: avg deps dist 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: avg cert interval 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: cert index size 0
      2019-07-03 14:55:37 2853116736 [Note] WSREP: Service thread queue flushed.
      2019-07-03 14:55:37 2763602752 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
      2019-07-03 14:55:37 2763602752 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 0)
      2019-07-03 14:55:37 2763602752 [Note] WSREP: Flushing memory map to disk...
      2019-07-03 14:55:37 2763602752 [Note] InnoDB: Starting shutdown...
      2019-07-03 14:55:37 2615085888 [Note] InnoDB: Dumping buffer pool(s) to /dev/shm/var/1/mysqld.1/data/ib_buffer_pool
      2019-07-03 14:55:37 2615085888 [Note] InnoDB: Instance 0, restricted to 127 pages due to innodb_buf_pool_dump_pct=25
      2019-07-03 14:55:37 2615085888 [Note] InnoDB: Buffer pool(s) dump completed at 190703 14:55:37
      2019-07-03 14:55:38 2763602752 [Note] InnoDB: Shutdown completed; log sequence number 1634032
      2019-07-03 14:55:38 2763602752 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2019-07-03 14:55:38 2763602752 [Note] /usr/sbin/mysqld: Shutdown complete
       
      2019-07-03 14:55:39 3069274048 [Note] /usr/sbin/mysqld (mysqld 10.2.26-MariaDB-10.2.26+maria~xenial-log) starting as process 17852 ...
      2019-07-03 14:55:39 3069274048 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4186)
      2019-07-03 14:55:39 3069274048 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
      2019-07-03 14:55:39 3069274048 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members.
      2019-07-03 14:55:39 3069274048 [Note] WSREP: Read nil XID from storage engines, skipping position init
      2019-07-03 14:55:39 3069274048 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/galera/libgalera_smm.so'
      2019-07-03 14:55:39 3069274048 [Note] WSREP: wsrep_load(): Galera 25.3.26(r3857) by Codership Oy <info@codership.com> loaded successfully.
      2019-07-03 14:55:39 3069274048 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
      2019-07-03 14:55:39 3069274048 [ERROR] WSREP: Bad value '/usr/share/mysql/mysql-test/std_data/galera-upgrade-ca-cert.pem' for SSL parameter 'socket.ssl_ca': 33558530: 'error:02001002:system library:fopen:No such file or directory': 22 (Invalid argument)
      	 at galerautils/src/gu_asio.cpp:ssl_prepare_context():195
      2019-07-03 14:55:39 3069274048 [ERROR] WSREP: wsrep::init() failed: 7, must shutdown
      2019-07-03 14:55:39 3069274048 [ERROR] Aborting
      

      Attachments

        Activity

          After adding missing SSL certifications

          galera.galera_ssl_upgrade 'innodb'       [ fail ]
                  Test ended at 2019-07-04 12:05:50
           
          CURRENT_TEST: galera.galera_ssl_upgrade
           
           
          Server [mysqld.1 - pid: 8265, winpid: 8265, exit: 256] failed during test run
          Server log from this test:
          ----------SERVER LOG START-----------
          2019-07-04 12:05:00 139732042729280 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (mysqld 10.2.26-MariaDB-debug-log) starting as process 8028 ...
          2019-07-04 12:05:00 139732042729280 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4190)
          2019-07-04 12:05:00 139732042729280 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
          2019-07-04 12:05:00 139732042729280 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members.
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Read nil XID from storage engines, skipping position init
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so'
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_load(): Galera 3.26(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: CRC-32C: using hardware acceleration.
          2019-07-04 12:05:00 139732042729280 [Warning] WSREP: Could not open state file for reading: '/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//grastate.dat'
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Passing config to GCS: base_dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; base_host = 127.0.0.1; base_port = 16002; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//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
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_sst_grab()
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Start replication
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: protonet asio version 0
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Using CRC-32C for message checksums.
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: initializing ssl context
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: backend: asio
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm thread scheduling priority set to other:0 
          2019-07-04 12:05:00 139732042729280 [Warning] WSREP: access file(/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//gvwstate.dat) failed(No such file or directory)
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: restore pc from disk failed
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: GMCast version 0
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') listening at ssl://0.0.0.0:16002
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') multicast: , ttl: 1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: EVS version 0
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer ''
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: start_prim is enabled, turn off pc_recovery
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Node cd82fa2e state prim
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: view(view_id(PRIM,cd82fa2e,1) memb {
          	cd82fa2e,0
          } joined {
          } left {
          } partitioned {
          })
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: save pc into disk
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm: connected
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Opened channel 'my_wsrep_cluster'
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: Waiting for SST to complete.
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Starting new group from scratch: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE_EXCHANGE: sent state UUID: cd8417c9-9e3a-11e9-8514-6b60d7222612
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE EXCHANGE: sent state msg: cd8417c9-9e3a-11e9-8514-6b60d7222612
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cd8417c9-9e3a-11e9-8514-6b60d7222612 from 0 (jan-HP-ZBook-15u-G5)
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Quorum results:
          	version    = 4,
          	component  = PRIMARY,
          	conf_id    = 0,
          	members    = 1/1 (joined/total),
          	act_id     = 0,
          	last_appl. = -1,
          	protocols  = 0/9/3 (gcs/repl/appl),
          	group UUID = cd8416ae-9e3a-11e9-baa6-eeb59ff3def7
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Flow-control interval: [16, 16]
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Trying to continue unpaused monitor
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Restored state OPEN -> JOINED (0)
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Member 0.0 (jan-HP-ZBook-15u-G5) synced with group.
          2019-07-04 12:05:00 139731711018752 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3
          2019-07-04 12:05:00 139732042729280 [Note] WSREP: SST complete, seqno: 0
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'SEQUENCE' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'partition' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Uses event mutexes
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Compressed tables use zlib 1.2.11
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Number of pools: 1
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Using SSE2 crc32 instructions
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Completed initialization of buffer pool
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:42602 local endpoint ssl://127.0.0.1:16002 cipher: TLS_AES_256_GCM_SHA384 compression: none
          2019-07-04 12:05:00 139731434194688 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') connection established to cd859add ssl://127.0.0.1:16005
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: 
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Highest supported file format is Barracuda.
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: 128 out of 128 rollback segments are active.
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Creating shared tablespace for temporary tables
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Waiting for purge to start
          2019-07-04 12:05:00 139732042729280 [Note] InnoDB: 5.7.26 started; log sequence number 1633870
          2019-07-04 12:05:00 139730989610752 [Note] InnoDB: Loading buffer pool(s) from /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/ib_buffer_pool
          2019-07-04 12:05:00 139730989610752 [Note] InnoDB: Buffer pool(s) load completed at 190704 12:05:00
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'FEEDBACK' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'user_variables' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2019-07-04 12:05:00 139732042729280 [Warning] /home/jan/mysql/10.2-sst/sql/mysqld: unknown option '--loose-pam-debug'
          2019-07-04 12:05:00 139732042729280 [Note] Server socket created on IP: '127.0.0.1'.
          2019-07-04 12:05:00 139732042729280 [Note] Reading of all Master_info entries succeeded
          2019-07-04 12:05:00 139732042729280 [Note] Added new Master_info '' to hash table
          2019-07-04 12:05:00 139732042729280 [Note] /home/jan/mysql/10.2-sst/sql/mysqld: ready for connections.
          Version: '10.2.26-MariaDB-debug-log'  socket: '/home/jan/mysql/10.2-sst/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: REPL Protocols: 9 (4, 2)
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4
          2019-07-04 12:05:00 139731960522496 [Note] WSREP: Service thread queue flushed.
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: Synchronized with group, ready for connections
          2019-07-04 12:05:00 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: declaring cd859add at ssl://127.0.0.1:16005 stable
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: Node cd82fa2e state prim
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: view(view_id(PRIM,cd82fa2e,2) memb {
          	cd82fa2e,0
          	cd859add,0
          } joined {
          } left {
          } partitioned {
          })
          2019-07-04 12:05:00 139731719411456 [Note] WSREP: save pc into disk
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE_EXCHANGE: sent state UUID: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: sent state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 from 0 (jan-HP-ZBook-15u-G5)
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 from 1 (jan-HP-ZBook-15u-G5)
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: Quorum results:
          	version    = 4,
          	component  = PRIMARY,
          	conf_id    = 1,
          	members    = 1/2 (joined/total),
          	act_id     = 0,
          	last_appl. = 0,
          	protocols  = 0/9/3 (gcs/repl/appl),
          	group UUID = cd8416ae-9e3a-11e9-baa6-eeb59ff3def7
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: Flow-control interval: [23, 23]
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: Trying to continue unpaused monitor
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# 2: Primary, number of nodes: 2, my index: 0, protocol version 3
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: REPL Protocols: 9 (4, 2)
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4
          2019-07-04 12:05:01 139731960522496 [Note] WSREP: Service thread queue flushed.
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: Member 1.0 (jan-HP-ZBook-15u-G5) requested state transfer from '*any*'. Selected 0.0 (jan-HP-ZBook-15u-G5)(SYNCED) as donor.
          2019-07-04 12:05:01 139731711018752 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 0)
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:01 139730947647232 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'donor' --address '127.0.0.1:16007/rsync_sst' --socket '/home/jan/mysql/10.2-sst/mysql-test/var/tmp/mysqld.1.sock' --datadir '/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/'  --defaults-file '/home/jan/mysql/10.2-sst/mysql-test/var/my.cnf' --defaults-group-suffix '.1'    '' --gtid 'cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0' --gtid-domain-id '0''
          2019-07-04 12:05:01 139731935037184 [Note] WSREP: sst_donor_thread signaled with 0
          2019-07-04 12:05:01 139730947647232 [Note] WSREP: Flushing tables for SST...
          2019-07-04 12:05:01 139730947647232 [Note] WSREP: Provider paused at cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0 (5)
          2019-07-04 12:05:01 139730947647232 [Note] WSREP: Tables flushed.
          2019-07-04 12:05:02 139730947647232 [Note] WSREP: resuming provider at 5
          2019-07-04 12:05:02 139730947647232 [Note] WSREP: Provider resumed.
          2019-07-04 12:05:02 139731711018752 [Note] WSREP: 0.0 (jan-HP-ZBook-15u-G5): State transfer to 1.0 (jan-HP-ZBook-15u-G5) complete.
          2019-07-04 12:05:02 139731711018752 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 0)
          2019-07-04 12:05:02 139731711018752 [Note] WSREP: Member 0.0 (jan-HP-ZBook-15u-G5) synced with group.
          2019-07-04 12:05:02 139731711018752 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0)
          2019-07-04 12:05:02 139731935037184 [Note] WSREP: Synchronized with group, ready for connections
          2019-07-04 12:05:02 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:03 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting off
          2019-07-04 12:05:04 139731711018752 [Note] WSREP: 1.0 (jan-HP-ZBook-15u-G5): State transfer from 0.0 (jan-HP-ZBook-15u-G5) complete.
          2019-07-04 12:05:04 139731711018752 [Note] WSREP: Member 1.0 (jan-HP-ZBook-15u-G5) synced with group.
          2019-07-04 12:05:07 139731689322240 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (initiated by: root[root] @ localhost [127.0.0.1]): Normal shutdown
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: Stop replication
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: Closing send monitor...
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: Closed send monitor.
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: terminating thread
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: joining thread
          2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: closing backend
          2019-07-04 12:05:10 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') connection to peer cd859add with addr ssl://127.0.0.1:16005 timed out, no messages seen in PT3S
          2019-07-04 12:05:10 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: ssl://127.0.0.1:16005 
          2019-07-04 12:05:11 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') reconnecting to cd859add (ssl://127.0.0.1:16005), attempt 0
          2019-07-04 12:05:11 139731689322240 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:16005 local endpoint ssl://127.0.0.1:47326 cipher: TLS_AES_256_GCM_SHA384 compression: none
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: evs::proto(cd82fa2e, LEAVING, view_id(REG,cd82fa2e,2)) suspecting node: cd859add
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: evs::proto(cd82fa2e, LEAVING, view_id(REG,cd82fa2e,2)) suspected node without join message, declaring inactive
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: view(view_id(NON_PRIM,cd82fa2e,2) memb {
          	cd82fa2e,0
          } joined {
          } left {
          } partitioned {
          	cd859add,0
          })
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: view((empty))
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: gcomm: closed
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Flow-control interval: [16, 16]
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Trying to continue unpaused monitor
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received NON-PRIMARY.
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 0)
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received self-leave message.
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Flow-control interval: [0, 0]
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Trying to continue unpaused monitor
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received SELF-LEAVE. Closing connection.
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 0)
          2019-07-04 12:05:12 139731711018752 [Note] WSREP: RECV thread exiting 0: Success
          2019-07-04 12:05:12 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3
          2019-07-04 12:05:12 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:12 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3
          2019-07-04 12:05:12 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: recv_thread() joined.
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: Closing replication queue.
          2019-07-04 12:05:12 139731689322240 [Note] WSREP: Closing slave action queue.
          2019-07-04 12:05:12 139731935037184 [Note] WSREP: applier thread exiting (code:0)
          2019-07-04 12:05:14 139731935344384 [Note] WSREP: rollbacker thread exiting
          2019-07-04 12:05:14 139731107043072 [Note] InnoDB: FTS optimize thread exiting.
          2019-07-04 12:05:14 139731689322240 [Note] Event Scheduler: Purging the queue. 0 events
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: dtor state: CLOSED
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: apply mon: entered 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: apply mon: entered 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: mon: entered 9 oooe fraction 0 oool fraction 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert index usage at exit 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert trx map usage at exit 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: deps set usage at exit 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: avg deps dist 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: avg cert interval 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert index size 0
          2019-07-04 12:05:14 139731960522496 [Note] WSREP: Service thread queue flushed.
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 0)
          2019-07-04 12:05:14 139731689322240 [Note] WSREP: Flushing memory map to disk...
          2019-07-04 12:05:14 139731689322240 [Note] InnoDB: Starting shutdown...
          2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Dumping buffer pool(s) to /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/ib_buffer_pool
          2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Instance 0, restricted to 127 pages due to innodb_buf_pool_dump_pct=25
          2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Buffer pool(s) dump completed at 190704 12:05:14
          2019-07-04 12:05:15 139731689322240 [Note] InnoDB: Shutdown completed; log sequence number 1634062
          2019-07-04 12:05:15 139731689322240 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
          2019-07-04 12:05:15 139731689322240 [Note] Debug sync points hit:                   1065
          2019-07-04 12:05:15 139731689322240 [Note] Debug sync points executed:              0
          2019-07-04 12:05:15 139731689322240 [Note] Debug sync points max active per thread: 0
          2019-07-04 12:05:15 139731689322240 [Note] /home/jan/mysql/10.2-sst/sql/mysqld: Shutdown complete
           
          2019-07-04 12:05:16 140433919133504 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (mysqld 10.2.26-MariaDB-debug-log) starting as process 8266 ...
          2019-07-04 12:05:16 140433919133504 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4190)
          2019-07-04 12:05:16 140433919133504 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
          2019-07-04 12:05:16 140433919133504 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members.
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Read nil XID from storage engines, skipping position init
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so'
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_load(): Galera 3.26(rXXXX) by Codership Oy <info@codership.com> loaded successfully.
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: CRC-32C: using hardware acceleration.
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Found saved state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, safe_to_bootstrap: 0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Passing config to GCS: base_dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; base_host = 127.0.0.1; base_port = ; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//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
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Assign initial position for certification: 0, protocol version: -1
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_sst_grab()
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Start replication
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Setting initial position to cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: protonet asio version 0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: Using CRC-32C for message checksums.
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: initializing ssl context
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: backend: asio
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: gcomm thread scheduling priority set to other:0 
          2019-07-04 12:05:16 140433919133504 [Warning] WSREP: access file(/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//gvwstate.dat) failed(No such file or directory)
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: restore pc from disk failed
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: GMCast version 0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: (d6f5cc64, 'ssl://0.0.0.0:0') listening at ssl://0.0.0.0:0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: (d6f5cc64, 'ssl://0.0.0.0:0') multicast: , ttl: 1
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: EVS version 0
          2019-07-04 12:05:16 140433919133504 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:'
          2019-07-04 12:05:19 140433919133504 [Warning] WSREP: no nodes coming from prim view, prim not possible
          2019-07-04 12:05:19 140433919133504 [Note] WSREP: view(view_id(NON_PRIM,d6f5cc64,1) memb {
          	d6f5cc64,0
          } joined {
          } left {
          } partitioned {
          })
          2019-07-04 12:05:19 140433919133504 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.50217S), skipping check
          2019-07-04 12:05:49 140433919133504 [Note] WSREP: view((empty))
          2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: failed to open gcomm backend connection: 110: failed to reach primary view: 110 (Connection timed out)
          	 at gcomm/src/pc.cpp:connect():158
          2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs/src/gcs_core.cpp:gcs_core_open():209: Failed to open backend connection: -110 (Connection timed out)
          2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs/src/gcs.cpp:gcs_open():1458: Failed to open channel 'my_wsrep_cluster' at 'gcomm://127.0.0.1:': -110 (Connection timed out)
          2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs connect failed: Connection timed out
          2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: wsrep::connect(gcomm://127.0.0.1:) failed: 7
          2019-07-04 12:05:49 140433919133504 [ERROR] Aborting
           
          ----------SERVER LOG END-------------
          mysqltest failed but provided no output
          The result from queries just before the failure was:
          < snip >
          SELECT VARIABLE_VALUE = 'Synced' FROM INFORMATION_SCHEMA.GLOBAL_STATUS WHERE VARIABLE_NAME = 'wsrep_local_state_comment';
          VARIABLE_VALUE = 'Synced'
          1
          SELECT VARIABLE_VALUE = 2 FROM INFORMATION_SCHEMA.GLOBAL_STATUS WHERE VARIABLE_NAME = 'wsrep_cluster_size';
          VARIABLE_VALUE = 2
          1
          connection node_1;
           
           
           
           - saving '/home/jan/mysql/10.2-sst/mysql-test/var/log/galera.galera_ssl_upgrade-innodb/' to '/home/jan/mysql/10.2-sst/mysql-test/var/log/galera.galera_ssl_upgrade-innodb/'
          --------------------------------------------------------------------------
          The servers were restarted 0 times
          Spent 0.000 of 53 seconds executing testcases
           
          Failure: Failed 1/1 tests, 0.00% were successful.
           
          Failing test(s): galera.galera_ssl_upgrade
          
          

          jplindst Jan Lindström (Inactive) added a comment - After adding missing SSL certifications galera.galera_ssl_upgrade 'innodb' [ fail ] Test ended at 2019-07-04 12:05:50   CURRENT_TEST: galera.galera_ssl_upgrade     Server [mysqld.1 - pid: 8265, winpid: 8265, exit: 256] failed during test run Server log from this test: ----------SERVER LOG START----------- 2019-07-04 12:05:00 139732042729280 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (mysqld 10.2.26-MariaDB-debug-log) starting as process 8028 ... 2019-07-04 12:05:00 139732042729280 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4190) 2019-07-04 12:05:00 139732042729280 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000) 2019-07-04 12:05:00 139732042729280 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members. 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Read nil XID from storage engines, skipping position init 2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so' 2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_load(): Galera 3.26(rXXXX) by Codership Oy <info@codership.com> loaded successfully. 2019-07-04 12:05:00 139732042729280 [Note] WSREP: CRC-32C: using hardware acceleration. 2019-07-04 12:05:00 139732042729280 [Warning] WSREP: Could not open state file for reading: '/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//grastate.dat' 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Passing config to GCS: base_dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; base_host = 127.0.0.1; base_port = 16002; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//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 2019-07-04 12:05:00 139732042729280 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: wsrep_sst_grab() 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Start replication 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: protonet asio version 0 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Using CRC-32C for message checksums. 2019-07-04 12:05:00 139732042729280 [Note] WSREP: initializing ssl context 2019-07-04 12:05:00 139732042729280 [Note] WSREP: backend: asio 2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm thread scheduling priority set to other:0 2019-07-04 12:05:00 139732042729280 [Warning] WSREP: access file(/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//gvwstate.dat) failed(No such file or directory) 2019-07-04 12:05:00 139732042729280 [Note] WSREP: restore pc from disk failed 2019-07-04 12:05:00 139732042729280 [Note] WSREP: GMCast version 0 2019-07-04 12:05:00 139732042729280 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') listening at ssl://0.0.0.0:16002 2019-07-04 12:05:00 139732042729280 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') multicast: , ttl: 1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: EVS version 0 2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '' 2019-07-04 12:05:00 139732042729280 [Note] WSREP: start_prim is enabled, turn off pc_recovery 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Node cd82fa2e state prim 2019-07-04 12:05:00 139732042729280 [Note] WSREP: view(view_id(PRIM,cd82fa2e,1) memb { cd82fa2e,0 } joined { } left { } partitioned { }) 2019-07-04 12:05:00 139732042729280 [Note] WSREP: save pc into disk 2019-07-04 12:05:00 139732042729280 [Note] WSREP: gcomm: connected 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0) 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Opened channel 'my_wsrep_cluster' 2019-07-04 12:05:00 139731711018752 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1 2019-07-04 12:05:00 139732042729280 [Note] WSREP: Waiting for SST to complete. 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Starting new group from scratch: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7 2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE_EXCHANGE: sent state UUID: cd8417c9-9e3a-11e9-8514-6b60d7222612 2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE EXCHANGE: sent state msg: cd8417c9-9e3a-11e9-8514-6b60d7222612 2019-07-04 12:05:00 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cd8417c9-9e3a-11e9-8514-6b60d7222612 from 0 (jan-HP-ZBook-15u-G5) 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 0, members = 1/1 (joined/total), act_id = 0, last_appl. = -1, protocols = 0/9/3 (gcs/repl/appl), group UUID = cd8416ae-9e3a-11e9-baa6-eeb59ff3def7 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Flow-control interval: [16, 16] 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Trying to continue unpaused monitor 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Restored state OPEN -> JOINED (0) 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Member 0.0 (jan-HP-ZBook-15u-G5) synced with group. 2019-07-04 12:05:00 139731711018752 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0) 2019-07-04 12:05:00 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# 1: Primary, number of nodes: 1, my index: 0, protocol version 3 2019-07-04 12:05:00 139732042729280 [Note] WSREP: SST complete, seqno: 0 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'SEQUENCE' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'partition' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Uses event mutexes 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Compressed tables use zlib 1.2.11 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Number of pools: 1 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Using SSE2 crc32 instructions 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Completed initialization of buffer pool 2019-07-04 12:05:00 139731719411456 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:42602 local endpoint ssl://127.0.0.1:16002 cipher: TLS_AES_256_GCM_SHA384 compression: none 2019-07-04 12:05:00 139731434194688 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority(). 2019-07-04 12:05:00 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') connection established to cd859add ssl://127.0.0.1:16005 2019-07-04 12:05:00 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Highest supported file format is Barracuda. 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: 128 out of 128 rollback segments are active. 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Creating shared tablespace for temporary tables 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ... 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: File './ibtmp1' size is now 12 MB. 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: Waiting for purge to start 2019-07-04 12:05:00 139732042729280 [Note] InnoDB: 5.7.26 started; log sequence number 1633870 2019-07-04 12:05:00 139730989610752 [Note] InnoDB: Loading buffer pool(s) from /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/ib_buffer_pool 2019-07-04 12:05:00 139730989610752 [Note] InnoDB: Buffer pool(s) load completed at 190704 12:05:00 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'FEEDBACK' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'user_variables' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2019-07-04 12:05:00 139732042729280 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2019-07-04 12:05:00 139732042729280 [Warning] /home/jan/mysql/10.2-sst/sql/mysqld: unknown option '--loose-pam-debug' 2019-07-04 12:05:00 139732042729280 [Note] Server socket created on IP: '127.0.0.1'. 2019-07-04 12:05:00 139732042729280 [Note] Reading of all Master_info entries succeeded 2019-07-04 12:05:00 139732042729280 [Note] Added new Master_info '' to hash table 2019-07-04 12:05:00 139732042729280 [Note] /home/jan/mysql/10.2-sst/sql/mysqld: ready for connections. Version: '10.2.26-MariaDB-debug-log' socket: '/home/jan/mysql/10.2-sst/mysql-test/var/tmp/mysqld.1.sock' port: 16000 Source distribution 2019-07-04 12:05:00 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:00 139731935037184 [Note] WSREP: REPL Protocols: 9 (4, 2) 2019-07-04 12:05:00 139731935037184 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4 2019-07-04 12:05:00 139731960522496 [Note] WSREP: Service thread queue flushed. 2019-07-04 12:05:00 139731935037184 [Note] WSREP: GCache history reset: 00000000-0000-0000-0000-000000000000:0 -> cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0 2019-07-04 12:05:00 139731935037184 [Note] WSREP: Synchronized with group, ready for connections 2019-07-04 12:05:00 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:00 139731719411456 [Note] WSREP: declaring cd859add at ssl://127.0.0.1:16005 stable 2019-07-04 12:05:00 139731719411456 [Note] WSREP: Node cd82fa2e state prim 2019-07-04 12:05:00 139731719411456 [Note] WSREP: view(view_id(PRIM,cd82fa2e,2) memb { cd82fa2e,0 cd859add,0 } joined { } left { } partitioned { }) 2019-07-04 12:05:00 139731719411456 [Note] WSREP: save pc into disk 2019-07-04 12:05:01 139731711018752 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2 2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE_EXCHANGE: sent state UUID: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: sent state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 from 0 (jan-HP-ZBook-15u-G5) 2019-07-04 12:05:01 139731711018752 [Note] WSREP: STATE EXCHANGE: got state msg: cdd48359-9e3a-11e9-9e8c-ce6f919a1fd4 from 1 (jan-HP-ZBook-15u-G5) 2019-07-04 12:05:01 139731711018752 [Note] WSREP: Quorum results: version = 4, component = PRIMARY, conf_id = 1, members = 1/2 (joined/total), act_id = 0, last_appl. = 0, protocols = 0/9/3 (gcs/repl/appl), group UUID = cd8416ae-9e3a-11e9-baa6-eeb59ff3def7 2019-07-04 12:05:01 139731711018752 [Note] WSREP: Flow-control interval: [23, 23] 2019-07-04 12:05:01 139731711018752 [Note] WSREP: Trying to continue unpaused monitor 2019-07-04 12:05:01 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# 2: Primary, number of nodes: 2, my index: 0, protocol version 3 2019-07-04 12:05:01 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:01 139731935037184 [Note] WSREP: REPL Protocols: 9 (4, 2) 2019-07-04 12:05:01 139731935037184 [Note] WSREP: Assign initial position for certification: 0, protocol version: 4 2019-07-04 12:05:01 139731960522496 [Note] WSREP: Service thread queue flushed. 2019-07-04 12:05:01 139731711018752 [Note] WSREP: Member 1.0 (jan-HP-ZBook-15u-G5) requested state transfer from '*any*'. Selected 0.0 (jan-HP-ZBook-15u-G5)(SYNCED) as donor. 2019-07-04 12:05:01 139731711018752 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 0) 2019-07-04 12:05:01 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:01 139730947647232 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'donor' --address '127.0.0.1:16007/rsync_sst' --socket '/home/jan/mysql/10.2-sst/mysql-test/var/tmp/mysqld.1.sock' --datadir '/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/' --defaults-file '/home/jan/mysql/10.2-sst/mysql-test/var/my.cnf' --defaults-group-suffix '.1' '' --gtid 'cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0' --gtid-domain-id '0'' 2019-07-04 12:05:01 139731935037184 [Note] WSREP: sst_donor_thread signaled with 0 2019-07-04 12:05:01 139730947647232 [Note] WSREP: Flushing tables for SST... 2019-07-04 12:05:01 139730947647232 [Note] WSREP: Provider paused at cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0 (5) 2019-07-04 12:05:01 139730947647232 [Note] WSREP: Tables flushed. 2019-07-04 12:05:02 139730947647232 [Note] WSREP: resuming provider at 5 2019-07-04 12:05:02 139730947647232 [Note] WSREP: Provider resumed. 2019-07-04 12:05:02 139731711018752 [Note] WSREP: 0.0 (jan-HP-ZBook-15u-G5): State transfer to 1.0 (jan-HP-ZBook-15u-G5) complete. 2019-07-04 12:05:02 139731711018752 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 0) 2019-07-04 12:05:02 139731711018752 [Note] WSREP: Member 0.0 (jan-HP-ZBook-15u-G5) synced with group. 2019-07-04 12:05:02 139731711018752 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 0) 2019-07-04 12:05:02 139731935037184 [Note] WSREP: Synchronized with group, ready for connections 2019-07-04 12:05:02 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:03 139731719411456 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting off 2019-07-04 12:05:04 139731711018752 [Note] WSREP: 1.0 (jan-HP-ZBook-15u-G5): State transfer from 0.0 (jan-HP-ZBook-15u-G5) complete. 2019-07-04 12:05:04 139731711018752 [Note] WSREP: Member 1.0 (jan-HP-ZBook-15u-G5) synced with group. 2019-07-04 12:05:07 139731689322240 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (initiated by: root[root] @ localhost [127.0.0.1]): Normal shutdown 2019-07-04 12:05:07 139731689322240 [Note] WSREP: Stop replication 2019-07-04 12:05:07 139731689322240 [Note] WSREP: Closing send monitor... 2019-07-04 12:05:07 139731689322240 [Note] WSREP: Closed send monitor. 2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: terminating thread 2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: joining thread 2019-07-04 12:05:07 139731689322240 [Note] WSREP: gcomm: closing backend 2019-07-04 12:05:10 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') connection to peer cd859add with addr ssl://127.0.0.1:16005 timed out, no messages seen in PT3S 2019-07-04 12:05:10 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') turning message relay requesting on, nonlive peers: ssl://127.0.0.1:16005 2019-07-04 12:05:11 139731689322240 [Note] WSREP: (cd82fa2e, 'ssl://0.0.0.0:16002') reconnecting to cd859add (ssl://127.0.0.1:16005), attempt 0 2019-07-04 12:05:11 139731689322240 [Note] WSREP: SSL handshake successful, remote endpoint ssl://127.0.0.1:16005 local endpoint ssl://127.0.0.1:47326 cipher: TLS_AES_256_GCM_SHA384 compression: none 2019-07-04 12:05:12 139731689322240 [Note] WSREP: evs::proto(cd82fa2e, LEAVING, view_id(REG,cd82fa2e,2)) suspecting node: cd859add 2019-07-04 12:05:12 139731689322240 [Note] WSREP: evs::proto(cd82fa2e, LEAVING, view_id(REG,cd82fa2e,2)) suspected node without join message, declaring inactive 2019-07-04 12:05:12 139731689322240 [Note] WSREP: view(view_id(NON_PRIM,cd82fa2e,2) memb { cd82fa2e,0 } joined { } left { } partitioned { cd859add,0 }) 2019-07-04 12:05:12 139731689322240 [Note] WSREP: view((empty)) 2019-07-04 12:05:12 139731689322240 [Note] WSREP: gcomm: closed 2019-07-04 12:05:12 139731711018752 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Flow-control interval: [16, 16] 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Trying to continue unpaused monitor 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received NON-PRIMARY. 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Shifting SYNCED -> OPEN (TO: 0) 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received self-leave message. 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Flow-control interval: [0, 0] 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Trying to continue unpaused monitor 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Received SELF-LEAVE. Closing connection. 2019-07-04 12:05:12 139731711018752 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 0) 2019-07-04 12:05:12 139731711018752 [Note] WSREP: RECV thread exiting 0: Success 2019-07-04 12:05:12 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# -1: non-Primary, number of nodes: 1, my index: 0, protocol version 3 2019-07-04 12:05:12 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:12 139731935037184 [Note] WSREP: New cluster view: global state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, view# -1: non-Primary, number of nodes: 0, my index: -1, protocol version 3 2019-07-04 12:05:12 139731935037184 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification. 2019-07-04 12:05:12 139731689322240 [Note] WSREP: recv_thread() joined. 2019-07-04 12:05:12 139731689322240 [Note] WSREP: Closing replication queue. 2019-07-04 12:05:12 139731689322240 [Note] WSREP: Closing slave action queue. 2019-07-04 12:05:12 139731935037184 [Note] WSREP: applier thread exiting (code:0) 2019-07-04 12:05:14 139731935344384 [Note] WSREP: rollbacker thread exiting 2019-07-04 12:05:14 139731107043072 [Note] InnoDB: FTS optimize thread exiting. 2019-07-04 12:05:14 139731689322240 [Note] Event Scheduler: Purging the queue. 0 events 2019-07-04 12:05:14 139731689322240 [Note] WSREP: dtor state: CLOSED 2019-07-04 12:05:14 139731689322240 [Note] WSREP: apply mon: entered 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: apply mon: entered 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: mon: entered 9 oooe fraction 0 oool fraction 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert index usage at exit 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert trx map usage at exit 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: deps set usage at exit 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: avg deps dist 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: avg cert interval 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: cert index size 0 2019-07-04 12:05:14 139731960522496 [Note] WSREP: Service thread queue flushed. 2019-07-04 12:05:14 139731689322240 [Note] WSREP: wsdb trx map usage 0 conn query map usage 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: MemPool(LocalTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: MemPool(SlaveTrxHandle): hit ratio: 0, misses: 0, in use: 0, in pool: 0 2019-07-04 12:05:14 139731689322240 [Note] WSREP: Shifting CLOSED -> DESTROYED (TO: 0) 2019-07-04 12:05:14 139731689322240 [Note] WSREP: Flushing memory map to disk... 2019-07-04 12:05:14 139731689322240 [Note] InnoDB: Starting shutdown... 2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Dumping buffer pool(s) to /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/ib_buffer_pool 2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Instance 0, restricted to 127 pages due to innodb_buf_pool_dump_pct=25 2019-07-04 12:05:14 139730989610752 [Note] InnoDB: Buffer pool(s) dump completed at 190704 12:05:14 2019-07-04 12:05:15 139731689322240 [Note] InnoDB: Shutdown completed; log sequence number 1634062 2019-07-04 12:05:15 139731689322240 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1" 2019-07-04 12:05:15 139731689322240 [Note] Debug sync points hit: 1065 2019-07-04 12:05:15 139731689322240 [Note] Debug sync points executed: 0 2019-07-04 12:05:15 139731689322240 [Note] Debug sync points max active per thread: 0 2019-07-04 12:05:15 139731689322240 [Note] /home/jan/mysql/10.2-sst/sql/mysqld: Shutdown complete   2019-07-04 12:05:16 140433919133504 [Note] /home/jan/mysql/10.2-sst/sql/mysqld (mysqld 10.2.26-MariaDB-debug-log) starting as process 8266 ... 2019-07-04 12:05:16 140433919133504 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4190) 2019-07-04 12:05:16 140433919133504 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000) 2019-07-04 12:05:16 140433919133504 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16004' which makes it impossible for another host to reach this one. Please set it to the address which this node can be connected at by other cluster members. 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Read nil XID from storage engines, skipping position init 2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib/libgalera_smm.so' 2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_load(): Galera 3.26(rXXXX) by Codership Oy <info@codership.com> loaded successfully. 2019-07-04 12:05:16 140433919133504 [Note] WSREP: CRC-32C: using hardware acceleration. 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Found saved state: cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0, safe_to_bootstrap: 0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Passing config to GCS: base_dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; base_host = 127.0.0.1; base_port = ; 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 = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT5S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//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 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Assign initial position for certification: 0, protocol version: -1 2019-07-04 12:05:16 140433919133504 [Note] WSREP: wsrep_sst_grab() 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Start replication 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Setting initial position to cd8416ae-9e3a-11e9-baa6-eeb59ff3def7:0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: protonet asio version 0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: Using CRC-32C for message checksums. 2019-07-04 12:05:16 140433919133504 [Note] WSREP: initializing ssl context 2019-07-04 12:05:16 140433919133504 [Note] WSREP: backend: asio 2019-07-04 12:05:16 140433919133504 [Note] WSREP: gcomm thread scheduling priority set to other:0 2019-07-04 12:05:16 140433919133504 [Warning] WSREP: access file(/home/jan/mysql/10.2-sst/mysql-test/var/mysqld.1/data//gvwstate.dat) failed(No such file or directory) 2019-07-04 12:05:16 140433919133504 [Note] WSREP: restore pc from disk failed 2019-07-04 12:05:16 140433919133504 [Note] WSREP: GMCast version 0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: (d6f5cc64, 'ssl://0.0.0.0:0') listening at ssl://0.0.0.0:0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: (d6f5cc64, 'ssl://0.0.0.0:0') multicast: , ttl: 1 2019-07-04 12:05:16 140433919133504 [Note] WSREP: EVS version 0 2019-07-04 12:05:16 140433919133504 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:' 2019-07-04 12:05:19 140433919133504 [Warning] WSREP: no nodes coming from prim view, prim not possible 2019-07-04 12:05:19 140433919133504 [Note] WSREP: view(view_id(NON_PRIM,d6f5cc64,1) memb { d6f5cc64,0 } joined { } left { } partitioned { }) 2019-07-04 12:05:19 140433919133504 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.50217S), skipping check 2019-07-04 12:05:49 140433919133504 [Note] WSREP: view((empty)) 2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: failed to open gcomm backend connection: 110: failed to reach primary view: 110 (Connection timed out) at gcomm/src/pc.cpp:connect():158 2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs/src/gcs_core.cpp:gcs_core_open():209: Failed to open backend connection: -110 (Connection timed out) 2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs/src/gcs.cpp:gcs_open():1458: Failed to open channel 'my_wsrep_cluster' at 'gcomm://127.0.0.1:': -110 (Connection timed out) 2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: gcs connect failed: Connection timed out 2019-07-04 12:05:49 140433919133504 [ERROR] WSREP: wsrep::connect(gcomm://127.0.0.1:) failed: 7 2019-07-04 12:05:49 140433919133504 [ERROR] Aborting   ----------SERVER LOG END------------- mysqltest failed but provided no output The result from queries just before the failure was: < snip > SELECT VARIABLE_VALUE = 'Synced' FROM INFORMATION_SCHEMA.GLOBAL_STATUS WHERE VARIABLE_NAME = 'wsrep_local_state_comment'; VARIABLE_VALUE = 'Synced' 1 SELECT VARIABLE_VALUE = 2 FROM INFORMATION_SCHEMA.GLOBAL_STATUS WHERE VARIABLE_NAME = 'wsrep_cluster_size'; VARIABLE_VALUE = 2 1 connection node_1;       - saving '/home/jan/mysql/10.2-sst/mysql-test/var/log/galera.galera_ssl_upgrade-innodb/' to '/home/jan/mysql/10.2-sst/mysql-test/var/log/galera.galera_ssl_upgrade-innodb/' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 53 seconds executing testcases   Failure: Failed 1/1 tests, 0.00% were successful.   Failing test(s): galera.galera_ssl_upgrade

          ok to push if buildbot tests are green please wait at least kvm-fulltest that is not yet finished.

          jplindst Jan Lindström (Inactive) added a comment - ok to push if buildbot tests are green please wait at least kvm-fulltest that is not yet finished.
          sysprg Julius Goryavsky added a comment - Fixed & merged with head revision: https://github.com/MariaDB/server/commit/95003eab451cac2fd2c0552f374a85842f2773f2

          People

            sysprg Julius Goryavsky
            jplindst Jan Lindström (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Git Integration

                Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.