[MDEV-15060] Assertion `0' failed in row_log_table_apply_op after instant ADD when the table is emptied during subsequent ALTER TABLE - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Major
Resolution: Fixed
Affects Version/s: 10.3.2
Fix Version/s: 10.3.7
Component/s: Storage Engine - InnoDB
Labels:
- online-ddl

Description

Note: run the test case with --mem --repeat=N. For me N=100 has always been enough so far, but it can vary on different machines. --mem is important, at least on my machines, apparently the test is not fast/concurrent enough when it's run on disk.

--source include/have_innodb.inc

--disable_abort_on_error

CREATE TABLE t1 (a INT) ENGINE=InnoDB;

INSERT INTO t1 VALUES (NULL),(NULL);

ALTER TABLE t1 ADD COLUMN b INT;

ALTER TABLE t1 ADD UNIQUE KEY uidx (b);

--connect (con1,localhost,root,,test)

--send

	ALTER TABLE t1 DROP COLUMN b;

--connection default

DELETE FROM t1;

INSERT INTO t1 (b) VALUES (1),(1);

# Cleanup

--connection con1

--reap

--disconnect con1

--connection default

DROP TABLE t1;

10.3 cb16bc95ff
mysqld: /data/src/10.3/storage/innobase/row/row0log.cc:2435: const mrec_t* row_log_table_apply_op(que_thr_t, ulint, row_merge_dup_t, dberr_t, mem_heap_t, mem_heap_t, const mrec_t, const mrec_t, ulint): Assertion `0' failed.
180430 18:12:25 [ERROR] mysqld got signal 6 ;

#7 0x00007f48b64a0ee2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6
#8 0x000055bf625b97d3 in row_log_table_apply_op (thr=0x7f48600771c8, new_trx_id_col=1, dup=0x7f48ab74fd80, error=0x7f48ab74fb2c, offsets_heap=0x7f486003fe60, heap=0x7f486003bdd0, mrec=0x7f48ab60c021 "", mrec_end=0x7f48ab60c06c "", offsets=0x7f48600358c0) at /data/src/10.3/storage/innobase/row/row0log.cc:2435
#9 0x000055bf625bbd36 in row_log_table_apply_ops (thr=0x7f48600771c8, dup=0x7f48ab74fd80, stage=0x7f4860038100) at /data/src/10.3/storage/innobase/row/row0log.cc:3046
#10 0x000055bf625bc2ee in row_log_table_apply (thr=0x7f48600771c8, old_table=0x7f485c03c1e8, table=0x7f48600d48b0, stage=0x7f4860038100) at /data/src/10.3/storage/innobase/row/row0log.cc:3146
#11 0x000055bf62495fdf in commit_try_rebuild (ha_alter_info=0x7f48ab7513f0, ctx=0x7f4860014e88, altered_table=0x7f48600d48b0, old_table=0x7f485c085b70, trx=0x7f48b02dcac8, table_name=0x7f485c08a185 "t1") at /data/src/10.3/storage/innobase/handler/handler0alter.cc:8439
#12 0x000055bf62490618 in ha_innobase::commit_inplace_alter_table (this=0x7f485c087368, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, commit=true) at /data/src/10.3/storage/innobase/handler/handler0alter.cc:9314
#13 0x000055bf62138de6 in handler::ha_commit_inplace_alter_table (this=0x7f485c087368, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, commit=true) at /data/src/10.3/sql/handler.cc:4406
#14 0x000055bf61f23d58 in mysql_inplace_alter_table (thd=0x7f4860000b00, table_list=0x7f4860013bd8, table=0x7f485c085b70, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7f48ab751460, alter_ctx=0x7f48ab752060) at /data/src/10.3/sql/sql_table.cc:7535
#15 0x000055bf61f29c3f in mysql_alter_table (thd=0x7f4860000b00, new_db=0x7f4860005168, new_name=0x7f4860005518, create_info=0x7f48ab752c50, table_list=0x7f4860013bd8, alter_info=0x7f48ab752b90, order_num=0, order=0x0, ignore=false) at /data/src/10.3/sql/sql_table.cc:9670
#16 0x000055bf61faf0fc in Sql_cmd_alter_table::execute (this=0x7f4860014258, thd=0x7f4860000b00) at /data/src/10.3/sql/sql_alter.cc:334
#17 0x000055bf61e55f79 in mysql_execute_command (thd=0x7f4860000b00) at /data/src/10.3/sql/sql_parse.cc:6282
#18 0x000055bf61e5aa46 in mysql_parse (thd=0x7f4860000b00, rawbuf=0x7f4860013af0 "ALTER TABLE t1 DROP COLUMN b", length=28, parser_state=0x7f48ab7545d0, is_com_multi=false, is_next_command=false) at /data/src/10.3/sql/sql_parse.cc:8001
#19 0x000055bf61e48229 in dispatch_command (command=COM_QUERY, thd=0x7f4860000b00, packet=0x7f486000b271 "ALTER TABLE t1 DROP COLUMN b", packet_length=28, is_com_multi=false, is_next_command=false) at /data/src/10.3/sql/sql_parse.cc:1846
#20 0x000055bf61e46c68 in do_command (thd=0x7f4860000b00) at /data/src/10.3/sql/sql_parse.cc:1391
#21 0x000055bf61fa9aef in do_handle_one_connection (connect=0x55bf64f3b540) at /data/src/10.3/sql/sql_connect.cc:1402
#22 0x000055bf61fa987c in handle_one_connection (arg=0x55bf64f3b540) at /data/src/10.3/sql/sql_connect.cc:1308
#23 0x000055bf6242da8d in pfs_spawn_thread (arg=0x55bf64f43d70) at /data/src/10.3/storage/perfschema/pfs.cc:1862
#24 0x00007f48b8177494 in start_thread (arg=0x7f48ab755700) at pthread_create.c:333
#25 0x00007f48b655d93f in clone () from /lib/x86_64-linux-gnu/libc.so.6

