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

Assertion `!vcol->v_indexes.empty() in trx_undo_log_v_idx

    XMLWordPrintable

    Details

      Description

      origin/10.6 a8350cfb5e8366641ec99433a3fbd3fb068a8d6c 2021-06-24T21:56:44+03:00
       
      # 2021-06-29T17:29:34 [1303254] | [rr 1305084 46579]mysqld: /data/Server/10.6U/storage/innobase/trx/trx0rec.cc:138: byte* trx_undo_log_v_idx(buf_block_t*, const dict_table_t*, ulint, byte*, bool): Assertion `!vcol->v_indexes.empty()' failed.
      # 2021-06-29T17:29:34 [1303254] | [rr 1305084 46599]210629 17:26:00 [rr 1305084 46602][ERROR] mysqld got signal 6 ;
      # 2021-06-29T17:29:34 [1303254] | Thread 3 (Thread 1305084.1310415):
      # 2021-06-29T17:29:34 [1303254] | #0  __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:50
      # 2021-06-29T17:29:34 [1303254] | #1  0x00006f885b9c8859 in __GI_abort () at abort.c:79
      # 2021-06-29T17:29:34 [1303254] | #2  0x00006f885b9c8729 in __assert_fail_base (fmt=0x6f885bb5e588 "%s%s%s:%u: %s%sAssertion `%s' failed.\n%n", assertion=0x556323407a00 "!vcol->v_indexes.empty()", file=0x5563234051a0 "/data/Server/10.6U/storage/innobase/trx/trx0rec.cc", line=138, function=<optimized out>) at assert.c:92
      # 2021-06-29T17:29:34 [1303254] | #3  0x00006f885b9d9f36 in __GI___assert_fail (assertion=assertion@entry=0x556323407a00 "!vcol->v_indexes.empty()", file=file@entry=0x5563234051a0 "/data/Server/10.6U/storage/innobase/trx/trx0rec.cc", line=line@entry=138, function=function@entry=0x556323407980 "byte* trx_undo_log_v_idx(buf_block_t*, const dict_table_t*, ulint, byte*, bool)") at assert.c:101
      # 2021-06-29T17:29:34 [1303254] | #4  0x00005563224fd6cf in trx_undo_log_v_idx (undo_block=undo_block@entry=0x640006932fe8, table=table@entry=0x61800008ad08, pos=pos@entry=0, ptr=<optimized out>, first_v_col=first_v_col@entry=true) at /usr/include/c++/9/bits/forward_list.h:778
      # 2021-06-29T17:29:34 [1303254] | #5  0x00005563224fed0c in trx_undo_report_insert_virtual (undo_block=undo_block@entry=0x640006932fe8, table=0x61800008ad08, row=row@entry=0x616004ea9c08, ptr=ptr@entry=0x492a5dec92a0) at /data/Server/10.6U/storage/innobase/trx/trx0rec.cc:330
      # 2021-06-29T17:29:34 [1303254] | #6  0x000055632250fde2 in trx_undo_page_report_insert (undo_block=undo_block@entry=0x640006932fe8, trx=trx@entry=0x1c7714904c68, index=index@entry=0x616003832608, clust_entry=clust_entry@entry=0x616004ea9c08, mtr=mtr@entry=0x492a5dec94e0, write_empty=write_empty@entry=false) at /data/Server/10.6U/storage/innobase/trx/trx0rec.cc:466
      # 2021-06-29T17:29:34 [1303254] | #7  0x00005563225172ab in trx_undo_report_row_operation (thr=thr@entry=0x6200001d48a8, index=index@entry=0x616003832608, clust_entry=clust_entry@entry=0x616004ea9c08, update=update@entry=0x0, cmpl_info=cmpl_info@entry=0, rec=rec@entry=0x0, offsets=<optimized out>, roll_ptr=<optimized out>) at /data/Server/10.6U/storage/innobase/trx/trx0rec.cc:2075
      # 2021-06-29T17:29:34 [1303254] | #8  0x0000556322600f35 in btr_cur_ins_lock_and_undo (flags=flags@entry=0, cursor=cursor@entry=0x492a5deca5b0, entry=entry@entry=0x616004ea9c08, thr=thr@entry=0x6200001d48a8, mtr=mtr@entry=0x492a5deca9c0, inherit=inherit@entry=0x492a5dec9ca0) at /data/Server/10.6U/storage/innobase/btr/btr0cur.cc:3300
      # 2021-06-29T17:29:34 [1303254] | #9  0x000055632260f56e in btr_cur_optimistic_insert (flags=flags@entry=0, cursor=cursor@entry=0x492a5deca5b0, offsets=offsets@entry=0x492a5deca4d0, heap=heap@entry=0x492a5deca4b0, entry=entry@entry=0x616004ea9c08, rec=rec@entry=0x492a5deca4f0, big_rec=<optimized out>, n_ext=0, thr=<optimized out>, mtr=<optimized out>) at /data/Server/10.6U/storage/innobase/btr/btr0cur.cc:3527
      # 2021-06-29T17:29:34 [1303254] | #10 0x000055632234ada9 in row_ins_clust_index_entry_low (flags=flags@entry=0, mode=<optimized out>, mode@entry=2, index=index@entry=0x616003832608, n_uniq=n_uniq@entry=0, entry=entry@entry=0x616004ea9c08, n_ext=n_ext@entry=0, thr=<optimized out>) at /data/Server/10.6U/storage/innobase/row/row0ins.cc:2762
      # 2021-06-29T17:29:34 [1303254] | #11 0x000055632234ba85 in row_ins_clust_index_entry (index=index@entry=0x616003832608, entry=entry@entry=0x616004ea9c08, thr=thr@entry=0x6200001d48a8, n_ext=n_ext@entry=0) at /data/Server/10.6U/storage/innobase/row/row0ins.cc:3235
      # 2021-06-29T17:29:34 [1303254] | #12 0x0000556322350b41 in row_ins_index_entry (index=0x616003832608, entry=0x616004ea9c08, thr=thr@entry=0x6200001d48a8) at /data/Server/10.6U/storage/innobase/row/row0ins.cc:3361
      # 2021-06-29T17:29:34 [1303254] | #13 0x0000556322350d2d in row_ins_index_entry_step (node=node@entry=0x6200001d4650, thr=thr@entry=0x6200001d48a8) at /usr/include/c++/9/bits/stl_iterator.h:819
      # 2021-06-29T17:29:34 [1303254] | #14 0x0000556322352487 in row_ins (node=node@entry=0x6200001d4650, thr=thr@entry=0x6200001d48a8) at /data/Server/10.6U/storage/innobase/row/row0ins.cc:3676
      # 2021-06-29T17:29:34 [1303254] | #15 0x000055632235337b in row_ins_step (thr=thr@entry=0x6200001d48a8) at /data/Server/10.6U/storage/innobase/row/row0ins.cc:3822
      # 2021-06-29T17:29:34 [1303254] | #16 0x00005563223a65f5 in row_insert_for_mysql (mysql_rec=mysql_rec@entry=0x6190004777c8 "\375\001", prebuilt=0x6200001d4108, ins_mode=<optimized out>) at /data/Server/10.6U/storage/innobase/row/row0mysql.cc:1349
      # 2021-06-29T17:29:34 [1303254] | #17 0x0000556321fdb189 in ha_innobase::write_row (this=0x61d00059f6b8, record=<optimized out>) at /data/Server/10.6U/storage/innobase/handler/ha_innodb.cc:7780
      # 2021-06-29T17:29:34 [1303254] | #18 0x00005563215a8229 in handler::ha_write_row (this=0x61d00059f6b8, buf=0x6190004777c8 "\375\001") at /data/Server/10.6U/sql/handler.cc:7500
      # 2021-06-29T17:29:34 [1303254] | #19 0x0000556320c587d6 in write_record (thd=thd@entry=0x62b000150218, table=table@entry=0x619000477298, info=info@entry=0x492a5decc2d0, sink=sink@entry=0x0) at /data/Server/10.6U/sql/sql_insert.cc:2113
      # 2021-06-29T17:29:34 [1303254] | #20 0x0000556320c795f4 in mysql_insert (thd=thd@entry=0x62b000150218, table_list=<optimized out>, fields=..., values_list=..., update_fields=..., update_values=..., duplic=<optimized out>, ignore=<optimized out>, result=<optimized out>) at /data/Server/10.6U/sql/sql_insert.cc:1104
      # 2021-06-29T17:29:34 [1303254] | #21 0x0000556320d53335 in mysql_execute_command (thd=thd@entry=0x62b000150218, is_called_from_prepared_stmt=is_called_from_prepared_stmt@entry=false) at /data/Server/10.6U/sql/sql_parse.cc:4568
      # 2021-06-29T17:29:34 [1303254] | #22 0x0000556320d12f99 in mysql_parse (thd=thd@entry=0x62b000150218, rawbuf=<optimized out>, length=<optimized out>, parser_state=parser_state@entry=0x492a5decd390) at /data/Server/10.6U/sql/sql_parse.cc:8028
      # 2021-06-29T17:29:34 [1303254] | #23 0x0000556320d41081 in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x62b000150218, packet=packet@entry=0x629000c6c219 "INSERT INTO t1 (col_int) VALUES (1) /* E_R Thread5 QNO 9 CON_ID 18 */ ", packet_length=packet_length@entry=70, blocking=blocking@entry=true) at /data/Server/10.6U/sql/sql_class.h:1337
      # 2021-06-29T17:29:34 [1303254] | #24 0x0000556320d4927b in do_command (thd=0x62b000150218, blocking=blocking@entry=true) at /data/Server/10.6U/sql/sql_parse.cc:1406
      # 2021-06-29T17:29:34 [1303254] | #25 0x00005563211818a7 in do_handle_one_connection (connect=<optimized out>, connect@entry=0x6080000032b8, put_in_cache=put_in_cache@entry=true) at /data/Server/10.6U/sql/sql_connect.cc:1410
      # 2021-06-29T17:29:34 [1303254] | #26 0x0000556321182455 in handle_one_connection (arg=0x6080000032b8) at /data/Server/10.6U/sql/sql_connect.cc:1312
      # 2021-06-29T17:29:34 [1303254] | #27 0x0000268e0fcbd609 in start_thread (arg=<optimized out>) at pthread_create.c:477
      # 2021-06-29T17:29:34 [1303254] | #28 0x00006f885bac5293 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
      # 2021-06-29T17:29:34 [1303254] | Query (0x62b000157238): INSERT INTO t1 (col_int) VALUES (1) /* E_R Thread5 QNO 9 CON_ID 18 */
      # 2021-06-29T17:29:34 [1303254] | [rr 1305084 65711]
      # 2021-06-29T17:29:34 [1303254] | Connection ID (thread ID): 18
      # 2021-06-29T17:29:34 [1303254] | [rr 1305084 65713]Status: KILL_TIMEOUT
       
      pluto:/data/Results/1624985014/TBR-D3_1/dev/shm/vardir/1624985014/118/1/rr
      _RR_TRACE_DIR="." rr replay --mark-stdio
       
      RQG
      ===
      git clone https://github.com/mleich1/rqg --branch experimental RQG
       
      perl rqg.pl \
      --grammar=MDEV-21325_1.yy \
      --gendata=conf/mariadb/table_stress.zz \
      --mysqld=--loose-innodb_lock_schedule_algorithm=fcfs \
      --mysqld=--loose-idle_write_transaction_timeout=0 \
      --mysqld=--loose-idle_transaction_timeout=0 \
      --mysqld=--loose-idle_readonly_transaction_timeout=0 \
      --mysqld=--connect_timeout=60 \
      --mysqld=--interactive_timeout=28800 \
      --mysqld=--slave_net_timeout=60 \
      --mysqld=--net_read_timeout=30 \
      --mysqld=--net_write_timeout=60 \
      --mysqld=--loose-table_lock_wait_timeout=50 \
      --mysqld=--wait_timeout=28800 \
      --mysqld=--lock-wait-timeout=86400 \
      --mysqld=--innodb-lock-wait-timeout=50 \
      --no-mask \
      --queries=10000000 \
      --seed=random \
      --reporters=Backtrace \
      --reporters=ErrorLog \
      --reporters=Deadlock1 \
      --validators=None \
      --mysqld=--log_output=none \
      --mysqld=--log-bin \
      --mysqld=--log_bin_trust_function_creators=1 \
      --mysqld=--loose-debug_assert_on_not_freed_memory=0 \
      --engine=InnoDB \
      --restart_timeout=240 \
      --mysqld=--plugin-load-add=file_key_management.so \
      --mysqld=--loose-file-key-management-filename=$RQG_HOME/conf/mariadb/encryption_keys.txt \
      --duration=300 \
      --mysqld=--loose-innodb_fatal_semaphore_wait_threshold=300 \
      --mysqld=--innodb_stats_persistent=off \
      --mysqld=--innodb_adaptive_hash_index=on \
      --mysqld=--log-bin \
      --mysqld=--loose-innodb_evict_tables_on_commit_debug=off \
      --mysqld=--loose-max-statement-time=30 \
      --threads=9 \
      --mysqld=--innodb-use-native-aio=0 \
      --rr=Extended \
      --wait" \
      --mysqld=--innodb_page_size=8K \
      --mysqld=--innodb-buffer-pool-size=8M \
      --no_mask \
      --workdir=<local settings> \
      --vardir=<local settings> \
      --mtr-build-thread=<local settings> \
      --basedir1=<local settings> \
      --script_debug=_nix_
       
      RQG grammar MDEV-21325_1.yy
      ----------------------------------------------------
      query:
          ALTER TABLE t1 ADD UNIQUE ( col_int_g ) |
          INSERT INTO t1 (col_int) VALUES (1) ;
       
      thread1_init:
          CREATE TABLE t1 (col_int INT , col_int_g INT GENERATED ALWAYS AS (col_int) VIRTUAL) ENGINE = InnoDB ;
       
      Error pattern for RQG
      [ 'TBR-D3-MDEV-26057', 'mysqld: .{1,150}trx0rec.cc:.{1,30}byte\* trx_undo_log_v_idx.{1,200}: Assertion .\!vcol->v_indexes.empty\(\). failed.' ],
       
      Archive: 
      pluto:/data/Results/1624985014/001498.tar.xz
      
      

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              nikitamalyavin Nikita Malyavin
              Reporter:
              mleich Matthias Leich
              Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

                Dates

                Created:
                Updated:

                  Git Integration