binlog_encryption.rpl_parallel_ignored_errors 'innodb,stmt' w1 [ fail ]

        Test ended at 2020-05-04 04:35:17

CURRENT_TEST: binlog_encryption.rpl_parallel_ignored_errors

mysqltest: In included file "./suite/rpl/include/rpl_parallel_ignored_errors.inc": 

included from /home/buildbot/buildbot/build/mariadb-10.2.32/mysql-test/suite/binlog_encryption/rpl_parallel_ignored_errors.test at line 2:

At line 105: query 'SET GLOBAL debug_dbug=@old_debug' failed: 2013: Lost connection to MySQL server during query

The result from queries just before the failure was:

< snip >

SET debug_sync="debug_sync_action SIGNAL reached_pause WAIT_FOR continue_worker";

connection server_1;

SET gtid_seq_no=100;

INSERT INTO t1 VALUES (33);

connection server_2;

SET debug_sync='now WAIT_FOR reached_pause';

connection server_1;

INSERT INTO t1 VALUES (34);

connection server_2;

connection con_temp2;

COMMIT;

connection server_2;

include/stop_slave.inc

include/assert.inc [table t1 should have zero rows where a>32]

SELECT * FROM t1 WHERE a>32;

a

DELETE FROM t1 WHERE a=32;

SET GLOBAL slave_parallel_threads=@old_parallel_threads;

SET GLOBAL slave_parallel_mode=@old_parallel_mode;

SET GLOBAL debug_dbug=@old_debug;

More results from queries before failure can be found in /dev/shm/var/1/log/rpl_parallel_ignored_errors.log

Server [mysqld.2 - pid: 7165, winpid: 7165, exit: 256] failed during test run

Server log from this test:

----------SERVER LOG START-----------

2020-05-04  4:35:14 140388002363328 [Note] /home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld (mysqld 10.2.32-MariaDB-debug-log) starting as process 7166 ...

2020-05-04  4:35:14 140388002363328 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32186)

2020-05-04  4:35:14 140388002363328 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'SEQUENCE' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'partition' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Uses event mutexes

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Compressed tables use zlib 1.2.11

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Using Linux native AIO

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Number of pools: 1

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Using generic crc32 instructions

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Completed initialization of buffer pool

2020-05-04  4:35:14 140387797407488 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Highest supported file format is Barracuda.

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: 128 out of 128 rollback segments are active.

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Creating shared tablespace for temporary tables

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: File './ibtmp1' size is now 12 MB.

2020-05-04  4:35:14 140388002363328 [Note] InnoDB: 5.7.30 started; log sequence number 1628840

2020-05-04  4:35:14 140387701475072 [Note] InnoDB: Loading buffer pool(s) from /dev/shm/var/1/mysqld.2/data/ib_buffer_pool

2020-05-04  4:35:14 140387701475072 [Note] InnoDB: Buffer pool(s) load completed at 200504  4:35:14

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_MUTEXES' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_CMP' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_DELETED' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_CMP_RESET' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'FEEDBACK' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'user_variables' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.

2020-05-04  4:35:14 140388002363328 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.

2020-05-04  4:35:14 140388002363328 [Warning] /home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld: unknown option '--loose-pam-debug'

2020-05-04  4:35:14 140388002363328 [Note] Server socket created on IP: '127.0.0.1'.

2020-05-04  4:35:14 140388002363328 [Note] Reading of all Master_info entries succeeded

2020-05-04  4:35:14 140388002363328 [Note] Added new Master_info '' to hash table

2020-05-04  4:35:14 140388002363328 [Note] /home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld: ready for connections.

Version: '10.2.32-MariaDB-debug-log'  socket: '/dev/shm/var/tmp/1/mysqld.2.sock'  port: 16001  Source distribution

2020-05-04  4:35:15 140387622995712 [Note] Deleted Master_info file '/dev/shm/var/1/mysqld.2/data/master.info'.

2020-05-04  4:35:15 140387622995712 [Note] Deleted Master_info file '/dev/shm/var/1/mysqld.2/data/relay-log.info'.

2020-05-04  4:35:15 140387622995712 [Note] Master connection name: ''  Master_info_file: 'master.info'  Relay_info_file: 'relay-log.info'

2020-05-04  4:35:15 140387622995712 [Note] 'CHANGE MASTER TO executed'. Previous state master_host='', master_port='3306', master_log_file='', master_log_pos='4'. New state master_host='127.0.0.1', master_port='16000', master_log_file='master-bin.000001', master_log_pos='4'.

2020-05-04  4:35:15 140387619055360 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication started in log 'master-bin.000001' at position 4

2020-05-04  4:35:15 140387618584320 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 4, relay log './slave-relay-bin.000001' position: 4

2020-05-04  4:35:15 140387618584320 [Note] Error reading relay log event: slave SQL thread was killed

2020-05-04  4:35:15 140387618584320 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 369