Not reproducible on 10.2.

Initially this problem was interchangeable with ~~MDEV-14906~~ (in the sense that they both were happening randomly on the same test cases), but ~~MDEV-14906~~ has been presumably fixed now, and this one still happens.

Attachments

Issue Links

is caused by

MDEV-11369 Instant add column for InnoDB

Closed

Activity

Ascending order - Click to sort in descending order

Elena Stepanova created issue - 2018-01-24 17:30

Elena Stepanova made changes - 2018-01-27 22:35

Field	Original Value	New Value
Description	https://api.travis-ci.org/v3/job/332523466/log.txt {noformat:title=10.3 9aa461b187b3660aa6a1da9d8335ed52af5f45b5} mysqld: /home/travis/src/storage/innobase/row/row0log.cc:2410: const mrec_t* row_log_table_apply_op(que_thr_t, ulint, row_merge_dup_t, dberr_t, mem_heap_t, mem_heap_t, const mrec_t, const mrec_t, ulint): Assertion `0' failed. 180124 16:14:19 [ERROR] mysqld got signal 6 ; Query (0x7fbda80139d8): ALTER TABLE t1 DROP COLUMN col1 /* QNO 17690 CON_ID 19 / Connection ID (thread ID): 19 Status: NOT_KILLED {noformat} {noformat} #7 0x00007fbe75ef7ec2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6 #8 0x0000000000f52d8b in row_log_table_apply_op (thr=0x7fbdab68b3d0, new_trx_id_col=1, dup=0x7fbe74cbc7f0, error=0x7fbe74cbc7a8, offsets_heap=0x7fbdab6b9780, heap=0x7fbdab6b56f0, mrec=0x7fbde00f3023 "", mrec_end=0x7fbde00f3054 "", offsets=0x7fbda8064540) at /home/travis/src/storage/innobase/row/row0log.cc:2410 #9 0x0000000000f550c2 in row_log_table_apply_ops (thr=0x7fbdab68b3d0, dup=0x7fbe74cbc7f0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3020 #10 0x0000000000f5562a in row_log_table_apply (thr=0x7fbdab68b3d0, old_table=0x7fbd8c40e728, table=0x7fbdab69c9a0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3119 #11 0x0000000000e28515 in commit_try_rebuild (ha_alter_info=0x7fbe74cbdfd0, ctx=0x7fbda80151a0, altered_table=0x7fbdab69c9a0, old_table=0x7fbda381be00, trx=0x7fbe752c9a48, table_name=0x7fbda382cdad "t1") at /home/travis/src/storage/innobase/handler/handler0alter.cc:8392 #12 0x0000000000e230cf in ha_innobase::commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/storage/innobase/handler/handler0alter.cc:9268 #13 0x0000000000aa26d0 in handler::ha_commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/sql/handler.cc:4395 #14 0x0000000000881511 in mysql_inplace_alter_table (thd=0x7fbda8000d90, table_list=0x7fbda8013b00, table=0x7fbda381be00, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7fbe74cbf6d0, alter_ctx=0x7fbe74cbe140) at /home/travis/src/sql/sql_table.cc:7624 #15 0x0000000000886e5f in mysql_alter_table (thd=0x7fbda8000d90, new_db=0x7fbda8014130 "test", new_name=0x0, create_info=0x7fbe74cbf9e0, table_list=0x7fbda8013b00, alter_info=0x7fbe74cbfb80, order_num=0, order=0x0, ignore=false) at /home/travis/src/sql/sql_table.cc:9682 #16 0x0000000000910305 in Sql_cmd_alter_table::execute (this=0x7fbda8014160, thd=0x7fbda8000d90) at /home/travis/src/sql/sql_alter.cc:331 #17 0x00000000007b489c in mysql_execute_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:6258 #18 0x00000000007b959b in mysql_parse (thd=0x7fbda8000d90, rawbuf=0x7fbda80139d8 "ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 /", length=58, parser_state=0x7fbe74cc1470, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:7987 #19 0x00000000007a6b30 in dispatch_command (command=COM_QUERY, thd=0x7fbda8000d90, packet=0x7fbda800b211 " ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 */ ", packet_length=60, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:1826 #20 0x00000000007a5557 in do_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:1370 #21 0x000000000090b036 in do_handle_one_connection (connect=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1401 #22 0x000000000090adb8 in handle_one_connection (arg=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1307 #23 0x00007fbe76aa3e9a in start_thread () from /lib/x86_64-linux-gnu/libpthread.so.0 #24 0x00007fbe75fbf2ed in clone () from /lib/x86_64-linux-gnu/libc.so.6 #25 0x0000000000000000 in ?? () {noformat} {noformat:title=experimental 5487313992d46fcdd949bc93f9d5d46973c51666} perl /home/travis/rqg/runall-new.pl --views --validators=TransformerNoComparator --mysqld=--max-statement-time=30 --seed=1516810166 --threads=6 --duration=350 --reporters=Backtrace,ErrorLog,Deadlock --transformers=ExecuteAsUnion,ExecuteAsView --redefine=conf/mariadb/federated.yy --mysqld=--default-storage-engine=MyISAM --mysqld=--query_cache_type=0 --mysqld=--query_cache_size=0 --mysqld=--key_buffer_size=2G --mysqld=--thread_stack=192 --mysqld=--thread_cache_size=8 --mysqld=--myisam_recover_options=BACKUP --mysqld=--character-set-server=utf8mb4 --mysqld=--collation-server=utf8mb4_general_ci --grammar=conf/runtime/alter_online.yy --gendata=conf/runtime/alter_online.zz --engine=MyISAM --basedir1=/home/travis/server --vardir=/home/travis/logs/vardir1_2 {noformat}	https://api.travis-ci.org/v3/job/332523466/log.txt {noformat:title=10.3 9aa461b187b3660aa6a1da9d8335ed52af5f45b5} mysqld: /home/travis/src/storage/innobase/row/row0log.cc:2410: const mrec_t* row_log_table_apply_op(que_thr_t, ulint, row_merge_dup_t, dberr_t, mem_heap_t, mem_heap_t, const mrec_t, const mrec_t, ulint): Assertion `0' failed. 180124 16:14:19 [ERROR] mysqld got signal 6 ; Query (0x7fbda80139d8): ALTER TABLE t1 DROP COLUMN col1 /* QNO 17690 CON_ID 19 / Connection ID (thread ID): 19 Status: NOT_KILLED {noformat} {noformat} #7 0x00007fbe75ef7ec2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6 #8 0x0000000000f52d8b in row_log_table_apply_op (thr=0x7fbdab68b3d0, new_trx_id_col=1, dup=0x7fbe74cbc7f0, error=0x7fbe74cbc7a8, offsets_heap=0x7fbdab6b9780, heap=0x7fbdab6b56f0, mrec=0x7fbde00f3023 "", mrec_end=0x7fbde00f3054 "", offsets=0x7fbda8064540) at /home/travis/src/storage/innobase/row/row0log.cc:2410 #9 0x0000000000f550c2 in row_log_table_apply_ops (thr=0x7fbdab68b3d0, dup=0x7fbe74cbc7f0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3020 #10 0x0000000000f5562a in row_log_table_apply (thr=0x7fbdab68b3d0, old_table=0x7fbd8c40e728, table=0x7fbdab69c9a0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3119 #11 0x0000000000e28515 in commit_try_rebuild (ha_alter_info=0x7fbe74cbdfd0, ctx=0x7fbda80151a0, altered_table=0x7fbdab69c9a0, old_table=0x7fbda381be00, trx=0x7fbe752c9a48, table_name=0x7fbda382cdad "t1") at /home/travis/src/storage/innobase/handler/handler0alter.cc:8392 #12 0x0000000000e230cf in ha_innobase::commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/storage/innobase/handler/handler0alter.cc:9268 #13 0x0000000000aa26d0 in handler::ha_commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/sql/handler.cc:4395 #14 0x0000000000881511 in mysql_inplace_alter_table (thd=0x7fbda8000d90, table_list=0x7fbda8013b00, table=0x7fbda381be00, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7fbe74cbf6d0, alter_ctx=0x7fbe74cbe140) at /home/travis/src/sql/sql_table.cc:7624 #15 0x0000000000886e5f in mysql_alter_table (thd=0x7fbda8000d90, new_db=0x7fbda8014130 "test", new_name=0x0, create_info=0x7fbe74cbf9e0, table_list=0x7fbda8013b00, alter_info=0x7fbe74cbfb80, order_num=0, order=0x0, ignore=false) at /home/travis/src/sql/sql_table.cc:9682 #16 0x0000000000910305 in Sql_cmd_alter_table::execute (this=0x7fbda8014160, thd=0x7fbda8000d90) at /home/travis/src/sql/sql_alter.cc:331 #17 0x00000000007b489c in mysql_execute_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:6258 #18 0x00000000007b959b in mysql_parse (thd=0x7fbda8000d90, rawbuf=0x7fbda80139d8 "ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 /", length=58, parser_state=0x7fbe74cc1470, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:7987 #19 0x00000000007a6b30 in dispatch_command (command=COM_QUERY, thd=0x7fbda8000d90, packet=0x7fbda800b211 " ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 */ ", packet_length=60, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:1826 #20 0x00000000007a5557 in do_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:1370 #21 0x000000000090b036 in do_handle_one_connection (connect=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1401 #22 0x000000000090adb8 in handle_one_connection (arg=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1307 #23 0x00007fbe76aa3e9a in start_thread () from /lib/x86_64-linux-gnu/libpthread.so.0 #24 0x00007fbe75fbf2ed in clone () from /lib/x86_64-linux-gnu/libc.so.6 #25 0x0000000000000000 in ?? () {noformat} {noformat:title=experimental 5487313992d46fcdd949bc93f9d5d46973c51666} perl /home/travis/rqg/runall-new.pl --views --validators=TransformerNoComparator --mysqld=--max-statement-time=30 --seed=1516810166 --threads=6 --duration=350 --reporters=Backtrace,ErrorLog,Deadlock --transformers=ExecuteAsUnion,ExecuteAsView --redefine=conf/mariadb/federated.yy --mysqld=--default-storage-engine=MyISAM --mysqld=--query_cache_type=0 --mysqld=--query_cache_size=0 --mysqld=--key_buffer_size=2G --mysqld=--thread_stack=192 --mysqld=--thread_cache_size=8 --mysqld=--myisam_recover_options=BACKUP --mysqld=--character-set-server=utf8mb4 --mysqld=--collation-server=utf8mb4_general_ci --grammar=conf/runtime/alter_online.yy --gendata=conf/runtime/alter_online.zz --engine=MyISAM --basedir1=/home/travis/server --vardir=/home/travis/logs/vardir1_2 {noformat} Not easily reproducible.

