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

binlog_encryption.rpl_skip_replication failed with assertion `!strcmp(table_list->get_db_name(), table_list->mdl_request.key.db_name()) && !strcmp(table_list->get_table_name(), table_list->mdl_request.key.name())'

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Duplicate
    • Affects Version/s: 10.3
    • Fix Version/s: N/A
    • Component/s: Tests
    • Labels:
      None

      Description

      http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/9283/steps/test_2/logs/stdio

      uint get_table_def_key(const TABLE_LIST*, const char**): Assertion `!strcmp(table_list->get_db_name(), table_list->mdl_request.key.db_name()) && !strcmp(table_list->get_table_name(), table_list->mdl_request.key.name())' failed.
      

      binlog_encryption.rpl_skip_replication 'innodb,row' w2 [ fail ]
              Test ended at 2017-08-20 23:03:37
       
      CURRENT_TEST: binlog_encryption.rpl_skip_replication
      mysqltest: In included file "./extra/rpl_tests/rpl_skip_replication.inc": 
      included from /mnt/buildbot/build/mariadb-10.3.1/mysql-test/suite/binlog_encryption/rpl_skip_replication.test at line 3:
      At line 124: failed in 'select master_pos_wait('master-bin.000005', 841, 300, '')': 2013: Lost connection to MySQL server during query
       
      The result from queries just before the failure was:
      < snip >
      t1
      t2
      SELECT * FROM t1;
      a	b
      1	NULL
      SELECT * FROM t2;
      a	b
      1	NULL
      connection master;
      DROP TABLE t3;
      FLUSH NO_WRITE_TO_BINLOG LOGS;
      connection slave;
      connection slave;
      STOP SLAVE;
      SET GLOBAL replicate_events_marked_for_skip=REPLICATE;
      START SLAVE;
      connection master;
      SET skip_replication=1;
      CREATE TABLE t3 (a INT PRIMARY KEY, b INT) ENGINE=myisam;
      INSERT INTO t3(a) VALUES(2);
       
      More results from queries before failure can be found in /mnt/buildbot/build/mariadb-10.3.1/mysql-test/var/2/log/rpl_skip_replication.log
       
       
      Server [mysqld.2 - pid: 12891, winpid: 12891, exit: 256] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2017-08-20 23:03:33 3072772416 [Note] /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld (mysqld 10.3.1-MariaDB-debug-log) starting as process 12892 ...
      2017-08-20 23:03:33 3072772416 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
      2017-08-20 23:03:33 3072772416 [Note] Plugin 'partition' is disabled.
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Uses event mutexes
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Using Linux native AIO
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Number of pools: 1
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Using generic crc32 instructions
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Completed initialization of buffer pool
      2017-08-20 23:03:33 2905570112 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Creating shared tablespace for temporary tables
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2017-08-20 23:03:33 3072772416 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
      2017-08-20 23:03:34 3072772416 [Note] InnoDB: Waiting for purge to start
      2017-08-20 23:03:34 3072772416 [Note] InnoDB: 5.7.19 started; log sequence number 1632791
      2017-08-20 23:03:34 2804923200 [Note] InnoDB: Loading buffer pool(s) from /run/shm/var_auto_Payq/2/mysqld.2/data/ib_buffer_pool
      2017-08-20 23:03:34 2804923200 [Note] InnoDB: Buffer pool(s) load completed at 170820 23:03:34
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'SEQUENCE' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_CMP' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'FEEDBACK' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'user_variables' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2017-08-20 23:03:34 3072772416 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2017-08-20 23:03:34 3072772416 [Warning] /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld: unknown option '--loose-pam-debug'
      2017-08-20 23:03:34 3072772416 [Note] Server socket created on IP: '127.0.0.1'.
      2017-08-20 23:03:35 3072772416 [Note] Reading of all Master_info entries succeded
      2017-08-20 23:03:35 3072772416 [Note] Added new Master_info '' to hash table
      2017-08-20 23:03:35 3072772416 [Note] /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld: ready for connections.
      Version: '10.3.1-MariaDB-debug-log'  socket: '/mnt/buildbot/build/mariadb-10.3.1/mysql-test/var/tmp/2/mysqld.2.sock'  port: 16001  Source distribution
      2017-08-20 23:03:35 2957421376 [Note] Deleted Master_info file '/mnt/buildbot/build/mariadb-10.3.1/mysql-test/var/2/mysqld.2/data/master.info'.
      2017-08-20 23:03:35 2957421376 [Note] Deleted Master_info file '/mnt/buildbot/build/mariadb-10.3.1/mysql-test/var/2/mysqld.2/data/relay-log.info'.
      2017-08-20 23:03:35 2957421376 [Note] Master connection name: ''  Master_info_file: 'master.info'  Relay_info_file: 'relay-log.info'
      2017-08-20 23:03:35 2957421376 [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-08-20 23:03:35 2896874304 [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-08-20 23:03:35 2896571200 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 4, relay log './slave-relay-bin.000001' position: 4
      2017-08-20 23:03:35 2896571200 [Note] Error reading relay log event: slave SQL thread was killed
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000001' at position 369
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread exiting, read up to log 'master-bin.000001', position 369
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000001' at position 369, relay log './slave-relay-bin.000002' position: 669
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication started in log 'master-bin.000001' at position 369
      2017-08-20 23:03:35 2896571200 [Note] Error reading relay log event: slave SQL thread was killed
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000003' at position 427
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread exiting, read up to log 'master-bin.000003', position 427
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication started in log 'master-bin.000003' at position 427
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000003' at position 427, relay log './slave-relay-bin.000007' position: 727
      2017-08-20 23:03:35 2896571200 [Note] Error reading relay log event: slave SQL thread was killed
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread exiting, replication stopped in log 'master-bin.000005' at position 427
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread exiting, read up to log 'master-bin.000005', position 427
      2017-08-20 23:03:35 2896874304 [Note] Slave I/O thread: connected to master 'root@127.0.0.1:16000',replication started in log 'master-bin.000005' at position 427
      2017-08-20 23:03:35 2896571200 [Note] Slave SQL thread initialized, starting replication in log 'master-bin.000005' at position 427, relay log './slave-relay-bin.000012' position: 727
      mysqld: /home/buildbot/buildbot/build/mariadb-10.3.1/sql/sql_base.cc:204: uint get_table_def_key(const TABLE_LIST*, const char**): Assertion `!strcmp(table_list->get_db_name(), table_list->mdl_request.key.db_name()) && !strcmp(table_list->get_table_name(), table_list->mdl_request.key.name())' failed.
      170820 23:03:35 [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.1-MariaDB-debug-log
      key_buffer_size=1048576
      read_buffer_size=131072
      max_used_connections=5
      max_threads=153
      thread_count=14
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 61882 K  bytes of memory
      Hope that's ok; if not, decrease some variables in the equation.
       
      Thread pointer: 0xa9500820
      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 = 0xacaac220 thread_stack 0x49000
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(my_print_stacktrace+0x32)[0x8d7e50e]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(handle_fatal_signal+0x3c7)[0x86292ef]
      [0xb77c0400]
      [0xb77c0424]
      /lib/i386-linux-gnu/libc.so.6(gsignal+0x4f)[0xb729c1ef]
      /lib/i386-linux-gnu/libc.so.6(abort+0x175)[0xb729f835]
      /lib/i386-linux-gnu/libc.so.6(+0x27095)[0xb7295095]
      /lib/i386-linux-gnu/libc.so.6(+0x27147)[0xb7295147]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z17get_table_def_keyPK10TABLE_LISTPPKc+0x97)[0x8305860]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z17tdc_acquire_shareP3THDP10TABLE_LISTjPP5TABLE+0x25)[0x857a1da]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z10open_tableP3THDP10TABLE_LISTP18Open_table_context+0x979)[0x83086e2]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld[0x830b452]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z11open_tablesP3THDRK14DDL_options_stPP10TABLE_LISTPjjP19Prelocking_strategy+0x299)[0x830c35f]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z20open_and_lock_tablesP3THDRK14DDL_options_stP10TABLE_LISTbjP19Prelocking_strategy+0xbe)[0x830d83f]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_Z20open_and_lock_tablesP3THDP10TABLE_LISTbj+0x5c)[0x8301566]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_ZN15rpl_slave_state11record_gtidEP3THDPK8rpl_gtidybbPPv+0x252)[0x852df0a]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(_ZN15rpl_slave_state4loadEP3THDPKcjbb+0xff)[0x852f671]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld[0x82c8e5e]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld(handle_slave_io+0x8bc)[0x82d0076]
      /mnt/buildbot/build/mariadb-10.3.1/sql/mysqld[0x883dd98]
      /lib/i386-linux-gnu/libpthread.so.0(+0x6d4c)[0xb7548d4c]
      /lib/i386-linux-gnu/libc.so.6(clone+0x5e)[0xb7358ace]
       
      Trying to get some variables.
      Some pointers may be invalid and cause the dump to abort.
      Query (0x0): 
      Connection ID (thread ID): 21
      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_grouping_derived=on
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              Unassigned
              Reporter:
              alice Alice Sherepa
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved: