binlog_encryption.rpl_typeconv 'row' w1 [ fail ]
|
Test ended at 2017-10-06 15:08:59
|
|
CURRENT_TEST: binlog_encryption.rpl_typeconv
|
mysqltest: In included file "./include/end_include_file.inc":
|
included from ./include/wait_for_slave_param.inc at line 124:
|
included from ./include/wait_for_slave_sql_to_stop.inc at line 36:
|
included from ./include/wait_for_slave_to_stop.inc at line 34:
|
included from ./include/stop_slave.inc at line 84:
|
included from ./include/rpl_reset.inc at line 70:
|
included from ./extra/rpl_tests/type_conversions.test at line 880:
|
included from ./extra/rpl_tests/rpl_typeconv.inc at line 54:
|
included from /usr/share/mysql/mysql-test/suite/binlog_encryption/rpl_typeconv.test at line 2:
|
At line 35: query 'let $_include_file_enabled_query_log= `SELECT SUBSTRING('$_include_file_enabled_query_log', 2)`' failed: 2013: Lost connection to MySQL server during query
|
|
The result from queries just before the failure was:
|
< snip >
|
SELECT @@global.slave_type_conversions;
|
@@global.slave_type_conversions
|
ALL_LOSSY,ALL_NON_LOSSY
|
SET GLOBAL SLAVE_TYPE_CONVERSIONS='ALL_LOSSY,ALL_NON_LOSSY,NONEXISTING_BIT';
|
ERROR 42000: Variable 'slave_type_conversions' can't be set to the value of 'NONEXISTING_BIT'
|
SELECT @@global.slave_type_conversions;
|
@@global.slave_type_conversions
|
ALL_LOSSY,ALL_NON_LOSSY
|
SET GLOBAL SLAVE_TYPE_CONVERSIONS='';
|
**** Running tests with @@SLAVE_TYPE_CONVERSIONS = '' ****
|
include/rpl_reset.inc
|
SET GLOBAL SLAVE_TYPE_CONVERSIONS='ALL_NON_LOSSY';
|
**** Running tests with @@SLAVE_TYPE_CONVERSIONS = 'ALL_NON_LOSSY' ****
|
include/rpl_reset.inc
|
SET GLOBAL SLAVE_TYPE_CONVERSIONS='ALL_LOSSY';
|
**** Running tests with @@SLAVE_TYPE_CONVERSIONS = 'ALL_LOSSY' ****
|
include/rpl_reset.inc
|
SET GLOBAL SLAVE_TYPE_CONVERSIONS='ALL_LOSSY,ALL_NON_LOSSY';
|
**** Running tests with @@SLAVE_TYPE_CONVERSIONS = 'ALL_LOSSY,ALL_NON_LOSSY' ****
|
include/rpl_reset.inc
|
|
More results from queries before failure can be found in /dev/shm/var/1/log/rpl_typeconv.log
|
|
|
Server [mysqld.2 - pid: 14490, winpid: 14490, exit: 256] failed during test run
|
Server log from this test:
|
----------SERVER LOG START-----------
|
2017-10-06 15:08:52 70366620009936 [Note] /usr/sbin/mysqld (mysqld 10.1.29-MariaDB-1~stretch) starting as process 14491 ...
|
2017-10-06 15:08:52 70366620009936 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'partition' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'SEQUENCE' is disabled.
|
innodb_open_files should not be greater than the open_files_limit.
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
|
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Using mutexes to ref count buffer pool pages
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: The InnoDB memory heap is disabled
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Compressed tables use zlib 1.2.8
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Using Linux native AIO
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Using POWER8 crc32 instructions
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Initializing buffer pool, size = 8.0M
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Completed initialization of buffer pool
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Highest supported file format is Barracuda.
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: 128 rollback segment(s) are active.
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Waiting for purge to start
|
2017-10-06 15:08:52 70366620009936 [Note] InnoDB: Percona XtraDB (http://www.percona.com) 5.6.36-82.2 started; log sequence number 1629444
|
2017-10-06 15:08:52 70366277922752 [Note] InnoDB: Dumping buffer pool(s) not yet started
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'XTRADB_RSEG' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMP_PER_INDEX' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_TRX' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_LOCKS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'FEEDBACK' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMP' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_MUTEXES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_BUFFER_PAGE_LRU' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_BUFFER_POOL_STATS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_METRICS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMPMEM' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_TABLES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_BUFFER_PAGE' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_CHANGED_PAGES' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
|
2017-10-06 15:08:52 70366620009936 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-startup-interval=20'
|
2017-10-06 15:08:52 70366620009936 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-first-interval=60'
|
2017-10-06 15:08:52 70366620009936 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-interval=60'
|
2017-10-06 15:08:52 70366620009936 [Warning] /usr/sbin/mysqld: unknown option '--loose-pam-debug'
|
2017-10-06 15:08:52 70366620009936 [Warning] /usr/sbin/mysqld: unknown variable 'loose-debug-sync-timeout=300'
|
2017-10-06 15:08:52 70366620009936 [Note] Server socket created on IP: '127.0.0.1'.
|
2017-10-06 15:08:52 70366620009936 [Note] /usr/sbin/mysqld: ready for connections.
|
Version: '10.1.29-MariaDB-1~stretch' socket: '/dev/shm/var/tmp/1/mysqld.2.sock' port: 16001 mariadb.org binary distribution
|
2017-10-06 15:08:52 70366397887424 [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'.
|
2017-10-06 15:08:52 70366396707776 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 4, relay log './slave-relay-bin.000001' position: 4
|
2017-10-06 15:08:52 70366397100992 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication started in log 'master-bin.000001' at position 4
|
2017-10-06 15:08:52 70366396707776 [ERROR] Slave SQL: Column 1 of table 'test.t1' cannot be converted from type 'tinyblob' to type 'blob', Gtid 0-1-6, Internal MariaDB error code: 1677
|
2017-10-06 15:08:52 70366396707776 [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 1188
|
2017-10-06 15:08:52 70366396707776 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 1188
|
2017-10-06 15:08:52 70366396707776 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 1188, relay log './slave-relay-bin.000002' position: 1441
|
2017-10-06 15:08:52 70366396707776 [Note] 'SQL_SLAVE_SKIP_COUNTER=1' executed at relay_log_file='./slave-relay-bin.000002', relay_log_pos='1441', master_log_name='master-bin.000001', master_log_pos='1188' and new position at relay_log_file='./slave-relay-bin.000002', relay_log_pos='1629', master_log_name='master-bin.000001', master_log_pos='1376'
|
2017-10-06 15:08:52 70366396707776 [ERROR] Slave SQL: Column 1 of table 'test.t1' cannot be converted from type 'tinyblob' to type 'mediumblob', Gtid 0-1-9, Internal MariaDB error code: 1677
|
2017-10-06 15:08:52 70366396707776 [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'
|
...
|
...
|
...
|
2017-10-06 15:08:58 70366396707776 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 13598
|
2017-10-06 15:08:59 70366396707776 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 13598, relay log './slave-relay-bin.000002' position: 13851
|
2017-10-06 15:08:59 70366396707776 [Note] 'SQL_SLAVE_SKIP_COUNTER=1' executed at relay_log_file='./slave-relay-bin.000002', relay_log_pos='13851', master_log_name='master-bin.000001', master_log_pos='13598' and new position at relay_log_file='./slave-relay-bin.000002', relay_log_pos='14037', master_log_name='master-bin.000001', master_log_pos='13784'
|
2017-10-06 15:08:59 70366396707776 [Note] Error reading relay log event: slave SQL thread was killed
|
2017-10-06 15:08:59 70366396707776 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 71159
|
171006 15:08:59 [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.1.29-MariaDB-1~stretch
|
key_buffer_size=1048576
|
read_buffer_size=131072
|
max_used_connections=4
|
max_threads=153
|
thread_count=4
|
It is possible that mysqld could use up to
|
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 62911 K bytes of memory
|
Hope that's ok; if not, decrease some variables in the equation.
|
|
Thread pointer: 0x3fff7a950008
|
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 = 0x3fff74145fe8 thread_stack 0x48400
|
2017-10-06 15:08:59 70366397100992 [Note] Slave I/O thread exiting, read up to log 'master-bin.000001', position 71159
|
/usr/sbin/mysqld(my_print_stacktrace+0x68)[0x5f800b68]
|
/usr/sbin/mysqld(handle_fatal_signal+0x430)[0x5f20b4b0]
|
linux-vdso64.so.1(__kernel_sigtramp_rt64+0x0)[0x3fff815d04d8]
|
/usr/sbin/mysqld(_ZN14rpl_group_info19unmark_start_commitEv+0x34)[0x5f106464]
|
[0x3fff74145750]
|
/usr/sbin/mysqld(_Z17ha_rollback_transP3THDb+0x364)[0x5f20ff34]
|
/usr/sbin/mysqld(_Z14trans_rollbackP3THD+0x78)[0x5f127058]
|
/usr/sbin/mysqld(_ZN3THD7cleanupEv+0x148)[0x5efd8278]
|
/usr/sbin/mysqld(_ZN3THDD1Ev+0x7c8)[0x5efd8e28]
|
/usr/sbin/mysqld(_ZN3THDD0Ev+0x28)[0x5efd8fc8]
|
/usr/sbin/mysqld(handle_slave_sql+0x1da8)[0x5ef7cc58]
|
/usr/sbin/mysqld(+0x965a14)[0x5f535a14]
|
/lib/powerpc64le-linux-gnu/libpthread.so.0(+0x80dc)[0x3fff815880dc]
|
/lib/powerpc64le-linux-gnu/libc.so.6(clone+0x98)[0x3fff80c71568]
|
|
Trying to get some variables.
|
Some pointers may be invalid and cause the dump to abort.
|
Query (0x0):
|
Connection ID (thread ID): 221
|
Status: KILL_CONNECTION
|
|
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=off
|
|
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
|
----------SERVER LOG END-------------
|
|
|
- saving '/dev/shm/var/1/log/binlog_encryption.rpl_typeconv-row/' to '/dev/shm/var/log/binlog_encryption.rpl_typeconv-row/'
|
|
Retrying test binlog_encryption.rpl_typeconv, attempt(2/3)...
|
|
worker[1] > Restart - not started
|
worker[1] > Restart - not started
|
***Warnings generated in error logs during shutdown after running tests: binlog_encryption.rpl_typeconv
|
|
171006 15:08:59 [ERROR] mysqld got signal 11 ;
|
Attempting backtrace. You can use the following information to find out
|