[MDEV-18282] Galera test failure on galera.galera_bf_abort_group_commit Created: 2019-01-17  Updated: 2022-10-27  Resolved: 2022-10-27

Status: Closed
Project: MariaDB Server
Component/s: Debug, Galera, Tests
Affects Version/s: 10.4
Fix Version/s: 10.4.27

Type: Bug Priority: Major
Reporter: Jan Lindström (Inactive) Assignee: Jan Lindström (Inactive)
Resolution: Fixed Votes: 0
Labels: None


 Description    

galera.galera_bf_abort_group_commit 'innodb' w3 [ fail ]
 
        Test ended at 2019-01-17 13:57:17
 
 
 
CURRENT_TEST: galera.galera_bf_abort_group_commit
 
mysqltest: In included file "/home/jan/mysql/10.4-galera-2/mysql-test/suite/galera/../../suite/galera_sr/t/galera_sr_bf_abort.inc": 
included from /home/jan/mysql/10.4-galera-2/mysql-test/suite/galera/t/galera_bf_abort_group_commit.test at line 22:
 
At line 87: query 'reap' failed with wrong errno 2013: 'Lost connection to MySQL server during query', instead of 1213...
 
 
 
The result from queries just before the failure was:
 
< snip >
 
INSERT INTO t1 VALUES (2);
 
connection node_1a;
 
SET SESSION wsrep_on = 0;
 
SET SESSION wsrep_on = 1;
 
SET GLOBAL wsrep_provider_options = 'dbug=';
 
SET GLOBAL wsrep_provider_options = 'dbug=d,after_replicate_sync';
 
connection node_1;
 
INSERT INTO t1 VALUES (3);
 
connection node_1a;
 
SET SESSION wsrep_on = 0;
 
SET SESSION wsrep_on = 1;
 
SET GLOBAL wsrep_provider_options = 'dbug=';
 
SET GLOBAL wsrep_provider_options = 'dbug=d,abort_trx_end';
 
SET GLOBAL wsrep_provider_options = 'signal=apply_monitor_slave_enter_sync';
 
SET SESSION wsrep_on = 0;
 
SET SESSION wsrep_on = 1;
 
SET GLOBAL wsrep_provider_options = 'dbug=';
 
SET GLOBAL wsrep_provider_options = 'signal=abort_trx_end';
 
SET GLOBAL wsrep_provider_options = 'signal=after_replicate_sync';
 
connection node_1;
 
 
 
More results from queries before failure can be found in /home/jan/mysql/10.4-galera-2/mysql-test/var/3/log/galera_bf_abort_group_commit.log
 
 
 
 
 
Server [mysqld.1 - pid: 30523, winpid: 30523, exit: 256] failed during test run
 
Server log from this test:
 
----------SERVER LOG START-----------
 
2019-01-17 13:57:05 0 [Warning] WSREP: option --wsrep-causal-reads is deprecated
 
2019-01-17 13:57:05 0 [Note] /home/jan/mysql/10.4-galera-2/sql/mysqld (mysqld 10.4.2-MariaDB-debug-log) starting as process 30524 ...
 
2019-01-17 13:57:05 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4186)
 
2019-01-17 13:57:05 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2019-01-17 13:57:05 0 [Warning] WSREP: wsrep_sst_receive_address is set to '127.0.0.1:16044' 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-01-17 13:57:05 0 [Note] WSREP: Server initial position: 00000000-0000-0000-0000-000000000000:-1
 
2019-01-17 13:57:05 0 [Note] WSREP: Loading provider /usr/lib/libgalera_4d_smm.soinitial position: 00000000-0000-0000-0000-000000000000:-1
 
wsrep loader: [INFO] wsrep_load(): loading provider library '/usr/lib/libgalera_4d_smm.so'
 
wsrep loader: [INFO] wsrep_load(): Galera 26.4.0(r4456) by Codership Oy <info@codership.com> loaded successfully.
 
2019-01-17 13:57:06 0 [Note] WSREP: CRC-32C: using hardware acceleration.
 
2019-01-17 13:57:06 0 [Warning] WSREP: Could not open state file for reading: '/home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data//grastate.dat'
 
2019-01-17 13:57:06 0 [Note] WSREP: Found saved state: 00000000-0000-0000-0000-000000000000:-1, safe_to_bootstrap: 1
 
2019-01-17 13:57:06 0 [Note] WSREP: GCache DEBUG: opened preamble:
 
Version: 0
 
UUID: 00000000-0000-0000-0000-000000000000
 
Seqno: -1 - -1
 
Offset: -1
 
Synced: 0
 
2019-01-17 13:57:06 0 [Note] WSREP: Skipped GCache ring buffer recovery: could not determine history UUID.
 
2019-01-17 13:57:06 0 [Note] WSREP: Passing config to GCS: base_dir = /home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data/; base_host = 127.0.0.1; base_port = 16042; cert.log_conflicts = no; cert.optimistic_pa = yes; dbug = ; 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.debug = 0; gcache.dir = /home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data/; gcache.keep_pages_size = 0; gcache.mem_size = 0; gcache.name = /home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data//galera.cache; gcache.page_size = 128M; gcache.recover = yes; 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 = 6
 
2019-01-17 13:57:06 0 [Note] WSREP: GCache history reset: old(00000000-0000-0000-0000-000000000000:0 -> 00000000-0000-0000-0000-000000000000:-1
 
2019-01-17 13:57:06 0 [Note] WSREP: Start replication
 
2019-01-17 13:57:06 0 [Note] WSREP: Connecting with bootstrap option: 1
 
2019-01-17 13:57:06 0 [Note] WSREP: Setting GCS initial position to 00000000-0000-0000-0000-000000000000:-1
 
2019-01-17 13:57:06 0 [Note] WSREP: protonet asio version 0
 
2019-01-17 13:57:06 0 [Note] WSREP: Using CRC-32C for message checksums.
 
2019-01-17 13:57:06 0 [Note] WSREP: backend: asio
 
2019-01-17 13:57:06 0 [Note] WSREP: gcomm thread scheduling priority set to other:0 
2019-01-17 13:57:06 0 [Warning] WSREP: access file(/home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data//gvwstate.dat) failed(No such file or directory)
 
2019-01-17 13:57:06 0 [Note] WSREP: restore pc from disk failed
 
2019-01-17 13:57:06 0 [Note] WSREP: GMCast version 0
 
2019-01-17 13:57:06 0 [Note] WSREP: (02ad39ae, 'tcp://0.0.0.0:16042') listening at tcp://0.0.0.0:16042
 
2019-01-17 13:57:06 0 [Note] WSREP: (02ad39ae, 'tcp://0.0.0.0:16042') multicast: , ttl: 1
 
2019-01-17 13:57:06 0 [Note] WSREP: EVS version 1
 
2019-01-17 13:57:06 0 [Note] WSREP: gcomm: bootstrapping new group 'my_wsrep_cluster'
 
2019-01-17 13:57:06 0 [Note] WSREP: start_prim is enabled, turn off pc_recovery
 
2019-01-17 13:57:06 0 [Note] WSREP: EVS version upgrade 0 -> 1
 
2019-01-17 13:57:06 0 [Note] WSREP: PC protocol upgrade 0 -> 1
 
2019-01-17 13:57:06 0 [Note] WSREP: Node 02ad39ae state prim
 
2019-01-17 13:57:06 0 [Note] WSREP: view(view_id(PRIM,02ad39ae,1) memb {
 
	02ad39ae,0
 
} joined {
 
} left {
 
} partitioned {
 
})
 
2019-01-17 13:57:06 0 [Note] WSREP: save pc into disk
 
2019-01-17 13:57:06 0 [Note] WSREP: gcomm: connected
 
2019-01-17 13:57:06 0 [Note] WSREP: Changing maximum packet size to 64500, resulting msg size: 32636
 
2019-01-17 13:57:06 0 [Note] WSREP: Shifting CLOSED -> OPEN (TO: 0)
 
2019-01-17 13:57:06 0 [Note] WSREP: Opened channel 'my_wsrep_cluster'
 
2019-01-17 13:57:06 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 1
 
2019-01-17 13:57:06 0 [Note] WSREP: Starting new group from scratch: 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 02b1d67c-1a4f-11e9-baeb-1b8ba7345511
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 02b1d67c-1a4f-11e9-baeb-1b8ba7345511
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE EXCHANGE: got state msg: 02b1d67c-1a4f-11e9-baeb-1b8ba7345511 from 0 (jan-laptop-asus)
 
2019-01-17 13:57:06 0 [Note] WSREP: Quorum results:
 
	version    = 5,
 
	component  = PRIMARY,
 
	conf_id    = 0,
 
	members    = 1/1 (joined/total),
 
	act_id     = 0,
 
	last_appl. = -1,
 
	protocols  = 1/10/4 (gcs/repl/appl),
 
	vote policy= 0,
 
	group UUID = 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41
 
2019-01-17 13:57:06 2 [Note] WSREP: Starting applier thread 2
 
2019-01-17 13:57:06 0 [Note] WSREP: Writing down CC checksum: 32259ed7 cb3328b4 2ffca2f6 3b1a1456 at offset 136
 
2019-01-17 13:57:06 0 [Note] WSREP: Flow-control interval: [16, 16]
 
2019-01-17 13:57:06 0 [Note] WSREP: Trying to continue unpaused monitor
 
2019-01-17 13:57:06 0 [Note] WSREP: Restored state OPEN -> JOINED (1)
 
2019-01-17 13:57:06 0 [Note] WSREP: Member 0.0 (jan-laptop-asus) synced with group.
 
2019-01-17 13:57:06 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 1)
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### processing CC 1, local, ordered
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### drain monitors upto -1
 
2019-01-17 13:57:06 2 [Note] WSREP: REPL Protocols: 10 (5, 3)
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### My UUID: 02ad39ae-1a4f-11e9-afbe-37405017b1ca
 
2019-01-17 13:57:06 2 [Note] WSREP: Server jan-laptop-asus connected to cluster at position 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:1 with ID 02ad39ae-1a4f-11e9-afbe-37405017b1ca
 
2019-01-17 13:57:06 2 [Note] WSREP: Server status change disconnected -> connected
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### ST not required
 
2019-01-17 13:57:06 2 [Note] WSREP: Cert index reset to 00000000-0000-0000-0000-000000000000:0 (proto: 10), state transfer needed: no
 
2019-01-17 13:57:06 0 [Note] WSREP: Service thread queue flushed.
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### Assign initial position for certification: 00000000-0000-0000-0000-000000000000:0, protocol version: 5
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### Adjusting cert position: 0 -> 1
 
2019-01-17 13:57:06 0 [Note] WSREP: Service thread queue flushed.
 
2019-01-17 13:57:06 2 [Note] WSREP: ####### Setting monitor position to 1
 
2019-01-17 13:57:06 2 [Note] WSREP: Lowest cert indnex boundary for CC from group: 1
 
2019-01-17 13:57:06 2 [Note] WSREP: Min available from gcache for CC from group: 1
 
2019-01-17 13:57:06 2 [Note] WSREP: ================================================
 
View:
 
  id: 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:1
 
  seqno: 1
 
  status: 0
 
  prococol_version: 4
 
  own_index: 0
 
  final: 0
 
  members
 
2019-01-17 13:57:06 2 [Note] WSREP:     id: 02ad39ae-1a4f-11e9-afbe-37405017b1ca name: jan-laptop-asus
 
2019-01-17 13:57:06 2 [Note] WSREP: =================================================
 
2019-01-17 13:57:06 2 [Note] WSREP: Server status change connected -> joiner
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 2 [Note] WSREP: Server status change joiner -> initializing
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 0 [Note] WSREP: (02ad39ae, 'tcp://0.0.0.0:16042') connection established to 02b9127f tcp://127.0.0.1:16045
 
2019-01-17 13:57:06 0 [Note] WSREP: (02ad39ae, 'tcp://0.0.0.0:16042') turning message relay requesting on, nonlive peers: 
2019-01-17 13:57:06 0 [Note] Plugin 'partition' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2019-01-17 13:57:06 0 [Note] InnoDB: Using Linux native AIO
 
2019-01-17 13:57:06 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2019-01-17 13:57:06 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2019-01-17 13:57:06 0 [Note] InnoDB: Uses event mutexes
 
2019-01-17 13:57:06 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2019-01-17 13:57:06 0 [Note] InnoDB: Number of pools: 1
 
2019-01-17 13:57:06 0 [Note] InnoDB: Using SSE2 crc32 instructions
 
2019-01-17 13:57:06 0 [Note] mysqld: O_TMPFILE is not supported on /home/jan/mysql/10.4-galera-2/mysql-test/var/tmp/3/mysqld.1 (disabling future attempts)
 
2019-01-17 13:57:06 0 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
 
2019-01-17 13:57:06 0 [Note] InnoDB: Completed initialization of buffer pool
 
2019-01-17 13:57:06 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
 
2019-01-17 13:57:06 0 [Note] InnoDB: 128 out of 128 rollback segments are active.
 
2019-01-17 13:57:06 0 [Note] InnoDB: Creating shared tablespace for temporary tables
 
2019-01-17 13:57:06 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
 
2019-01-17 13:57:06 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
 
2019-01-17 13:57:06 0 [Note] InnoDB: Waiting for purge to start
 
2019-01-17 13:57:06 0 [Note] InnoDB: 10.4.2 started; log sequence number 151248; transaction id 34
 
2019-01-17 13:57:06 0 [Note] InnoDB: Loading buffer pool(s) from /home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data/ib_buffer_pool
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_MUTEXES' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'user_variables' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
 
2019-01-17 13:57:06 0 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
 
2019-01-17 13:57:06 0 [Note] WSREP: wsrep_plugin_init()
 
2019-01-17 13:57:06 0 [Note] WSREP: declaring 02b9127f at tcp://127.0.0.1:16045 stable
 
2019-01-17 13:57:06 0 [Note] InnoDB: Buffer pool(s) load completed at 190117 13:57:06
 
2019-01-17 13:57:06 0 [Warning] /home/jan/mysql/10.4-galera-2/sql/mysqld: unknown option '--loose-pam-debug'
 
2019-01-17 13:57:06 0 [Note] WSREP: Node 02ad39ae state prim
 
2019-01-17 13:57:06 0 [Warning] /home/jan/mysql/10.4-galera-2/sql/mysqld: unknown option '--loose-aria'
 
2019-01-17 13:57:06 0 [Note] WSREP: view(view_id(PRIM,02ad39ae,2) memb {
 
	02ad39ae,0
 
	02b9127f,0
 
} joined {
 
} left {
 
} partitioned {
 
})
 
2019-01-17 13:57:06 0 [Note] WSREP: save pc into disk
 
2019-01-17 13:57:06 0 [Note] WSREP: New COMPONENT: primary = yes, bootstrap = no, my_idx = 0, memb_num = 2
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE_EXCHANGE: sent state UUID: 030802a0-1a4f-11e9-b653-632190d3331c
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE EXCHANGE: sent state msg: 030802a0-1a4f-11e9-b653-632190d3331c
 
2019-01-17 13:57:06 0 [Note] WSREP: STATE EXCHANGE: got state msg: 030802a0-1a4f-11e9-b653-632190d3331c from 0 (jan-laptop-asus)
 
2019-01-17 13:57:06 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2019-01-17 13:57:06 0 [Note] WSREP: wsrep_init_schema_and_SR (nil)
 
2019-01-17 13:57:06 1 [Note] WSREP: Cluster table is empty, not recovering transactions
 
2019-01-17 13:57:06 0 [Note] WSREP: Server initialized
 
2019-01-17 13:57:06 0 [Note] WSREP: Server status change initializing -> initialized
 
2019-01-17 13:57:06 0 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 2 [Note] WSREP: Bootstrapping a new cluster, setting initial position to 00000000-0000-0000-0000-000000000000:-1
 
2019-01-17 13:57:06 2 [Note] WSREP: Server status change initialized -> joined
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:06 0 [Note] Reading of all Master_info entries succeded
 
2019-01-17 13:57:06 0 [Note] Added new Master_info '' to hash table
 
2019-01-17 13:57:06 0 [Note] /home/jan/mysql/10.4-galera-2/sql/mysqld: ready for connections.
 
Version: '10.4.2-MariaDB-debug-log'  socket: '/home/jan/mysql/10.4-galera-2/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16040  Source distribution
 
2019-01-17 13:57:06 2 [Note] WSREP: Server jan-laptop-asus synced with group
 
2019-01-17 13:57:06 2 [Note] WSREP: Server status change joined -> synced
 
2019-01-17 13:57:06 2 [Note] WSREP: Synchronized with group, ready for connections
 
2019-01-17 13:57:06 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:07 0 [Note] WSREP: STATE EXCHANGE: got state msg: 030802a0-1a4f-11e9-b653-632190d3331c from 1 (jan-laptop-asus)
 
2019-01-17 13:57:07 0 [Note] WSREP: Quorum results:
 
	version    = 5,
 
	component  = PRIMARY,
 
	conf_id    = 1,
 
	members    = 1/2 (joined/total),
 
	act_id     = 1,
 
	last_appl. = 0,
 
	protocols  = 1/10/4 (gcs/repl/appl),
 
	vote policy= 0,
 
	group UUID = 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41
 
2019-01-17 13:57:07 0 [Note] WSREP: Writing down CC checksum: 92b06263 be390147 6b8c85d1 209e4eae at offset 216
 
2019-01-17 13:57:07 0 [Note] WSREP: Flow-control interval: [23, 23]
 
2019-01-17 13:57:07 0 [Note] WSREP: Trying to continue unpaused monitor
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### processing CC 2, local, ordered
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### drain monitors upto 1
 
2019-01-17 13:57:07 2 [Note] WSREP: REPL Protocols: 10 (5, 3)
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### My UUID: 02ad39ae-1a4f-11e9-afbe-37405017b1ca
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### ST not required
 
2019-01-17 13:57:07 2 [Note] WSREP: Skipping cert index reset
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### Adjusting cert position: 1 -> 2
 
2019-01-17 13:57:07 0 [Note] WSREP: Service thread queue flushed.
 
2019-01-17 13:57:07 2 [Note] WSREP: ####### Setting monitor position to 2
 
2019-01-17 13:57:07 2 [Note] WSREP: Lowest cert indnex boundary for CC from group: 2
 
2019-01-17 13:57:07 2 [Note] WSREP: Min available from gcache for CC from group: 1
 
2019-01-17 13:57:07 2 [Note] WSREP: ================================================
 
View:
 
  id: 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:2
 
  seqno: 2
 
  status: 0
 
  prococol_version: 4
 
  own_index: 0
 
  final: 0
 
  members
 
2019-01-17 13:57:07 2 [Note] WSREP:     id: 02ad39ae-1a4f-11e9-afbe-37405017b1ca name: jan-laptop-asus
 
2019-01-17 13:57:07 2 [Note] WSREP:     id: 02b9127f-1a4f-11e9-b88e-db678d822767 name: jan-laptop-asus
 
2019-01-17 13:57:07 2 [Note] WSREP: =================================================
 
2019-01-17 13:57:07 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:08 0 [Note] WSREP: Member 1.0 (jan-laptop-asus) requested state transfer from '*any*'. Selected 0.0 (jan-laptop-asus)(SYNCED) as donor.
 
2019-01-17 13:57:08 0 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 2)
 
2019-01-17 13:57:08 2 [Note] WSREP: Detected STR version: 1, req_len: 110, req: STRv1
 
2019-01-17 13:57:08 2 [Note] WSREP: Cert index preload: 2 -> 2
 
2019-01-17 13:57:08 2 [Note] WSREP: Server status change synced -> donor
 
2019-01-17 13:57:08 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:08 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:08 0 [Note] WSREP: Running: 'wsrep_sst_rsync --role 'donor' --address '127.0.0.2:16047/rsync_sst' --socket '/home/jan/mysql/10.4-galera-2/mysql-test/var/tmp/3/mysqld.1.sock' --datadir '/home/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data/'  --defaults-file '/home/jan/mysql/10.4-galera-2/mysql-test/var/3/my.cnf' --defaults-group-suffix '.1'   --binlog 'mysqld-bin' --gtid '02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:2' --gtid-domain-id '0''
 
2019-01-17 13:57:08 0 [Note] WSREP: async IST sender starting to serve tcp://127.0.0.1:16046 sending 2-2
 
2019-01-17 13:57:08 0 [Note] WSREP: IST sender 2 -> 2
 
2019-01-17 13:57:08 2 [Note] WSREP: sst_donor_thread signaled with 0
 
2019-01-17 13:57:08 0 [Note] WSREP: Flushing tables for SST...
 
