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

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