Marko Mäkelä made changes - 2018-02-21 17:58

Labels

online-ddl

Elena Stepanova made changes - 2018-04-30 15:23

Fix Version/s		10.3 [ 22126 ]
Description	https://api.travis-ci.org/v3/job/332523466/log.txt {noformat:title=10.3 9aa461b187b3660aa6a1da9d8335ed52af5f45b5} mysqld: /home/travis/src/storage/innobase/row/row0log.cc:2410: const mrec_t* row_log_table_apply_op(que_thr_t, ulint, row_merge_dup_t, dberr_t, mem_heap_t, mem_heap_t, const mrec_t, const mrec_t, ulint): Assertion `0' failed. 180124 16:14:19 [ERROR] mysqld got signal 6 ; Query (0x7fbda80139d8): ALTER TABLE t1 DROP COLUMN col1 /* QNO 17690 CON_ID 19 / Connection ID (thread ID): 19 Status: NOT_KILLED {noformat} {noformat} #7 0x00007fbe75ef7ec2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6 #8 0x0000000000f52d8b in row_log_table_apply_op (thr=0x7fbdab68b3d0, new_trx_id_col=1, dup=0x7fbe74cbc7f0, error=0x7fbe74cbc7a8, offsets_heap=0x7fbdab6b9780, heap=0x7fbdab6b56f0, mrec=0x7fbde00f3023 "", mrec_end=0x7fbde00f3054 "", offsets=0x7fbda8064540) at /home/travis/src/storage/innobase/row/row0log.cc:2410 #9 0x0000000000f550c2 in row_log_table_apply_ops (thr=0x7fbdab68b3d0, dup=0x7fbe74cbc7f0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3020 #10 0x0000000000f5562a in row_log_table_apply (thr=0x7fbdab68b3d0, old_table=0x7fbd8c40e728, table=0x7fbdab69c9a0, stage=0x7fbdab67b740) at /home/travis/src/storage/innobase/row/row0log.cc:3119 #11 0x0000000000e28515 in commit_try_rebuild (ha_alter_info=0x7fbe74cbdfd0, ctx=0x7fbda80151a0, altered_table=0x7fbdab69c9a0, old_table=0x7fbda381be00, trx=0x7fbe752c9a48, table_name=0x7fbda382cdad "t1") at /home/travis/src/storage/innobase/handler/handler0alter.cc:8392 #12 0x0000000000e230cf in ha_innobase::commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/storage/innobase/handler/handler0alter.cc:9268 #13 0x0000000000aa26d0 in handler::ha_commit_inplace_alter_table (this=0x7fbda37f80e8, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, commit=true) at /home/travis/src/sql/handler.cc:4395 #14 0x0000000000881511 in mysql_inplace_alter_table (thd=0x7fbda8000d90, table_list=0x7fbda8013b00, table=0x7fbda381be00, altered_table=0x7fbdab69c9a0, ha_alter_info=0x7fbe74cbdfd0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7fbe74cbf6d0, alter_ctx=0x7fbe74cbe140) at /home/travis/src/sql/sql_table.cc:7624 #15 0x0000000000886e5f in mysql_alter_table (thd=0x7fbda8000d90, new_db=0x7fbda8014130 "test", new_name=0x0, create_info=0x7fbe74cbf9e0, table_list=0x7fbda8013b00, alter_info=0x7fbe74cbfb80, order_num=0, order=0x0, ignore=false) at /home/travis/src/sql/sql_table.cc:9682 #16 0x0000000000910305 in Sql_cmd_alter_table::execute (this=0x7fbda8014160, thd=0x7fbda8000d90) at /home/travis/src/sql/sql_alter.cc:331 #17 0x00000000007b489c in mysql_execute_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:6258 #18 0x00000000007b959b in mysql_parse (thd=0x7fbda8000d90, rawbuf=0x7fbda80139d8 "ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 /", length=58, parser_state=0x7fbe74cc1470, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:7987 #19 0x00000000007a6b30 in dispatch_command (command=COM_QUERY, thd=0x7fbda8000d90, packet=0x7fbda800b211 " ALTER TABLE t1 DROP COLUMN col1 / QNO 17690 CON_ID 19 */ ", packet_length=60, is_com_multi=false, is_next_command=false) at /home/travis/src/sql/sql_parse.cc:1826 #20 0x00000000007a5557 in do_command (thd=0x7fbda8000d90) at /home/travis/src/sql/sql_parse.cc:1370 #21 0x000000000090b036 in do_handle_one_connection (connect=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1401 #22 0x000000000090adb8 in handle_one_connection (arg=0x3c8c5c0) at /home/travis/src/sql/sql_connect.cc:1307 #23 0x00007fbe76aa3e9a in start_thread () from /lib/x86_64-linux-gnu/libpthread.so.0 #24 0x00007fbe75fbf2ed in clone () from /lib/x86_64-linux-gnu/libc.so.6 #25 0x0000000000000000 in ?? () {noformat} {noformat:title=experimental 5487313992d46fcdd949bc93f9d5d46973c51666} perl /home/travis/rqg/runall-new.pl --views --validators=TransformerNoComparator --mysqld=--max-statement-time=30 --seed=1516810166 --threads=6 --duration=350 --reporters=Backtrace,ErrorLog,Deadlock --transformers=ExecuteAsUnion,ExecuteAsView --redefine=conf/mariadb/federated.yy --mysqld=--default-storage-engine=MyISAM --mysqld=--query_cache_type=0 --mysqld=--query_cache_size=0 --mysqld=--key_buffer_size=2G --mysqld=--thread_stack=192 --mysqld=--thread_cache_size=8 --mysqld=--myisam_recover_options=BACKUP --mysqld=--character-set-server=utf8mb4 --mysqld=--collation-server=utf8mb4_general_ci --grammar=conf/runtime/alter_online.yy --gendata=conf/runtime/alter_online.zz --engine=MyISAM --basedir1=/home/travis/server --vardir=/home/travis/logs/vardir1_2 {noformat} Not easily reproducible.	_Note: run the test case with \-\-mem \-\-repeat=N. For me N=100 has always been enough so far, but it can vary on different machines. \-\-mem is important, at least on my machines, apparently the test is not fast/concurrent enough when it's run on disk._ {code:sql} --source include/have_innodb.inc --disable_abort_on_error CREATE TABLE t1 (a INT) ENGINE=InnoDB; INSERT INTO t1 VALUES (NULL),(NULL); ALTER TABLE t1 ADD COLUMN b INT; ALTER TABLE t1 ADD UNIQUE KEY uidx (b); --connect (con1,localhost,root,,test) --send ALTER TABLE t1 DROP COLUMN b; --connection default DELETE FROM t1; INSERT INTO t1 (b) VALUES (1),(1); # Cleanup --connection con1 --reap --disconnect con1 --connection default DROP TABLE t1; {code} {noformat:title=10.3 cb16bc95ff} mysqld: /data/src/10.3/storage/innobase/row/row0log.cc:2435: const mrec_t* row_log_table_apply_op(que_thr_t, ulint, row_merge_dup_t, dberr_t, mem_heap_t, mem_heap_t, const mrec_t, const mrec_t, ulint): Assertion `0' failed. 180430 18:12:25 [ERROR] mysqld got signal 6 ; #7 0x00007f48b64a0ee2 in __assert_fail () from /lib/x86_64-linux-gnu/libc.so.6 #8 0x000055bf625b97d3 in row_log_table_apply_op (thr=0x7f48600771c8, new_trx_id_col=1, dup=0x7f48ab74fd80, error=0x7f48ab74fb2c, offsets_heap=0x7f486003fe60, heap=0x7f486003bdd0, mrec=0x7f48ab60c021 "", mrec_end=0x7f48ab60c06c "", offsets=0x7f48600358c0) at /data/src/10.3/storage/innobase/row/row0log.cc:2435 #9 0x000055bf625bbd36 in row_log_table_apply_ops (thr=0x7f48600771c8, dup=0x7f48ab74fd80, stage=0x7f4860038100) at /data/src/10.3/storage/innobase/row/row0log.cc:3046 #10 0x000055bf625bc2ee in row_log_table_apply (thr=0x7f48600771c8, old_table=0x7f485c03c1e8, table=0x7f48600d48b0, stage=0x7f4860038100) at /data/src/10.3/storage/innobase/row/row0log.cc:3146 #11 0x000055bf62495fdf in commit_try_rebuild (ha_alter_info=0x7f48ab7513f0, ctx=0x7f4860014e88, altered_table=0x7f48600d48b0, old_table=0x7f485c085b70, trx=0x7f48b02dcac8, table_name=0x7f485c08a185 "t1") at /data/src/10.3/storage/innobase/handler/handler0alter.cc:8439 #12 0x000055bf62490618 in ha_innobase::commit_inplace_alter_table (this=0x7f485c087368, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, commit=true) at /data/src/10.3/storage/innobase/handler/handler0alter.cc:9314 #13 0x000055bf62138de6 in handler::ha_commit_inplace_alter_table (this=0x7f485c087368, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, commit=true) at /data/src/10.3/sql/handler.cc:4406 #14 0x000055bf61f23d58 in mysql_inplace_alter_table (thd=0x7f4860000b00, table_list=0x7f4860013bd8, table=0x7f485c085b70, altered_table=0x7f48600d48b0, ha_alter_info=0x7f48ab7513f0, inplace_supported=HA_ALTER_INPLACE_NO_LOCK_AFTER_PREPARE, target_mdl_request=0x7f48ab751460, alter_ctx=0x7f48ab752060) at /data/src/10.3/sql/sql_table.cc:7535 #15 0x000055bf61f29c3f in mysql_alter_table (thd=0x7f4860000b00, new_db=0x7f4860005168, new_name=0x7f4860005518, create_info=0x7f48ab752c50, table_list=0x7f4860013bd8, alter_info=0x7f48ab752b90, order_num=0, order=0x0, ignore=false) at /data/src/10.3/sql/sql_table.cc:9670 #16 0x000055bf61faf0fc in Sql_cmd_alter_table::execute (this=0x7f4860014258, thd=0x7f4860000b00) at /data/src/10.3/sql/sql_alter.cc:334 #17 0x000055bf61e55f79 in mysql_execute_command (thd=0x7f4860000b00) at /data/src/10.3/sql/sql_parse.cc:6282 #18 0x000055bf61e5aa46 in mysql_parse (thd=0x7f4860000b00, rawbuf=0x7f4860013af0 "ALTER TABLE t1 DROP COLUMN b", length=28, parser_state=0x7f48ab7545d0, is_com_multi=false, is_next_command=false) at /data/src/10.3/sql/sql_parse.cc:8001 #19 0x000055bf61e48229 in dispatch_command (command=COM_QUERY, thd=0x7f4860000b00, packet=0x7f486000b271 "ALTER TABLE t1 DROP COLUMN b", packet_length=28, is_com_multi=false, is_next_command=false) at /data/src/10.3/sql/sql_parse.cc:1846 #20 0x000055bf61e46c68 in do_command (thd=0x7f4860000b00) at /data/src/10.3/sql/sql_parse.cc:1391 #21 0x000055bf61fa9aef in do_handle_one_connection (connect=0x55bf64f3b540) at /data/src/10.3/sql/sql_connect.cc:1402 #22 0x000055bf61fa987c in handle_one_connection (arg=0x55bf64f3b540) at /data/src/10.3/sql/sql_connect.cc:1308 #23 0x000055bf6242da8d in pfs_spawn_thread (arg=0x55bf64f43d70) at /data/src/10.3/storage/perfschema/pfs.cc:1862 #24 0x00007f48b8177494 in start_thread (arg=0x7f48ab755700) at pthread_create.c:333 #25 0x00007f48b655d93f in clone () from /lib/x86_64-linux-gnu/libc.so.6 {noformat} Not reproducible on 10.2. Initially this problem was interchangeable with ~~MDEV-14906~~ (in the sense that they both were happening randomly on the same test cases), but ~~MDEV-14906~~ has been presumably fixed now, and this one still happens.
Summary	[Draft] Assertion `0' failed in row_log_table_apply_op	Assertion `0' failed in row_log_table_apply_op upon DROP COLUMN