2019-01-17 13:57:08 0 [Note] WSREP: pause
 
2019-01-17 13:57:08 0 [Note] WSREP: Provider paused at 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:2 (5)
 
2019-01-17 13:57:08 0 [Note] WSREP: Server paused at: 2
 
2019-01-17 13:57:08 0 [Note] WSREP: Tables flushed.
 
WSREP_SST: [INFO] Preparing binlog files for transfer: (20190117 13:57:08.974)
 
mysqld-bin.000002
 
2019-01-17 13:57:09 0 [Note] WSREP: (02ad39ae, 'tcp://0.0.0.0:16042') turning message relay requesting off
 
2019-01-17 13:57:10 0 [Note] WSREP: resume
 
2019-01-17 13:57:10 0 [Note] WSREP: resuming provider at 5
 
2019-01-17 13:57:10 0 [Note] WSREP: Provider resumed.
 
2019-01-17 13:57:10 0 [Note] WSREP: SST sent: 02b1d2a6-1a4f-11e9-8426-7e98c10f9c41:2
 
2019-01-17 13:57:10 0 [Note] WSREP: Server status change donor -> joined
 
2019-01-17 13:57:10 0 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:10 0 [Note] WSREP: 0.0 (jan-laptop-asus): State transfer to 1.0 (jan-laptop-asus) complete.
 
2019-01-17 13:57:10 0 [Note] WSREP: Shifting DONOR/DESYNCED -> JOINED (TO: 2)
 
2019-01-17 13:57:10 0 [Note] WSREP: Member 0.0 (jan-laptop-asus) synced with group.
 
2019-01-17 13:57:10 0 [Note] WSREP: Shifting JOINED -> SYNCED (TO: 2)
 
2019-01-17 13:57:10 2 [Note] WSREP: Server jan-laptop-asus synced with group
 
2019-01-17 13:57:10 2 [Note] WSREP: Server status change joined -> synced
 
2019-01-17 13:57:10 2 [Note] WSREP: Synchronized with group, ready for connections
 
2019-01-17 13:57:10 2 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
 
2019-01-17 13:57:12 0 [Note] WSREP: async IST sender served
 
2019-01-17 13:57:12 0 [Note] WSREP: 1.0 (jan-laptop-asus): State transfer from 0.0 (jan-laptop-asus) complete.
 
2019-01-17 13:57:12 0 [Note] WSREP: Member 1.0 (jan-laptop-asus) synced with group.
 
mysqld: galera/src/replicator_smm.cpp:1586: wsrep_status_t galera::ReplicatorSMM::release_rollback(galera::TrxHandleMaster&): Assertion `commit_monitor_.last_left() >= ts.global_seqno()' failed.
 
190117 13:57:15 [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.4.2-MariaDB-debug-log
 
key_buffer_size=1048576
 
read_buffer_size=131072
 
max_used_connections=3
 
max_threads=153
 
thread_count=30
 
It is possible that mysqld could use up to 
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63459 K  bytes of memory
 
Hope that's ok; if not, decrease some variables in the equation.
 
 
 
Thread pointer: 0x7f968c000d60
 
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 = 0x7f9701e17dd0 thread_stack 0x49000
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(my_print_stacktrace+0x40)[0x561498f888ba]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(handle_fatal_signal+0x3e1)[0x5614987d398e]
 
/lib/x86_64-linux-gnu/libpthread.so.0(+0x12670)[0x7f971a0b5670]
 
/lib/x86_64-linux-gnu/libc.so.6(gsignal+0x10b)[0x7f9718b2385b]
 
/lib/x86_64-linux-gnu/libc.so.6(abort+0x121)[0x7f9718b0e535]
 
/lib/x86_64-linux-gnu/libc.so.6(+0x2240f)[0x7f9718b0e40f]
 
linux/raise.c:51(__GI_raise)[0x7f9718b1c0a2]
 
/usr/lib/libgalera_4d_smm.so(_ZN6galera13ReplicatorSMM16release_rollbackERNS_15TrxHandleMasterE+0x552)[0x7f9713c31ce0]
 
src/replicator_smm.cpp:1590(galera::ReplicatorSMM::release_rollback(galera::TrxHandleMaster&))[0x7f9713c59dde]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(_ZN5wsrep18wsrep_provider_v267releaseERNS_9ws_handleE+0x4f)[0x561499017fbd]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(_ZN5wsrep11transaction15after_statementEv+0x624)[0x56149900bfe2]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(_ZN5wsrep12client_state15after_statementEv+0x185)[0x561498ffca01]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(+0x761b3b)[0x5614984a2b3b]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(+0x77a459)[0x5614984bb459]
 
/home/jan/mysql/10.4-galera-2/sql/mysqld(_Z16dispatch_command19enum_server_commandP3THDPcjbb+0xf0c)[0x5614984a71c2]
 
src/wsrep_provider_v26.cpp:742(wsrep::wsrep_provider_v26::release(wsrep::ws_handle&))[0x5614984a5c70]
 
src/transaction.cpp:769(wsrep::transaction::after_statement())[0x561498611cb6]
 
src/client_state.cpp:242(wsrep::client_state::after_statement())[0x561498611a24]
 
sql/wsrep_trans_observer.h:309(wsrep_after_statement(THD*))[0x561498f20d1a]
 
nptl/pthread_create.c:487(start_thread)[0x7f971a0aafa3]
 
/lib/x86_64-linux-gnu/libc.so.6(clone+0x3f)[0x7f9718be57ef]
 
 
 
Trying to get some variables.
 
Some pointers may be invalid and cause the dump to abort.
 
Query (0x7f968c0143b8): INSERT INTO t1 VALUES (3)
 
Connection ID (thread ID): 31
 
Status: NOT_KILLED
 
 
 
Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=off,engine_condition_pushdown=off,index_condition_pushdown=on,derived_merge=on,derived_with_keys=on,firstmatch=on,loosescan=on,materialization=on,in_to_exists=on,semijoin=on,partial_match_rowid_merge=on,partial_match_table_scan=on,subquery_cache=on,mrr=off,mrr_cost_based=off,mrr_sort_keys=off,outer_join_with_cache=on,semijoin_with_cache=on,join_cache_incremental=on,join_cache_hashed=on,join_cache_bka=on,optimize_join_buffer_size=off,table_elimination=on,extended_keys=on,exists_to_in=on,orderby_uses_equalities=on,condition_pushdown_for_derived=on,split_materialized=on,condition_pushdown_for_subquery=on
 
 
 
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/jan/mysql/10.4-galera-2/mysql-test/var/3/mysqld.1/data/
 
----------SERVER LOG END-------------
 
 
 
 
 
 - saving '/home/jan/mysql/10.4-galera-2/mysql-test/var/3/log/galera.galera_bf_abort_group_commit-innodb/' to '/home/jan/mysql/10.4-galera-2/mysql-test/var/log/galera.galera_bf_abort_group_commit-innodb/'
 
***Warnings generated in error logs during shutdown after running tests: galera.galera_bf_abort_group_commit
 
 
 
mysqld: galera/src/replicator_smm.cpp:1586: wsrep_status_t galera::ReplicatorSMM::release_rollback(galera::TrxHandleMaster&): Assertion `commit_monitor_.last_left() >= ts.global_seqno()' failed.
 
Attempting backtrace. You can use the following information to find out


Generated at Thu Feb 08 08:42:54 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.