Details
-
Bug
-
Status: Closed (View Workflow)
-
Major
-
Resolution: Fixed
-
10.3.6
-
None
Description
galera test galera_many_tables_nopk from mtr galera test suite
crashed mysqld got signal 6
Thread pointer: 0x0
|
Attempting backtrace. You can use the following information to find out
|
where mysqld died. If you see no messages after this, something went
|
terribly wrong...
|
stack_bottom = 0x0 thread_stack 0x49000
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(my_print_stacktrace+0x29)[0x5593f3803dd9]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(handle_fatal_signal+0x33f)[0x5593f333e53f]
|
2018-04-24 16:22:50 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 00000000 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
/lib64/libpthread.so.0(+0xf5e0)[0x7fccdea625e0]
|
/lib64/libc.so.6(__poll+0x2d)[0x7fccdd037a3d]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(_Z26handle_connections_socketsv+0x20b)[0x5593f30e157b]
|
2018-04-24 16:23:43 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 00000000 with addr timed out, no messages seen in PT3S
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(_Z11mysqld_mainiPPc+0x2b79)[0x5593f30e4759]
|
/lib64/libc.so.6(__libc_start_main+0xf5)[0x7fccdcf6bc05]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(+0x4e2398)[0x5593f30d8398]
|
./mysql-test-run.pl --suite=galera --force --big-test --max-test-fail=0
|
2018-04-24 16:02:46 0 [Note] WSREP: Read nil XID from storage engines, skipping position init
|
2018-04-24 16:02:46 0 [Note] WSREP: wsrep_load(): loading provider library '/usr/lib64/galera/libgalera_smm.so'
|
2018-04-24 16:02:46 0 [Note] WSREP: wsrep_load(): Galera 25.3.22(r3764) by Codership Oy <info@codership.com> loaded successfully.
|
2018-04-24 16:02:46 0 [Note] WSREP: CRC-32C: using "slicing-by-8" algorithm.
|
2018-04-24 16:02:46 0 [Note] WSREP: Found saved state: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:-1, safe_to_bootstrap: 0
|
2018-04-24 16:02:46 0 [Note] WSREP: Passing config to GCS: base_dir = /home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/data/; base_host = 127.0.0.1; base_port = 16005; cert.log_conflicts = no; debug = no; evs.auto_evict = 0; evs.delay_margin = PT1S; evs.delayed_keep_period = PT30S; evs.inactive_check_period = PT0.5S; evs.inactive_timeout = PT30S; evs.install_timeout = PT15S; evs.join_retrans_period = PT1S; evs.max_install_timeouts = 3; evs.send_window = 4; evs.stats_report_period = PT1M; evs.suspect_timeout = PT10S; evs.user_send_window = 2; evs.view_forget_timeout = PT24H; gcache.dir = /home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/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 =
|
2018-04-24 16:02:46 0 [Note] WSREP: GCache history reset: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:0 -> 5707f719-47bf-11e8-a2b8-4683ac5e41e7:-1
|
2018-04-24 16:02:46 0 [Note] WSREP: Assign initial position for certification: -1, protocol version: -1
|
2018-04-24 16:02:46 0 [Note] WSREP: wsrep_sst_grab()
|
2018-04-24 16:02:46 0 [Note] WSREP: Start replication
|
2018-04-24 16:02:46 0 [Note] WSREP: Setting initial position to 00000000-0000-0000-0000-000000000000:-1
|
2018-04-24 16:02:46 0 [Note] WSREP: protonet asio version 0
|
2018-04-24 16:02:46 0 [Note] WSREP: Using CRC-32C for message checksums.
|
2018-04-24 16:02:46 0 [Note] WSREP: backend: asio
|
2018-04-24 16:02:46 0 [Note] WSREP: gcomm thread scheduling priority set to other:0
|
2018-04-24 16:02:46 0 [Note] WSREP: restore pc from disk successfully
|
2018-04-24 16:02:46 0 [Note] WSREP: GMCast version 0
|
2018-04-24 16:02:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') listening at tcp://0.0.0.0:16005
|
2018-04-24 16:02:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') multicast: , ttl: 1
|
2018-04-24 16:02:46 0 [Note] WSREP: EVS version 0
|
2018-04-24 16:02:46 0 [Note] WSREP: gcomm: connecting to group 'my_wsrep_cluster', peer '127.0.0.1:16002'
|
2018-04-24 16:02:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:02:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers:
|
2018-04-24 16:02:47 0 [Note] WSREP: gcomm: connected
|
2018-04-24 16:02:47 0 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
|
2018-04-24 16:02:47 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
|
2018-04-24 16:02:47 0 [Note] WSREP: Opened channel 'my_wsrep_cluster'
|
2018-04-24 16:02:47 0 [Note] WSREP: Waiting for SST to complete.
|
2018-04-24 16:02:47 0 [Note] WSREP: declaring 57076020 at tcp://127.0.0.1:16002 stable
|
2018-04-24 16:02:47 0 [Note] WSREP: Node 57076020 state prim
|
2018-04-24 16:02:47 0 [Note] WSREP: view(view_id(PRIM,57076020,4) memb {
|
57076020,0
|
570d5bae,0
|
} joined {
|
} left {
|
} partitioned {
|
})
|
2018-04-24 16:02:47 0 [Note] WSREP: save pc into disk
|
2018-04-24 16:02:47 0 [Note] WSREP: clear restored view
|
2018-04-24 16:02:47 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 1, memb_num = 2
|
2018-04-24 16:02:47 0 [Note] WSREP: STATE EXCHANGE: Waiting for state UUID.
|
2018-04-24 16:02:47 0 [Note] WSREP: STATE EXCHANGE: sent state msg: c933e8b0-47bf-11e8-937f-9a766d2a47b3
|
2018-04-24 16:02:47 0 [Note] WSREP: STATE EXCHANGE: got state msg: c933e8b0-47bf-11e8-937f-9a766d2a47b3 from 0 (t4w3.xentio.lan)
|
2018-04-24 16:02:47 0 [Note] WSREP: STATE EXCHANGE: got state msg: c933e8b0-47bf-11e8-937f-9a766d2a47b3 from 1 (t4w3.xentio.lan)
|
2018-04-24 16:02:47 0 [Note] WSREP: Quorum results:
|
version = 4,
|
component = PRIMARY,
|
conf_id = 3,
|
members = 1/2 (joined/total),
|
act_id = 1190,
|
last_appl. = -1,
|
protocols = 0/7/3 (gcs/repl/appl),
|
group UUID = 5707f719-47bf-11e8-a2b8-4683ac5e41e7
|
2018-04-24 16:02:47 0 [Note] WSREP: Flow-control interval: [23, 23]
|
2018-04-24 16:02:47 0 [Note] WSREP: Trying to continue unpaused monitor
|
2018-04-24 16:02:47 0 [Note] WSREP: Shifting OPEN -> PRIMARY (TO: 1190)
|
2018-04-24 16:02:47 1 [Note] WSREP: State transfer required:
|
Group state: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:1190
|
Local state: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:-1
|
2018-04-24 16:02:47 1 [Note] WSREP: New cluster view: global state: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:1190, view# 4: Primary, number of nodes: 2, my index: 1, protocol version 3
|
2018-04-24 16:02:47 1 [Warning] WSREP: Gap in state sequence. Need state transfer.
|
2018-04-24 16:02:47 0 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'joiner' --address '127.0.0.1:16007' --datadir '/home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/data/' --defaults-file '/home/qa-user1/rr/build-mariadb/mysql-test/var/my.cnf' --defaults-group-suffix '.2' --parent '13196' '' '
|
2018-04-24 16:02:48 1 [Note] WSREP: Prepared SST request: rsync|127.0.0.1:16007/rsync_sst
|
2018-04-24 16:02:48 1 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
|
2018-04-24 16:02:48 1 [Note] WSREP: REPL Protocols: 7 (3, 2)
|
2018-04-24 16:02:48 1 [Note] WSREP: Assign initial position for certification: 1190, protocol version: 3
|
2018-04-24 16:02:48 0 [Note] WSREP: Service thread queue flushed.
|
2018-04-24 16:02:48 1 [Warning] WSREP: Failed to prepare for incremental state transfer: Local state seqno is undefined: 1 (Operation not permitted)
|
at galera/src/replicator_str.cpp:prepare_for_IST():491. IST will be unavailable.
|
2018-04-24 16:02:48 0 [Note] WSREP: Member 1.0 (t4w3.xentio.lan) requested state transfer from '*any*'. Selected 0.0 (t4w3.xentio.lan)(SYNCED) as donor.
|
2018-04-24 16:02:48 0 [Note] WSREP: Shifting PRIMARY -> JOINER (TO: 1190)
|
2018-04-24 16:02:48 1 [Note] WSREP: Requesting state transfer: success, donor: 0
|
2018-04-24 16:02:48 1 [Note] WSREP: GCache history reset: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:0 -> 5707f719-47bf-11e8-a2b8-4683ac5e41e7:1190
|
2018-04-24 16:02:50 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting off
|
2018-04-24 16:02:56 0 [Note] WSREP: 0.0 (t4w3.xentio.lan): State transfer to 1.0 (t4w3.xentio.lan) complete.
|
2018-04-24 16:02:56 0 [Note] WSREP: Member 0.0 (t4w3.xentio.lan) synced with group.
|
WSREP_SST: [INFO] Joiner cleanup. rsync PID: 13239 (20180424 16:02:56.602)
|
WSREP_SST: [INFO] Joiner cleanup done. (20180424 16:02:57.123)
|
2018-04-24 16:02:57 0 [Note] WSREP: SST complete, seqno: 1190
|
2018-04-24 16:02:57 0 [Note] Plugin 'partition' is disabled.
|
2018-04-24 16:02:57 0 [Note] InnoDB: For Galera, using innodb_lock_schedule_algorithm=fcfs
|
2018-04-24 16:02:57 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
|
2018-04-24 16:02:57 0 [Note] InnoDB: Uses event mutexes
|
2018-04-24 16:02:57 0 [Note] InnoDB: Compressed tables use zlib 1.2.7
|
2018-04-24 16:02:57 0 [Note] InnoDB: Using Linux native AIO
|
2018-04-24 16:02:57 0 [Note] InnoDB: Number of pools: 1
|
2018-04-24 16:02:57 0 [Note] InnoDB: Using generic crc32 instructions
|
2018-04-24 16:02:57 0 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
|
2018-04-24 16:02:57 0 [Note] InnoDB: Completed initialization of buffer pool
|
2018-04-24 16:02:57 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
|
2018-04-24 16:02:57 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=208888189
|
2018-04-24 16:02:57 0 [Note] InnoDB: Starting final batch to recover 351 pages from redo log.
|
2018-04-24 16:02:57 0 [Note] InnoDB: 128 out of 128 rollback segments are active.
|
2018-04-24 16:02:57 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
|
2018-04-24 16:02:57 0 [Note] InnoDB: Creating shared tablespace for temporary tables
|
2018-04-24 16:02:57 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
|
2018-04-24 16:02:57 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
|
2018-04-24 16:02:57 0 [Note] InnoDB: Waiting for purge to start
|
2018-04-24 16:02:57 0 [Note] InnoDB: 5.7.21 started; log sequence number 209540076; transaction id 2622
|
2018-04-24 16:02:57 0 [Note] Plugin 'SEQUENCE' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_MUTEXES' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_CMP' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'FEEDBACK' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'user_variables' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
|
2018-04-24 16:02:57 0 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
|
2018-04-24 16:02:57 0 [Warning] /home/qa-user1/rr/build-mariadb/sql/mysqld: unknown variable 'loose-feedback-debug-startup-interval=20'
|
2018-04-24 16:02:57 0 [Warning] /home/qa-user1/rr/build-mariadb/sql/mysqld: unknown variable 'loose-feedback-debug-first-interval=60'
|
2018-04-24 16:02:57 0 [Warning] /home/qa-user1/rr/build-mariadb/sql/mysqld: unknown variable 'loose-feedback-debug-interval=60'
|
2018-04-24 16:02:57 0 [Warning] /home/qa-user1/rr/build-mariadb/sql/mysqld: unknown option '--loose-pam-debug'
|
2018-04-24 16:02:57 0 [Warning] /home/qa-user1/rr/build-mariadb/sql/mysqld: unknown variable 'loose-debug-sync-timeout=300'
|
2018-04-24 16:02:57 0 [Note] Recovering after a crash using tc.log
|
2018-04-24 16:02:57 0 [Note] Starting crash recovery...
|
2018-04-24 16:02:57 0 [Note] Crash recovery finished.
|
2018-04-24 16:02:57 0 [Note] InnoDB: Loading buffer pool(s) from /home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/data/ib_buffer_pool
|
2018-04-24 16:02:57 0 [Note] Server socket created on IP: '127.0.0.1'.
|
2018-04-24 16:02:57 0 [Note] WSREP: Signalling provider to continue.
|
2018-04-24 16:02:59 0 [Note] InnoDB: Buffer pool(s) load completed at 180424 16:02:59
|
2018-04-24 16:02:59 0 [Note] WSREP: SST received: 5707f719-47bf-11e8-a2b8-4683ac5e41e7:1190
|
2018-04-24 16:02:59 0 [Note] WSREP: 1.0 (t4w3.xentio.lan): State transfer from 0.0 (t4w3.xentio.lan) complete.
|
2018-04-24 16:02:59 0 [Note] WSREP: Shifting JOINER -> JOINED (TO: 1190)
|
2018-04-24 16:02:59 0 [Note] WSREP: Member 1.0 (t4w3.xentio.lan) synced with group.
|
2018-04-24 16:02:59 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 1190)
|
2018-04-24 16:02:59 1 [Note] WSREP: Synchronized with group, ready for connections
|
2018-04-24 16:02:59 1 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
|
2018-04-24 16:02:59 0 [Note] Reading of all Master_info entries succeded
|
2018-04-24 16:02:59 0 [Note] Added new Master_info '' to hash table
|
2018-04-24 16:02:59 0 [Note] /home/qa-user1/rr/build-mariadb/sql/mysqld: ready for connections.
|
Version: '10.3.7-MariaDB-log' socket: '/home/qa-user1/rr/build-mariadb/mysql-test/var/tmp/mysqld.2.sock' port: 16001 Source distribution
|
CURRENT_TEST: galera.galera_many_columns
|
CURRENT_TEST: galera.galera_many_rows
|
CURRENT_TEST: galera.galera_many_tables_nopk
|
2018-04-24 16:18:05 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.97725S), skipping check
|
2018-04-24 16:18:05 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:18:06 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:16002
|
2018-04-24 16:18:08 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.12966S), skipping check
|
2018-04-24 16:18:09 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:18:11 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT5.06177S), skipping check
|
2018-04-24 16:18:11 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:11 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:15 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting off
|
2018-04-24 16:18:16 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.92939S), skipping check
|
2018-04-24 16:18:17 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:18:18 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:16002
|
2018-04-24 16:18:18 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.47293S), skipping check
|
2018-04-24 16:18:19 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:18:20 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.77353S), skipping check
|
2018-04-24 16:18:20 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:20 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:23 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.5754S), skipping check
|
2018-04-24 16:18:24 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting off
|
2018-04-24 16:18:25 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.27279S), skipping check
|
2018-04-24 16:18:27 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.91676S), skipping check
|
2018-04-24 16:18:29 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.58083S), skipping check
|
2018-04-24 16:18:31 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:18:32 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:16002
|
2018-04-24 16:18:32 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.99903S), skipping check
|
2018-04-24 16:18:33 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:18:33 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:33 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:37 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.26624S), skipping check
|
2018-04-24 16:18:38 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:18:38 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.82026S), skipping check
|
2018-04-24 16:18:40 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:18:42 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.44483S), skipping check
|
2018-04-24 16:18:42 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:18:44 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.5172S), skipping check
|
2018-04-24 16:18:45 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting off
|
2018-04-24 16:19:02 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.68651S), skipping check
|
2018-04-24 16:19:05 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.79732S), skipping check
|
2018-04-24 16:19:07 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.32921S), skipping check
|
2018-04-24 16:19:15 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.55216S), skipping check
|
2018-04-24 16:19:20 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.06564S), skipping check
|
2018-04-24 16:19:22 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.75015S), skipping check
|
2018-04-24 16:19:29 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.62047S), skipping check
|
2018-04-24 16:19:31 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.71421S), skipping check
|
2018-04-24 16:19:34 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.22069S), skipping check
|
2018-04-24 16:19:37 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.59165S), skipping check
|
2018-04-24 16:19:38 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:19:39 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:16002
|
2018-04-24 16:19:40 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT3.3857S), skipping check
|
2018-04-24 16:19:40 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:19:41 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.80333S), skipping check
|
2018-04-24 16:19:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:19:46 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.91571S), skipping check
|
2018-04-24 16:19:46 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection established to 57076020 tcp://127.0.0.1:16002
|
2018-04-24 16:19:49 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1.7353S), skipping check
|
2018-04-24 16:19:51 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting off
|
2018-04-24 16:19:52 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.89326S), skipping check
|
2018-04-24 16:19:58 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT2.84684S), skipping check
|
2018-04-24 16:20:04 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 57076020 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
2018-04-24 16:20:12 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') turning message relay requesting on, nonlive peers: tcp://127.0.0.1:16002
|
2018-04-24 16:20:18 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') reconnecting to 57076020 (tcp://127.0.0.1:16002), attempt 0
|
2018-04-24 16:21:17 0 [Warning] WSREP: last inactive check more than PT1.5S ago (PT1M4.34766S), skipping check
|
180424 16:22:09 [ERROR] mysqld got signal 6 ;
|
This could be because you hit a bug. It is also possible that this binary
|
or one of the libraries it was linked against is corrupt, improperly built,
|
or misconfigured. This error can also be caused by malfunctioning hardware.
|
|
|
To report this bug, see https://mariadb.com/kb/en/reporting-bugs
|
|
|
We will try our best to scrape up some info that will hopefully help
|
diagnose the problem, but since we have already crashed,
|
something is definitely wrong and this may fail.
|
|
|
Server version: 10.3.7-MariaDB-log
|
key_buffer_size=1048576
|
read_buffer_size=131072
|
max_used_connections=2
|
max_threads=153
|
thread_count=9
|
It is possible that mysqld could use up to
|
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63163 K bytes of memory
|
Hope that's ok; if not, decrease some variables in the equation.
|
|
|
Thread pointer: 0x0
|
Attempting backtrace. You can use the following information to find out
|
where mysqld died. If you see no messages after this, something went
|
terribly wrong...
|
stack_bottom = 0x0 thread_stack 0x49000
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(my_print_stacktrace+0x29)[0x5593f3803dd9]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(handle_fatal_signal+0x33f)[0x5593f333e53f]
|
2018-04-24 16:22:50 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 00000000 with addr tcp://127.0.0.1:16002 timed out, no messages seen in PT3S
|
/lib64/libpthread.so.0(+0xf5e0)[0x7fccdea625e0]
|
/lib64/libc.so.6(__poll+0x2d)[0x7fccdd037a3d]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(_Z26handle_connections_socketsv+0x20b)[0x5593f30e157b]
|
2018-04-24 16:23:43 0 [Note] WSREP: (570d5bae, 'tcp://0.0.0.0:16005') connection to peer 00000000 with addr timed out, no messages seen in PT3S
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(_Z11mysqld_mainiPPc+0x2b79)[0x5593f30e4759]
|
/lib64/libc.so.6(__libc_start_main+0xf5)[0x7fccdcf6bc05]
|
/home/qa-user1/rr/build-mariadb/sql/mysqld(+0x4e2398)[0x5593f30d8398]
|
The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
|
information that should help you find out what is causing the crash.
|
Writing a core file at /home/qa-user1/rr/build-mariadb/mysql-test/var/mysqld.2/data/
|
|
Attachments
Issue Links
- blocks
-
-
- Closed
-
Activity
Hi, Could not repeat on 10.1 and 10.3:
jan@jan-laptop-asus:~/mysql/10.3/mysql-test$ ./mtr galera_many_tables_nopk --ddd
|
Logging: ./mtr galera_many_tables_nopk --ddd
|
vardir: /home/jan/mysql/10.3/mysql-test/var
|
Checking leftover processes...
|
Removing old var directory...
|
Creating var directory '/home/jan/mysql/10.3/mysql-test/var'...
|
Checking supported features...
|
MariaDB Version 10.3.7-MariaDB-debug
|
- SSL connections supported
|
- binaries are debug compiled
|
Collecting tests...
|
Installing system database...
|
|
|
==============================================================================
|
|
|
TEST RESULT TIME (ms) or COMMENT
|
--------------------------------------------------------------------------
|
|
|
worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 16000..16019
|
galera.galera_many_tables_nopk 'innodb' [ pass ] 66078
|
--------------------------------------------------------------------------
|
The servers were restarted 0 times
|
Spent 66.078 of 141 seconds executing testcases
|
|
|
Completed: All 1 tests were successful.
|
|
|
jan@jan-laptop-asus:~/mysql/10.3/mysql-test$ ./mtr galera_many_tables_nopk
|
Logging: ./mtr galera_many_tables_nopk
|
vardir: /home/jan/mysql/10.3/mysql-test/var
|
Checking leftover processes...
|
Removing old var directory...
|
Creating var directory '/home/jan/mysql/10.3/mysql-test/var'...
|
Checking supported features...
|
MariaDB Version 10.3.7-MariaDB-debug
|
- SSL connections supported
|
- binaries are debug compiled
|
Collecting tests...
|
Installing system database...
|
|
|
==============================================================================
|
|
|
TEST RESULT TIME (ms) or COMMENT
|
--------------------------------------------------------------------------
|
|
|
worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 16000..16019
|
galera.galera_many_tables_nopk 'innodb' [ pass ] 75023
|
--------------------------------------------------------------------------
|
The servers were restarted 0 times
|
Spent 75.023 of 93 seconds executing testcases
|
|
|
Completed: All 1 tests were successful.
|
|
|
jan@jan-laptop-asus:~/mysql/10.3/mysql-test$ ./mtr --suite=galera --force --parallel=6
|
Logging: ./mtr --suite=galera --force --parallel=6
|
vardir: /home/jan/mysql/10.3/mysql-test/var
|
Checking leftover processes...
|
- found old pid 3092 in 'mysqld.2.pid', killing it...
|
process did not exist!
|
Removing old var directory...
|
Creating var directory '/home/jan/mysql/10.3/mysql-test/var'...
|
Checking supported features...
|
MariaDB Version 10.3.7-MariaDB-debug
|
- SSL connections supported
|
- binaries are debug compiled
|
Using suites: galera
|
Collecting tests...
|
Installing system database...
|
^Cmysql-test-run: *** ERROR: Got ^C signal
|
jan@jan-laptop-asus:~/mysql/10.3/mysql-test$ ./mtr --suite=galera --force --parallel=6 --vardir=/dev/shm --big
|
Logging: ./mtr --suite=galera --force --parallel=6 --vardir=/dev/shm --big
|
vardir: /dev/shm
|
Checking leftover processes...
|
Removing old var directory...
|
couldn't chmod(0777, /dev/shm/PostgreSQL.1599903900): Operation not permitted at /usr/share/perl/5.26/File/Find.pm line 454.
|
Couldn't delete file '/dev/shm/PostgreSQL.1599903900': Operation not permitted at /usr/share/perl/5.26/File/Find.pm line 454.
|
couldn't chmod(0777, /dev/shm): Operation not permitted at /usr/share/perl/5.26/File/Find.pm line 511.
|
Couldn't remove directory '/dev/shm': Permission denied at /usr/share/perl/5.26/File/Find.pm line 511.
|
Creating var directory '/dev/shm'...
|
Checking supported features...
|
MariaDB Version 10.3.7-MariaDB-debug
|
- SSL connections supported
|
- binaries are debug compiled
|
Using suites: galera
|
Collecting tests...
|
Installing system database...
|
|
|
==============================================================================
|
|
|
TEST WORKER RESULT TIME (ms) or COMMENT
|
--------------------------------------------------------------------------
|
|
|
worker[3] Using MTR_BUILD_THREAD 300, with reserved ports 16000..16019
|
worker[2] Using MTR_BUILD_THREAD 302, with reserved ports 16040..16059
|
worker[1] Using MTR_BUILD_THREAD 301, with reserved ports 16020..16039
|
worker[6] Using MTR_BUILD_THREAD 303, with reserved ports 16060..16079
|
worker[5] Using MTR_BUILD_THREAD 304, with reserved ports 16080..16099
|
worker[4] Using MTR_BUILD_THREAD 305, with reserved ports 16100..16119
|
galera.GAL-419 [ disabled ] MDEV-13549 Galera test failures
|
galera.GAL-480 [ disabled ] "Lost connection to MySQL"
|
galera.MW-328A [ disabled ] have_deadlocks test not stable
|
galera.MW-329 [ disabled ] wsrep_local_replays not stable
|
galera.MW-44 [ disabled ] MDEV-15809 Test failure on galera.MW-44
|
galera.galera_account_management [ disabled ] MariaDB 10.0 does not support ALTER USER
|
galera.galera_as_master_gtid [ disabled ] Requires MySQL GTID
|
galera.galera_as_master_gtid_change_master [ disabled ] Requires MySQL GTID
|
galera.galera_as_slave_preordered [ disabled ] wsrep-preordered feature not merged to MariaDB
|
galera.galera_as_slave_replication_bundle [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_binlog_rows_query_log_events [ disabled ] MariaDB does not support binlog_rows_query_log_events
|
galera.galera_concurrent_ctas [ disabled ] MDEV-15845 Test failure on galera.galera_concurrent_ctas
|
galera.galera_flush [ disabled ] MariaDB does not have global.thread_statistics
|
galera.galera_gcache_recover [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_gcache_recover_full_gcache [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_gcache_recover_manytrx [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_ist_mysqldump [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_ist_progress [ disabled ] MDEV-15236 galera_ist_progress fails when trying to read transfer status
|
galera.galera_kill_applier [ disabled ] race condition at the start of the test
|
galera.galera_migrate [ disabled ] MariaDB does not support START SLAVE USER
|
galera.galera_pc_ignore_sb [ disabled ] MDEV-15811 Test failure on galera_pc_ignore_sb
|
galera.galera_ssl_upgrade [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_sst_mysqldump [ disabled ] MDEV-14069
|
galera.galera_var_auto_inc_control_on [ disabled ] MDEV-15803 Test failure on galera.galera_var_auto_inc_control_on
|
galera.galera_var_notify_cmd [ disabled ] MDEV-13549 Galera test failures
|
galera.galera_var_retry_autocommit [ disabled ] MDEV-15794 Test failure on galera.galera_var_retry_autocommit
|
galera.query_cache [ disabled ] MDEV-15805 Test failure on galera.query_cache
|
galera.galera_autoinc_sst_xtrabackup 'innodb' [ skipped ] Need innobackupex
|
galera.galera_ist_xtrabackup-v2 'debug,innodb' [ skipped ] Need innobackupex
|
galera.galera_sst_xtrabackup-v2-options [ skipped ] Need innobackupex
|
galera.galera_sst_xtrabackup-v2 'innodb' [ skipped ] Need innobackupex
|
galera.galera_sst_xtrabackup-v2_encrypt_with_key 'innodb' [ skipped ] Need innobackupex
|
galera.galera_binlog_event_max_size_min 'innodb' w5 [ pass ] 2028
|
galera.galera_binlog_checksum 'innodb' w2 [ pass ] 2044
|
galera.MW-313 'innodb' w1 [ pass ] 2106
|
galera.MW-86-wait1 'innodb' w3 [ pass ] 2136
|
galera.MW-86-wait8 'innodb' w6 [ pass ] 4531
|
galera.enforce_storage_engine2 'innodb' w5 [ pass ] 2072
|
galera.galera_log_output_csv 'innodb' w2 [ pass ] 2064
|
galera.galera_mdev_13787 'innodb' w1 [ pass ] 2191
|
galera.galera_gtid 'innodb' w6 [ pass ] 2138
|
galera.galera_applier_ftwrl_table_alter 'innodb' w3 [ pass ] 8146
|
galera.galera_sbr_binlog 'innodb' w2 [ pass ] 2069
|
galera.galera_forced_binlog_format 'innodb' w5 [ pass ] 2135
|
galera.galera_log_bin 'innodb' w1 [ pass ] 2490
|
galera.galera_v1_row_events 'innodb' w6 [ pass ] 2135
|
galera.galera_binlog_event_max_size_max 'innodb' w4 [ pass ] 43831
|
galera.galera_query_cache 'innodb' w5 [ pass ] 2097
|
galera.galera_wsrep_log_conficts 'innodb' w4 [ pass ] 2167
|
galera.galera_wsrep_new_cluster 'innodb' w6 [ pass ] 4012
|
galera.galera_query_cache_sync_wait 'innodb' w5 [ pass ] 4207
|
galera.mysql-wsrep#201 'innodb' w1 [ pass ] 6557
|
galera.partition 'innodb' w3 [ pass ] 25171
|
galera.galera_var_sst_auth 'innodb' w4 [ pass ] 2052
|
galera.galera_flush_local 'innodb' w2 [ pass ] 25599
|
galera.create 'innodb' w5 [ pass ] 2615
|
galera.galera_fk_cascade_update 'innodb' w1 [ pass ] 2244
|
galera.enforce_storage_engine 'innodb' w5 [ pass ] 2124
|
galera.galera_fk_conflict 'innodb' w1 [ pass ] 2148
|
galera.galera_nopk_blob 'innodb' w3 [ pass ] 2137
|
galera.ev51914 'innodb' w5 [ pass ] 2499
|
galera.galera_fk_mismatch 'innodb' w1 [ pass ] 2182
|
galera.galera_nopk_large_varchar 'innodb' w3 [ pass ] 2148
|
galera.fk 'innodb' w5 [ pass ] 2661
|
galera.galera_fk_multicolumn 'innodb' w1 [ pass ] 2227
|
galera.galera_nopk_unicode 'innodb' w3 [ pass ] 2109
|
galera.galera_fk_multitable 'innodb' w1 [ pass ] 2175
|
galera.galera_parallel_apply_lock_table 'innodb' w3 [ pass ] 4131
|
galera.galera_fk_no_pk 'innodb' w1 [ pass ] 2235
|
galera.galera_truncate 'innodb' w2 [ pass ] 2464
|
galera.galera_parallel_simple 'innodb' w3 [ pass ] 2289
|
galera.galera_fk_selfreferential 'innodb' w1 [ pass ] 2110
|
galera.galera_truncate_temporary 'innodb' w2 [ pass ] 2325
|
galera.galera_pk_bigint_signed 'innodb' w3 [ pass ] 2087
|
galera.galera_admin 'innodb' w5 [ pass ] 11749
|
galera.galera_fk_setnull 'innodb' w1 [ pass ] 2382
|
galera.galera_unicode_identifiers 'innodb' w2 [ pass ] 2416
|
galera.galera_pk_bigint_unsigned 'innodb' w3 [ pass ] 2175
|
galera.galera_alter_engine_innodb 'innodb' w5 [ pass ] 2263
|
galera.galera_unicode_pk 'innodb' w2 [ pass ] 2110
|
galera.galera_prepared_statement 'innodb' w3 [ pass ] 2373
|
galera.galera_alter_engine_myisam 'innodb' w5 [ pass ] 2138
|
galera.galera_ftwrl 'innodb' w1 [ pass ] 4103
|
galera.galera_update_limit 'innodb' w2 [ pass ] 2610
|
galera.galera_read_only 'innodb' w3 [ pass ] 2182
|
galera.galera_alter_table_force 'innodb' w5 [ pass ] 2140
|
galera.galera_ftwrl_drain 'innodb' w1 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.galera_var_OSU_method 'innodb' w2 [ pass ] 2084
|
galera.galera_repl_key_format_flat16 'innodb' w3 [ pass ] 2154
|
galera.galera_applier_ftwrl_table 'innodb' w5 [ pass ] 2201
|
galera.galera_var_OSU_method2 'innodb' w2 [ pass ] 2285
|
galera.galera_repl_max_ws_size 'innodb' w3 [ pass ] 2160
|
galera.galera_bf_abort 'innodb' w5 [ pass ] 2138
|
galera.galera_bf_abort_flush_for_export 'innodb' w5 [ pass ] 2217
|
galera.galera_var_auto_inc_control_off 'innodb' w2 [ pass ] 4325
|
galera.galera_bf_abort_for_update 'innodb' w5 [ pass ] 2127
|
galera.galera_gcs_fc_limit 'innodb' w1 [ pass ] 13367
|
galera.galera_var_certify_nonPK_off 'innodb' w2 [ pass ] 2390
|
galera.galera_split_brain 'innodb' w4 [ pass ] 34985
|
galera.galera_bf_abort_ftwrl 'innodb' w5 [ pass ] 2162
|
galera.galera_gra_log 'innodb' w1 [ pass ] 2192
|
galera.galera_sql_log_bin_zero 'innodb' w4 [ pass ] 2105
|
galera.MW-286 'innodb' w6 [ fail ]
|
Test ended at 2018-05-03 15:09:55
|
|
|
CURRENT_TEST: galera.MW-286
|
mysqltest: At line 26: query 'ALTER TABLE t1 ADD PRIMARY KEY (f1)' succeeded - should have failed with errno 1317...
|
|
|
The result from queries just before the failure was:
|
connection node_1;
|
CREATE TABLE ten (f1 INTEGER);
|
INSERT INTO ten VALUES (0),(1),(2),(3),(4),(5),(6),(7),(8),(9);
|
CREATE TABLE t1 (f1 INTEGER) Engine=InnoDB;
|
INSERT INTO t1 (f1) SELECT 000000 + (10000 * a1.f1) + (1000 * a2.f1) + (100 * a3.f1) + (10 * a4.f1) + a5.f1 FROM ten AS a1, ten AS a2, ten AS a3, ten AS a4, ten AS a5;
|
INSERT INTO t1 (f1) SELECT 100000 + (10000 * a1.f1) + (1000 * a2.f1) + (100 * a3.f1) + (10 * a4.f1) + a5.f1 FROM ten AS a1, ten AS a2, ten AS a3, ten AS a4, ten AS a5;;
|
connection node_2;
|
SET GLOBAL wsrep_desync = TRUE;
|
SET wsrep_on = FALSE;
|
ALTER TABLE t1 ADD PRIMARY KEY (f1);
|
|
|
- saving '/dev/shm/6/log/galera.MW-286-innodb/' to '/dev/shm/log/galera.MW-286-innodb/'
|
galera.galera_bf_abort_get_lock 'innodb' w5 [ pass ] 3070
|
galera.galera_insert_ignore 'innodb' w1 [ pass ] 2140
|
galera.galera_status_cluster 'innodb' w4 [ pass ] 2029
|
galera.galera_bf_abort_lock_table 'innodb' w5 [ pass ] 2064
|
galera.galera_insert_multi 'innodb' w1 [ pass ] 2274
|
galera.galera_status_local_index 'innodb' w4 [ pass ] 2077
|
galera.galera_restart_nochanges 'innodb' w3 [ pass ] 19191
|
galera.galera_bf_abort_sleep 'innodb' w5 [ pass ] 2047
|
galera.galera_status_local_state 'innodb' w4 [ pass ] 2057
|
galera.galera_binlog_cache_size 'innodb' w5 [ pass ] 3180
|
galera.galera_binlog_row_image 'innodb' w5 [ pass ] 2337
|
galera.galera_var_cluster_address 'innodb' w2 [ pass ] 19299
|
galera.galera_create_function 'innodb' w5 [ pass ] 2055
|
galera.galera_create_procedure 'innodb' w5 [ pass ] 2114
|
galera.galera_var_desync_on 'innodb' w2 [ pass ] 3380
|
galera.galera_create_table_like 'innodb' w5 [ pass ] 2347
|
galera.galera_create_trigger 'innodb' w5 [ pass ] 2458
|
galera.galera_ddl_multiline 'innodb' w5 [ pass ] 2763
|
galera.galera_kill_ddl 'innodb' w1 [ pass ] 24966
|
galera.galera_defaults 'innodb' w5 [ pass ] 2160
|
galera.galera_suspend_slave 'innodb' w4 [ pass ] 25705
|
galera.galera_delete_limit 'innodb' w5 [ pass ] 2193
|
galera.galera_sync_wait_show 'innodb' w4 [ pass ] 2109
|
galera.galera_var_dirty_reads 'innodb' w2 [ pass ] 17301
|
galera.galera_var_fkchecks 'innodb' w2 [ pass ] 2234
|
galera.galera_desync_overlapped 'innodb' w5 [ pass ] 4044
|
galera.galera_toi_alter_auto_increment 'innodb' w4 [ pass ] 4368
|
galera.galera_drop_multi 'innodb' w5 [ pass ] 2103
|
galera.galera_var_innodb_disallow_writes 'innodb' w2 [ pass ] 3190
|
galera.galera_enum 'innodb' w5 [ pass ] 2142
|
galera.galera_toi_ddl_locking 'innodb' w4 [ pass ] 5195
|
galera.galera_kill_nochanges 'innodb' w1 [ pass ] 18481
|
galera.galera_toi_ddl_nonconflicting 'innodb' w4 [ pass ] 2182
|
galera.galera_events 'innodb' w5 [ pass ] 3042
|
galera.galera_toi_ddl_sequential 'innodb' w4 [ pass ] 2232
|
galera.galera_fk_cascade_delete 'innodb' w5 [ pass ] 2270
|
galera.galera_var_max_ws_size 'innodb' w5 [ pass ] 2082
|
galera.galera_var_mysql_replication_bundle 'innodb' w5 [ pass ] 2097
|
galera.galera_var_reject_queries 'innodb' w5 [ pass ] 4131
|
galera.galera_var_replicate_myisam_off 'innodb' w5 [ pass ] 2041
|
galera.galera_var_replicate_myisam_on 'innodb' w5 [ pass ] 2369
|
galera.galera_kill_smallchanges 'innodb' w1 [ pass ] 26307
|
galera.galera_var_slave_threads 'innodb' w5 [ pass ] 2841
|
galera.galera_var_sync_wait 'innodb' w5 [ pass ] 2217
|
galera.galera_lock_table 'innodb' w1 [ pass ] 4203
|
galera.galera_var_wsrep_on_off 'innodb' w5 [ pass ] 2070
|
galera.galera_wsrep_provider_options_syntax 'innodb' w5 [ pass ] 2064
|
galera.galera_many_indexes 'innodb' w1 [ pass ] 6327
|
galera.galera_var_max_ws_rows 'innodb' w2 [ pass ] 48744
|
galera.galera_mdev_10812 'innodb' w1 [ pass ] 6085
|
galera.lp1276424 'innodb' w2 [ pass ] 2108
|
galera.lp1347768 'innodb' w2 [ pass ] 2140
|
galera.galera_bf_lock_wait 'innodb' w6 [ pass ] 102508
|
galera.galera_restart_on_unknown_option 'innodb' w3 [ pass ] 101360
|
galera.lp1376747 'innodb' w2 [ pass ] 4116
|
galera.galera_mdl_race 'innodb' w1 [ pass ] 11183
|
galera.lp1376747-2 'innodb' w2 [ pass ] 2098
|
galera.galera_wsrep_provider_unset_set 'innodb' w5 [ pass ] 20517
|
galera.galera_roles 'innodb' w3 [ pass ] 5318
|
galera.lp1376747-3 'innodb' w2 [ pass ] 2179
|
galera.galera_multi_database 'innodb' w1 [ pass ] 2228
|
galera.galera_zero_length_column 'innodb' w5 [ pass ] 2205
|
galera.galera_rsu_error 'innodb' w3 [ pass ] 2164
|
galera.galera_myisam_autocommit 'innodb' w1 [ pass ] 2069
|
galera.grant 'innodb' w5 [ pass ] 2044
|
galera.galera_rsu_simple 'innodb' w3 [ pass ] 2103
|
galera.galera_myisam_transactions 'innodb' w1 [ pass ] 2120
|
galera.mysql-wsrep#198 'innodb' w5 [ pass ] 2345
|
galera.galera_nopk_bit 'innodb' w1 [ pass ] 2140
|
galera.lp1376747-4 'innodb' w2 [ pass ] 9112
|
galera.galera_rsu_wsrep_desync 'innodb' w3 [ pass ] 4291
|
galera.mysql-wsrep#237 'innodb' w5 [ pass ] 3114
|
galera.view 'innodb' w1 [ pass ] 2113
|
galera.lp1438990 'innodb' w2 [ pass ] 2227
|
galera.galera_sbr 'innodb' w3 [ pass ] 2109
|
galera.mysql-wsrep#247 'innodb' w5 [ pass ] 3088
|
galera.lp959512 'innodb' w2 [ pass ] 2166
|
galera.galera_schema_dirty_reads 'innodb' w3 [ pass ] 2057
|
galera.mdev_9290 'innodb' w2 [ pass ] 2095
|
galera.mysql-wsrep#110 'innodb' w2 [ pass ] 2254
|
galera.galera_serializable 'innodb' w3 [ pass ] 6145
|
galera.galera_server 'innodb' w3 [ pass ] 2043
|
galera.mysql-wsrep#31 'innodb' w5 [ pass ] 22856
|
galera.MW-284 'innodb' w2 [ pass ] 9642
|
galera.galera_fulltext 'innodb' w6 [ pass ] 62190
|
galera.MDEV-15443 'innodb' w1 [ pass ] 24736
|
galera.mysql-wsrep#90 'innodb' w5 [ pass ] 2379
|
galera.galera#414 'innodb' w3 [ pass ] 13949
|
galera.galera_as_master 'innodb' w2 [ pass ] 2637
|
galera.galera_as_master_large 'innodb' w1 [ pass ] 2298
|
galera.galera_as_slave_autoinc 'innodb' w2 [ pass ] 2470
|
galera.galera_as_slave 'innodb' w3 [ pass ] 2278
|
galera.pxc-421 'innodb' w5 [ fail ]
|
Test ended at 2018-05-03 15:13:12
|
|
|
CURRENT_TEST: galera.pxc-421
|
mysqltest: In included file "./include/wait_until_connected_again.inc":
|
included from /home/jan/mysql/10.3/mysql-test/suite/galera/t/pxc-421.test at line 40:
|
At line 15: query 'show status' failed with wrong errno 1205: 'Lock wait timeout exceeded; try restarting transaction', instead of 0...
|
|
|
The result from queries just before the failure was:
|
connection node_1;
|
connection node_2;
|
connection node_1;
|
set GLOBAL wsrep_slave_threads=26;
|
CREATE TABLE t1 (f1 INTEGER) ENGINE=InnoDB;
|
INSERT INTO t1 VALUES (1);
|
INSERT INTO t1 (f1) SELECT * from t1 as x1;
|
connection node_2;
|
set GLOBAL wsrep_slave_threads=16;
|
SET GLOBAL wsrep_provider='none';
|
INSERT INTO t1 VALUES (2);
|
connection node_1;
|
INSERT INTO t1 VALUES (3);
|
connection node_2;
|
|
|
- saving '/dev/shm/5/log/galera.pxc-421-innodb/' to '/dev/shm/log/galera.pxc-421-innodb/'
|
galera.galera_as_slave_nonprim 'innodb' w2 [ skipped ] Test requires wsrep_on=ON
|
galera.rename 'innodb' w5 [ pass ] 2142
|
galera.galera_as_slave_gtid 'innodb' w1 [ pass ] 3170
|
galera.sql_log_bin 'innodb' w5 [ pass ] 2240
|
galera.unique_key 'innodb' w5 [ pass ] 2150
|
galera.galera_gcs_fragment 'innodb' w3 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.galera_gtid_slave 'innodb' w1 [ pass ] 2951
|
galera.galera_gcs_max_packet_size 'innodb' w2 [ pass ] 12304
|
galera.galera_ist_restart_joiner 'innodb' w2 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.galera_gtid_slave_sst_rsync 'innodb' w5 [ pass ] 23379
|
galera.galera_ist_recv_bind 'innodb' w1 [ pass ] 14684
|
galera.galera_mdev_15611 'innodb' w5 [ pass ] 4343
|
galera.galera_ssl 'innodb' w1 [ pass ] 2124
|
worker[3] mysql-test-run: WARNING: Waited 60 seconds for /dev/shm/3/run/mysqld.2.pid to be created, still waiting for 120 seconds...
|
galera.galera_ssl_compression 'innodb' w5 [ pass ] 2078
|
worker[5] mysql-test-run: WARNING: Check-testcase failed, this could also be caused by the previous test run by this worker thread
|
galera.galera_sst_mysqldump_with_key 'innodb' w5 [ fail ]
|
Test ended at 2018-05-03 15:14:57
|
|
|
CURRENT_TEST: galera.galera_sst_mysqldump_with_key
|
|
|
|
|
Could not execute 'check-testcase' before testcase 'galera.galera_sst_mysqldump_with_key' (res: 1):
|
mysqltest: Logging to '/dev/shm/5/tmp/check-mysqld_1.log'.
|
mysqltest: Results saved in '/dev/shm/5/tmp/check-mysqld_1.result'.
|
mysqltest: Connecting to server localhost:16080 (socket /dev/shm/tmp/5/mysqld.1.sock) as 'root', connection 'default', attempt 0 ...
|
mysqltest: Could not open connection 'default': 2026 SSL connection error: tlsv1 alert unknown ca
|
not ok
|
mysqltest failed but provided no output
|
|
|
|
|
- saving '/dev/shm/5/log/galera.galera_sst_mysqldump_with_key-innodb/' to '/dev/shm/log/galera.galera_sst_mysqldump_with_key-innodb/'
|
galera.galera_ist_rsync 'debug,innodb' w2 [ pass ] 59322
|
galera.galera_toi_drop_database 'innodb' w4 [ pass ] 278048
|
galera.galera_var_gtid_domain_id 'innodb' w2 [ pass ] 2048
|
galera.galera_toi_ftwrl 'innodb' w4 [ pass ] 2092
|
galera.galera_toi_lock_exclusive 'innodb' w4 [ pass ] 3103
|
worker[3] mysql-test-run: WARNING: Waited 120 seconds for /dev/shm/3/run/mysqld.2.pid to be created, still waiting for 60 seconds...
|
galera.galera_toi_lock_shared 'innodb' w4 [ pass ] 2071
|
galera.galera_var_log_bin 'innodb' w2 [ pass ] 2077
|
galera.galera_sst_rsync 'debug,innodb' w5 [ pass ] 63564
|
galera.galera_var_node_address 'innodb' w2 [ pass ] 2140
|
galera.galera_toi_truncate 'innodb' w4 [ pass ] 47327
|
galera.galera_transaction_read_only 'innodb' w4 [ pass ] 2059
|
galera.galera_wan 'innodb' w5 [ pass ] 2131
|
galera.galera_transaction_replay 'innodb' w4 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
worker[3] mysql-test-run: WARNING: Timeout after mysql-test-run waited 180 seconds for the process [mysqld.2 - pid: 16763, winpid: 16763] to create a pid file.
|
galera.galera_ist_innodb_flush_logs 'debug,innodb' w3 [ fail ]
|
Test ended at 2018-05-03 15:16:38
|
|
|
CURRENT_TEST: galera.galera_ist_innodb_flush_logs
|
|
|
|
|
Failed to start mysqld.2
|
mysqltest failed but provided no output
|
|
|
|
|
- saving '/dev/shm/3/log/galera.galera_ist_innodb_flush_logs-debug,innodb/' to '/dev/shm/log/galera.galera_ist_innodb_flush_logs-debug,innodb/'
|
***Warnings generated in error logs during shutdown after running tests: galera.galera_ist_innodb_flush_logs
|
|
|
2018-05-03 15:13:38 0 [ERROR] WSREP: Failed to read 'ready <addr>' from: wsrep_sst_xtrabackup-v2 --role 'joiner' --address '127.0.0.1:16007' --datadir '/dev/shm/3/mysqld.2/data/' --defaults-file '/dev/shm/3/my.cnf' --defaults-group-suffix '.2' --parent '16764' ''
|
2018-05-03 15:13:38 0 [ERROR] WSREP: Process completed with error: wsrep_sst_xtrabackup-v2 --role 'joiner' --address '127.0.0.1:16007' --datadir '/dev/shm/3/mysqld.2/data/' --defaults-file '/dev/shm/3/my.cnf' --defaults-group-suffix '.2' --parent '16764' '' : 2 (No such file or directory)
|
2018-05-03 15:13:38 2 [ERROR] WSREP: Failed to prepare for 'xtrabackup-v2' SST. Unrecoverable.
|
2018-05-03 15:13:38 2 [ERROR] Aborting
|
|
|
galera.galera_sst_mariabackup 'debug,innodb' w1 [ pass ] 111889
|
galera.mdev_10518 'innodb' w4 [ pass ] 2127
|
galera.galera_many_columns 'innodb' w1 [ pass ] 5270
|
galera.mysql-wsrep#33 'innodb' w3 [ pass ] 31044
|
galera.rpl_row_annotate 'innodb' w3 [ pass ] 2183
|
galera.galera_wan_restart_ist 'innodb' w2 [ pass ] 65989
|
galera.galera_wan_restart_sst 'innodb' w5 [ pass ] 77492
|
galera.galera_many_tables_pk 'innodb' w3 [ pass ] 23593
|
galera.galera_parallel_autoinc_manytrx 'innodb' w2 [ pass ] 59824
|
galera.galera_parallel_autoinc_largetrx 'innodb' w3 [ pass ] 39288
|
galera.galera_kill_largechanges 'innodb' w6 [ pass ] 388697
|
galera.galera_many_tables_nopk 'innodb' w1 [ pass ] 168938
|
galera.galera_toi_ddl_fk_update 'innodb' w6 [ pass ] 112012
|
galera.galera_toi_ddl_error 'innodb' w2 [ pass ] 156122
|
galera.galera_var_load_data_splitting 'innodb' w1 [ pass ] 99921
|
galera.GAL-382 'innodb' w2 [ pass ] 2361
|
galera.MW-252 'innodb' w2 [ pass ] 4135
|
galera.MW-258 'innodb' w2 [ pass ] 10133
|
galera.GAL-401 'innodb' w1 [ pass ] 20363
|
galera.MW-259 'innodb' w2 [ pass ] 4223
|
galera.MW-285 'innodb' w1 [ pass ] 2423
|
galera.MW-292 'innodb' w2 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.MW-309 'innodb' w1 [ pass ] 2217
|
galera.MW-328B 'innodb' w2 [ fail ] Found warnings/errors in server log file!
|
Test ended at 2018-05-03 15:22:17
|
line
|
safe_mutex: Found wrong usage of mutex 'LOCK_wsrep_thd' and 'LOCK_thd_kill'
|
^ Found warnings in /dev/shm/2/log/mysqld.2.err
|
ok
|
|
|
- saving '/dev/shm/2/log/galera.MW-328B-innodb/' to '/dev/shm/log/galera.MW-328B-innodb/'
|
galera.MW-328C 'innodb' w1 [ fail ] Found warnings/errors in server log file!
|
Test ended at 2018-05-03 15:22:17
|
line
|
safe_mutex: Found wrong usage of mutex 'LOCK_wsrep_thd' and 'LOCK_thd_kill'
|
^ Found warnings in /dev/shm/1/log/mysqld.2.err
|
ok
|
|
|
- saving '/dev/shm/1/log/galera.MW-328C-innodb/' to '/dev/shm/log/galera.MW-328C-innodb/'
|
galera.MW-328D 'innodb' w2 [ pass ] 4236
|
galera.MW-328E 'innodb' w1 [ pass ] 4253
|
galera.MW-357 'innodb' w1 [ pass ] 2085
|
galera.MW-336 'innodb' w2 [ pass ] 2657
|
galera.MW-369 'innodb' w1 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.MW-402 'innodb' w1 [ skipped ] "Test requires Galera debug library with debug_sync functionality"
|
galera.basic 'innodb' w1 [ pass ] 2351
|
galera.binlog_checksum 'innodb' w1 [ pass ] 2344
|
galera.galera_rsu_add_pk 'innodb' w5 [ pass ] 392934
|
galera.galera_wsrep_desync_wsrep_on 'innodb' w6 [ pass ] 284279
|
galera.galera_rsu_drop_pk 'innodb' w3 [ pass ] 482801
|
galera.MW-388 'innodb' w2 [ fail ] Found warnings/errors in server log file!
|
Test ended at 2018-05-03 15:27:40
|
line
|
2018-05-03 15:26:41 0 [Warning] InnoDB: A long semaphore wait:
|
2018-05-03 15:27:12 0 [Warning] InnoDB: A long semaphore wait:
|
^ Found warnings in /dev/shm/2/log/mysqld.1.err
|
ok
|
|
|
- saving '/dev/shm/2/log/galera.MW-388-innodb/' to '/dev/shm/log/galera.MW-388-innodb/'
|
galera.galera_toi_ddl_fk_insert 'innodb' w3 [ pass ] 27530
|
galera.galera_many_rows 'innodb' w4 [ pass ] 657758
|
--------------------------------------------------------------------------
|
The servers were restarted 64 times
|
Spent 4885.011 of 1251 seconds executing testcases
|
|
|
Completed: Failed 7/224 tests, 96.88% were successful.
|
|
|
Failing test(s): galera.MW-286 galera.pxc-421 galera.galera_sst_mysqldump_with_key galera.galera_ist_innodb_flush_logs galera.MW-328B galera.MW-328C galera.MW-388
|
|
|
The log files in var/log may give you some hint of what went wrong.
|
|
|
If you want to report this error, please read first the documentation
|
at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html
|
|
|
Errors/warnings were found in logfiles during server shutdown after running the
|
following sequence(s) of tests:
|
galera.galera_ist_innodb_flush_logs
|
13 tests were skipped, 8 by the test itself.
|
|
|
mysql-test-run: *** ERROR: there were failing test cases
|
Hi winstone, Is it specific to 10.3 or reproducible in 10.1 also ?