Elena Stepanova made changes - 2018-04-30 15:25

Assignee

Elena Stepanova [ elenst ]

Marko Mäkelä [ marko ]

Marko Mäkelä made changes - 2018-05-02 14:30

Status

Open [ 1 ]

Confirmed [ 10101 ]

Marko Mäkelä made changes - 2018-05-03 10:28

Link

This issue is caused by ~~MDEV-11369~~ [ ~~MDEV-11369~~ ]

Marko Mäkelä made changes - 2018-05-03 10:29

Summary

Assertion `0' failed in row_log_table_apply_op upon DROP COLUMN

Assertion `0' failed in row_log_table_apply_op after instant ADD when the table is emptied during subsequent ALTER TABLE

Marko Mäkelä made changes - 2018-05-03 10:29

Affects Version/s		10.3.2 [ 22533 ]
Affects Version/s	10.3 [ 22126 ]

Marko Mäkelä made changes - 2018-05-03 12:45

issue.field.resolutiondate

2018-05-03 12:45:28.0

2018-05-03 12:45:28.807

Marko Mäkelä made changes - 2018-05-03 12:45

Fix Version/s		10.3.7 [ 23005 ]
Fix Version/s	10.3 [ 22126 ]
Resolution		Fixed [ 1 ]
Status	Confirmed [ 10101 ]	Closed [ 6 ]

Sergei Golubchik made changes - 2021-12-06 21:46

Workflow

MariaDB v3 [ 85123 ]

MariaDB v4 [ 153641 ]

People

Assignee:: Marko Mäkelä

Reporter:: Elena Stepanova

Votes:: 0 Vote for this issue

Watchers:: 2 Start watching this issue

Dates

Created:: 2018-01-24 17:30

Updated:: 2018-05-03 12:45

Resolved:: 2018-05-03 12:45

Git Integration

Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.

MariaDB Server