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

Galera test failure on galera.galera_bf_abort_group_commit

    XMLWordPrintable

    Details

      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
      
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              teemu.ollakka Teemu Ollakka
              Reporter:
              jplindst Jan Lindström
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

                Dates

                Created:
                Updated:

                  Git Integration

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