2020-05-04  4:35:15 140387619055360 [Note] Slave I/O thread exiting, read up to log 'master-bin.000001', position 369

2020-05-04  4:35:15 140387622995712 [Note] Master connection name: ''  Master_info_file: 'master.info'  Relay_info_file: 'relay-log.info'

2020-05-04  4:35:15 140387622995712 [Note] 'CHANGE MASTER TO executed'. Previous state master_host='127.0.0.1', master_port='16000', master_log_file='master-bin.000001', master_log_pos='369'. New state master_host='127.0.0.1', master_port='16000', master_log_file='master-bin.000001', master_log_pos='369'.

2020-05-04  4:35:15 140387622995712 [Note] Previous Using_Gtid=No. New Using_Gtid=Slave_Pos

2020-05-04  4:35:15 140387618584320 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication starts at GTID position ''

2020-05-04  4:35:15 140387619055360 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 369, relay log './slave-relay-bin.000001' position: 4; GTID position ''

2020-05-04  4:35:15 140387616610048 [ERROR] Slave SQL: Error 'Duplicate entry '32' for key 'PRIMARY'' on query. Default database: 'test'. Query: 'INSERT INTO t1 VALUES (32)', Gtid 0-1-3, Internal MariaDB error code: 1062

2020-05-04  4:35:15 140387616610048 [Warning] Slave: Duplicate entry '32' for key 'PRIMARY' Error_code: 1062

2020-05-04  4:35:15 140387616610048 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'master-bin.000001' position 682; GTID position '0-1-2'

2020-05-04  4:35:15 140387619055360 [Note] Error reading relay log event: slave SQL thread was killed

2020-05-04  4:35:15 140387617081088 [ERROR] Slave (additional info): Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2020-05-04  4:35:15 140387617081088 [Warning] Slave: Commit failed due to failure of an earlier commit on which this one depends Error_code: 1964

2020-05-04  4:35:15 140387617081088 [ERROR] Error running query, slave SQL thread aborted. Fix the problem, and restart the slave SQL thread with "SLAVE START". We stopped at log 'master-bin.000001' position 682; GTID position '0-1-2'

2020-05-04  4:35:15 140387619055360 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 682; GTID position '0-1-2'

2020-05-04  4:35:15 140387618584320 [Note] Slave I/O thread exiting, read up to log 'master-bin.000001', position 1180; GTID position 0-1-101

200504  4:35:16 [ERROR] mysqld got signal 11 ;

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.2.32-MariaDB-debug-log

key_buffer_size=1048576

read_buffer_size=131072

max_used_connections=5

max_threads=153

thread_count=12

It is possible that mysqld could use up to 

key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63103 K  bytes of memory

Hope that's ok; if not, decrease some variables in the equation.

Thread pointer: 0x62a00003c208

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 = 0x7fae90980b70 thread_stack 0x5b000

/home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld(__interceptor_backtrace+0x5b)[0x5cb62b]

/home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld(my_print_stacktrace+0x10e)[0x22639ae]

/home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld(handle_fatal_signal+0x67a)[0xe95eca]

/lib/x86_64-linux-gnu/libpthread.so.0(+0x153c0)[0x7faea164c3c0]

/home/buildbot/buildbot/build/mariadb-10.2.32/sql/mysqld(_db_doprnt_+0x2a7)[0x22b88f7]

maria/ma_check.c:535(maria_chk_key)[0x16dc414]

sql/sql_string.h:351(String::free())[0x1897d3c]

/lib/x86_64-linux-gnu/libpthread.so.0(+0x9609)[0x7faea1640609]

/lib/x86_64-linux-gnu/libc.so.6(clone+0x43)[0x7faea1156153]

Trying to get some variables.

Some pointers may be invalid and cause the dump to abort.

Query (0x0): 

Connection ID (thread ID): 2

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

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...

Working directory at /dev/shm/var/1/mysqld.2/data

Resource Limits:

Limit                     Soft Limit           Hard Limit           Units     

Max cpu time              unlimited            unlimited            seconds   

Max file size             unlimited            unlimited            bytes     

Max data size             unlimited            unlimited            bytes     

Max stack size            8388608              unlimited            bytes     

Max core file size        unlimited            unlimited            bytes     

Max resident set          unlimited            unlimited            bytes     

Max processes             23440                23440                processes 

Max open files            1024                 1024                 files     

Max locked memory         67108864             67108864             bytes     

Max address space         unlimited            unlimited            bytes     

Max file locks            unlimited            unlimited            locks     

Max pending signals       23440                23440                signals   

Max msgqueue size         819200               819200               bytes     

Max nice priority         0                    0                    

Max realtime priority     0                    0                    

Max realtime timeout      unlimited            unlimited            us        

Core pattern: |/usr/share/apport/apport %p %s %c %d %P ...

----------SERVER LOG END-------------