[MDEV-16887] innobase/trx/trx0rec.cc .. Assertion `n_idx > 0' failed - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Major
Resolution: Duplicate
Affects Version/s: 10.2.17, 10.3.9
Fix Version/s: N/A
Component/s: Storage Engine - InnoDB, Virtual Columns
Labels:
None
Environment:
Ubuntu 17.10 but most probably unimportant

Description

Assert hit during RQG testing.

   10.3 commit 1b87cd80a268d333acbf4fc4a3d7527cb5da3d4d 2018-08-02

compiled from source with debug.

Version: '10.3.9-MariaDB-debug-log'  ...

mysqld: storage/innobase/trx/trx0rec.cc:237: byte* trx_undo_log_v_idx(buf_block_t*, const dict_table_t*, ulint, byte*, bool): Assertion `n_idx > 0' failed.

# 2018-08-02T17:37:00 [17435] | 180802 17:36:55 [ERROR] mysqld got signal 6 ;

...

Query (0x7f4698012d70): INSERT INTO t1 (col1,col2,col_int,col_text) VALUES (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ), (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ) /* E_R Thread6 QNO 34 CON_ID 20 */

Connection ID (thread ID): 20

Status: NOT_KILLED

...

#3  <signal handler called>

#4  __GI_raise (sig=sig@entry=6)

    at ../sysdeps/unix/sysv/linux/raise.c:51

#5  __GI_abort ()

    at abort.c:90

#6  __assert_fail_base (fmt=<optimized out>, assertion=assertion@entry=0x564f493f1680 "n_idx > 0", file=file@entry=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=line@entry=237, function=function@entry=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t*, dict_table_t const*, unsigned long, unsigned char*, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t*, const dict_table_t*, ulint, byte*, bool)")

    at assert.c:92

#7  __GI___assert_fail (assertion=0x564f493f1680 "n_idx > 0", file=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=237, function=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t*, dict_table_t const*, unsigned long, unsigned char*, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t*, const dict_table_t*, ulint, byte*, bool)")

    at assert.c:101

#8  trx_undo_log_v_idx (undo_block=0x7f4727fcc400, table=0x7f469c031858, pos=0, ptr=0x7f4728b7b024 "", first_v_col=true)

    at storage/innobase/trx/trx0rec.cc:237

#9  trx_undo_report_insert_virtual (undo_block=0x7f4727fcc400, table=0x7f469c031858, row=0x7f46a801d3c8, ptr=0x7f473c45aee8)

    at storage/innobase/trx/trx0rec.cc:417

#10 trx_undo_page_report_insert (undo_block=0x7f4727fcc400, trx=0x7f473e667ad8, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, mtr=0x7f473c45afe0)

    at storage/innobase/trx/trx0rec.cc:541

#11 trx_undo_report_row_operation (thr=0x7f4698026940, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, update=0x0, cmpl_info=0, rec=0x0, offsets=0x0, roll_ptr=0x7f473c45b580)

    at storage/innobase/trx/trx0rec.cc:2023

#12 btr_cur_ins_lock_and_undo (flags=0, cursor=0x7f473c45ba90, entry=0x7f46a801d3c8, thr=0x7f4698026940, mtr=0x7f473c45beb0, inherit=0x7f473c45b605)

    at storage/innobase/btr/btr0cur.cc:3018

#13 btr_cur_optimistic_insert (flags=0, cursor=0x7f473c45ba90, offsets=0x7f473c45ba30, heap=0x7f473c45ba28, entry=0x7f46a801d3c8, rec=0x7f473c45ba38, big_rec=0x7f473c45ba20, n_ext=0, thr=0x7f4698026940, mtr=0x7f473c45beb0)

    at storage/innobase/btr/btr0cur.cc:3225

#14 row_ins_clust_index_entry_low (flags=0, mode=2, index=0x7f469c036a98, n_uniq=0, entry=0x7f46a801d3c8, n_ext=0, thr=0x7f4698026940, dup_chk_only=false)

    at storage/innobase/row/row0ins.cc:2757

#15 row_ins_clust_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940, n_ext=0, dup_chk_only=false)

    at storage/innobase/row/row0ins.cc:3262

#16 row_ins_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940)

    at storage/innobase/row/row0ins.cc:3389

#17 row_ins_index_entry_step (node=0x7f4698026650, thr=0x7f4698026940)

    at storage/innobase/row/row0ins.cc:3539

#18 row_ins (node=0x7f4698026650, thr=0x7f4698026940)

    at storage/innobase/row/row0ins.cc:3681

#19 row_ins_step (thr=0x7f4698026940)

    at storage/innobase/row/row0ins.cc:3935

#20 row_insert_for_mysql (mysql_rec=0x7f46980238f0 "\360\001", prebuilt=0x7f4698026038, ins_mode=ROW_INS_NORMAL)

    at storage/innobase/row/row0mysql.cc:1453

#21 ha_innobase::write_row (this=0x7f4698024c30, record=0x7f46980238f0 "\360\001")

    at storage/innobase/handler/ha_innodb.cc:8169

#22 handler::ha_write_row (this=0x7f4698024c30, buf=0x7f46980238f0 "\360\001")

    at sql/handler.cc:6196

#23 write_record (thd=0x7f4698000ce8, table=0x7f4698023cc8, info=0x7f473c45cb90)

    at sql/sql_insert.cc:2020

#24 mysql_insert (thd=0x7f4698000ce8, table_list=0x7f4698012f98, fields=..., values_list=..., update_fields=..., update_values=..., duplic=DUP_ERROR, ignore=false)

    at sql/sql_insert.cc:1063

#25 mysql_execute_command (thd=0x7f4698000ce8)

    at sql/sql_parse.cc:4721

...

Attachments

- Sort By Name
- Sort By Date
- Ascending
- Descending
- Thumbnails
- List
- Download All

7_1.log
168 kB
2018-08-02 16:00
7_1.tgz
3.13 MB
2018-08-02 16:00
combinations.cc
1 kB
2018-08-02 16:00

Issue Links

duplicates

MDEV-24971 SEGV in in row_create_prebuilt

Closed

is caused by

MDEV-5800 indexes on virtual (not materialized) columns

Closed

Activity

Ascending order - Click to sort in descending order

Matthias Leich created issue - 2018-08-02 15:54

Matthias Leich made changes - 2018-08-02 16:00

Field	Original Value	New Value
Attachment		7_1.log [ 45982 ]
Attachment		7_1.tgz [ 45983 ]
Attachment		combinations.cc [ 45984 ]

Matthias Leich added a comment - 2018-08-02 16:13

Content of attached files
-------------------------------
Protocol of RQG run : 7_1.log
Archive (data dir, core, grammars) of RQG run showing the issue: 7_1.tgz
combinations.cc : config file for rqg_batch.pl
Elapsed runtime of rqg_batch.pl till first replay : 89s with 12 RQG runs in parallel
The RQG grammar used is already significant simplified.
rqg_batch.pl with the original grammar (not yet pushed table_stress.yy) replays in a few minutes.

I am currently working on some more simplified grammar.
With some luck I am able to offer some MTR based replay test.

Some replay based on RQG requires my experimental version of RQG.
git clone https://github.com/mleich1/rqg --branch experimental RQG_mleich1

Matthias Leich added a comment - 2018-08-02 16:13 Content of attached files ------------------------------- Protocol of RQG run : 7_1.log Archive (data dir, core, grammars) of RQG run showing the issue: 7_1.tgz combinations.cc : config file for rqg_batch.pl Elapsed runtime of rqg_batch.pl till first replay : 89s with 12 RQG runs in parallel The RQG grammar used is already significant simplified. rqg_batch.pl with the original grammar (not yet pushed table_stress.yy) replays in a few minutes. I am currently working on some more simplified grammar. With some luck I am able to offer some MTR based replay test. Some replay based on RQG requires my experimental version of RQG. git clone https://github.com/mleich1/rqg --branch experimental RQG_mleich1

Matthias Leich added a comment - 2018-08-02 16:21

Replay on
10.2 commit ef3070e997a6e90c8eadaa833dc47324c2167bb4 2018-08-02
10.2.17-MariaDB-debug-log

Matthias Leich added a comment - 2018-08-02 16:21 Replay on 10.2 commit ef3070e997a6e90c8eadaa833dc47324c2167bb4 2018-08-02 10.2.17-MariaDB-debug-log

Matthias Leich made changes - 2018-08-02 16:22

Affects Version/s		10.2.17 [ 23111 ]
Description	{noformat} Assert hit during RQG testing. 10.3 commit 1b87cd80a268d333acbf4fc4a3d7527cb5da3d4d 2018-08-02 compiled from source with debug. Version: '10.3.9-MariaDB-debug-log' ... mysqld: storage/innobase/trx/trx0rec.cc:237: byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte, bool): Assertion `n_idx > 0' failed. # 2018-08-02T17:37:00 [17435] \| 180802 17:36:55 [ERROR] mysqld got signal 6 ; ... Query (0x7f4698012d70): INSERT INTO t1 (col1,col2,col_int,col_text) VALUES (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ), (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ) / E_R Thread6 QNO 34 CON_ID 20 / Connection ID (thread ID): 20 Status: NOT_KILLED ... #3 <signal handler called> #4 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:51 #5 __GI_abort () at abort.c:90 #6 __assert_fail_base (fmt=<optimized out>, assertion=assertion@entry=0x564f493f1680 "n_idx > 0", file=file@entry=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=line@entry=237, function=function@entry=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t, dict_table_t const, unsigned long, unsigned char, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte, bool)") at assert.c:92 #7 __GI___assert_fail (assertion=0x564f493f1680 "n_idx > 0", file=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=237, function=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t, dict_table_t const, unsigned long, unsigned char, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte*, bool)") at assert.c:101 #8 trx_undo_log_v_idx (undo_block=0x7f4727fcc400, table=0x7f469c031858, pos=0, ptr=0x7f4728b7b024 "", first_v_col=true) at storage/innobase/trx/trx0rec.cc:237 #9 trx_undo_report_insert_virtual (undo_block=0x7f4727fcc400, table=0x7f469c031858, row=0x7f46a801d3c8, ptr=0x7f473c45aee8) at storage/innobase/trx/trx0rec.cc:417 #10 trx_undo_page_report_insert (undo_block=0x7f4727fcc400, trx=0x7f473e667ad8, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, mtr=0x7f473c45afe0) at storage/innobase/trx/trx0rec.cc:541 #11 trx_undo_report_row_operation (thr=0x7f4698026940, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, update=0x0, cmpl_info=0, rec=0x0, offsets=0x0, roll_ptr=0x7f473c45b580) at storage/innobase/trx/trx0rec.cc:2023 #12 btr_cur_ins_lock_and_undo (flags=0, cursor=0x7f473c45ba90, entry=0x7f46a801d3c8, thr=0x7f4698026940, mtr=0x7f473c45beb0, inherit=0x7f473c45b605) at storage/innobase/btr/btr0cur.cc:3018 #13 btr_cur_optimistic_insert (flags=0, cursor=0x7f473c45ba90, offsets=0x7f473c45ba30, heap=0x7f473c45ba28, entry=0x7f46a801d3c8, rec=0x7f473c45ba38, big_rec=0x7f473c45ba20, n_ext=0, thr=0x7f4698026940, mtr=0x7f473c45beb0) at storage/innobase/btr/btr0cur.cc:3225 #14 row_ins_clust_index_entry_low (flags=0, mode=2, index=0x7f469c036a98, n_uniq=0, entry=0x7f46a801d3c8, n_ext=0, thr=0x7f4698026940, dup_chk_only=false) at storage/innobase/row/row0ins.cc:2757 #15 row_ins_clust_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940, n_ext=0, dup_chk_only=false) at storage/innobase/row/row0ins.cc:3262 #16 row_ins_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3389 #17 row_ins_index_entry_step (node=0x7f4698026650, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3539 #18 row_ins (node=0x7f4698026650, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3681 #19 row_ins_step (thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3935 #20 row_insert_for_mysql (mysql_rec=0x7f46980238f0 "\360\001", prebuilt=0x7f4698026038, ins_mode=ROW_INS_NORMAL) at storage/innobase/row/row0mysql.cc:1453 #21 ha_innobase::write_row (this=0x7f4698024c30, record=0x7f46980238f0 "\360\001") at storage/innobase/handler/ha_innodb.cc:8169 #22 handler::ha_write_row (this=0x7f4698024c30, buf=0x7f46980238f0 "\360\001") at sql/handler.cc:6196 #23 write_record (thd=0x7f4698000ce8, table=0x7f4698023cc8, info=0x7f473c45cb90) at sql/sql_insert.cc:2020 #24 mysql_insert (thd=0x7f4698000ce8, table_list=0x7f4698012f98, fields=..., values_list=..., update_fields=..., update_values=..., duplic=DUP_ERROR, ignore=false) at sql/sql_insert.cc:1063 #25 mysql_execute_command (thd=0x7f4698000ce8) at sql/sql_parse.cc:4721 ... {noformat}	{noformat} Assert hit during RQG testing. 10.3 commit 1b87cd80a268d333acbf4fc4a3d7527cb5da3d4d 2018-08-02 compiled from source with debug. Version: '10.3.9-MariaDB-debug-log' ... mysqld: storage/innobase/trx/trx0rec.cc:237: byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte, bool): Assertion `n_idx > 0' failed. # 2018-08-02T17:37:00 [17435] \| 180802 17:36:55 [ERROR] mysqld got signal 6 ; ... Query (0x7f4698012d70): INSERT INTO t1 (col1,col2,col_int,col_text) VALUES (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ), (1,1,1,REPEAT(CAST( 1 AS CHAR(1)), @fill_amount) ) / E_R Thread6 QNO 34 CON_ID 20 / Connection ID (thread ID): 20 Status: NOT_KILLED ... #3 <signal handler called> #4 __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:51 #5 __GI_abort () at abort.c:90 #6 __assert_fail_base (fmt=<optimized out>, assertion=assertion@entry=0x564f493f1680 "n_idx > 0", file=file@entry=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=line@entry=237, function=function@entry=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t, dict_table_t const, unsigned long, unsigned char, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte, bool)") at assert.c:92 #7 __GI___assert_fail (assertion=0x564f493f1680 "n_idx > 0", file=0x564f493f1568 "storage/innobase/trx/trx0rec.cc", line=237, function=0x564f493f2a80 <trx_undo_log_v_idx(buf_block_t, dict_table_t const, unsigned long, unsigned char, bool)::__PRETTY_FUNCTION__> "byte* trx_undo_log_v_idx(buf_block_t, const dict_table_t, ulint, byte*, bool)") at assert.c:101 #8 trx_undo_log_v_idx (undo_block=0x7f4727fcc400, table=0x7f469c031858, pos=0, ptr=0x7f4728b7b024 "", first_v_col=true) at storage/innobase/trx/trx0rec.cc:237 #9 trx_undo_report_insert_virtual (undo_block=0x7f4727fcc400, table=0x7f469c031858, row=0x7f46a801d3c8, ptr=0x7f473c45aee8) at storage/innobase/trx/trx0rec.cc:417 #10 trx_undo_page_report_insert (undo_block=0x7f4727fcc400, trx=0x7f473e667ad8, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, mtr=0x7f473c45afe0) at storage/innobase/trx/trx0rec.cc:541 #11 trx_undo_report_row_operation (thr=0x7f4698026940, index=0x7f469c036a98, clust_entry=0x7f46a801d3c8, update=0x0, cmpl_info=0, rec=0x0, offsets=0x0, roll_ptr=0x7f473c45b580) at storage/innobase/trx/trx0rec.cc:2023 #12 btr_cur_ins_lock_and_undo (flags=0, cursor=0x7f473c45ba90, entry=0x7f46a801d3c8, thr=0x7f4698026940, mtr=0x7f473c45beb0, inherit=0x7f473c45b605) at storage/innobase/btr/btr0cur.cc:3018 #13 btr_cur_optimistic_insert (flags=0, cursor=0x7f473c45ba90, offsets=0x7f473c45ba30, heap=0x7f473c45ba28, entry=0x7f46a801d3c8, rec=0x7f473c45ba38, big_rec=0x7f473c45ba20, n_ext=0, thr=0x7f4698026940, mtr=0x7f473c45beb0) at storage/innobase/btr/btr0cur.cc:3225 #14 row_ins_clust_index_entry_low (flags=0, mode=2, index=0x7f469c036a98, n_uniq=0, entry=0x7f46a801d3c8, n_ext=0, thr=0x7f4698026940, dup_chk_only=false) at storage/innobase/row/row0ins.cc:2757 #15 row_ins_clust_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940, n_ext=0, dup_chk_only=false) at storage/innobase/row/row0ins.cc:3262 #16 row_ins_index_entry (index=0x7f469c036a98, entry=0x7f46a801d3c8, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3389 #17 row_ins_index_entry_step (node=0x7f4698026650, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3539 #18 row_ins (node=0x7f4698026650, thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3681 #19 row_ins_step (thr=0x7f4698026940) at storage/innobase/row/row0ins.cc:3935 #20 row_insert_for_mysql (mysql_rec=0x7f46980238f0 "\360\001", prebuilt=0x7f4698026038, ins_mode=ROW_INS_NORMAL) at storage/innobase/row/row0mysql.cc:1453 #21 ha_innobase::write_row (this=0x7f4698024c30, record=0x7f46980238f0 "\360\001") at storage/innobase/handler/ha_innodb.cc:8169 #22 handler::ha_write_row (this=0x7f4698024c30, buf=0x7f46980238f0 "\360\001") at sql/handler.cc:6196 #23 write_record (thd=0x7f4698000ce8, table=0x7f4698023cc8, info=0x7f473c45cb90) at sql/sql_insert.cc:2020 #24 mysql_insert (thd=0x7f4698000ce8, table_list=0x7f4698012f98, fields=..., values_list=..., update_fields=..., update_values=..., duplic=DUP_ERROR, ignore=false) at sql/sql_insert.cc:1063 #25 mysql_execute_command (thd=0x7f4698000ce8) at sql/sql_parse.cc:4721 ... {noformat}

Matthias Leich added a comment - 2018-08-03 12:54

Simplified RQG grammar
-------------------------------
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 ;

Experiments showed that the likelihood/speed to replay depends strong on how heavy the CPU load on the box is.
one RQG runner – quite low likelihood to replay at all
nproc concurrent RQG runner – On my notebook the first "replayer" needs less than 60s.

Matthias Leich added a comment - 2018-08-03 12:54 Simplified RQG grammar ------------------------------- 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 ; Experiments showed that the likelihood/speed to replay depends strong on how heavy the CPU load on the box is. one RQG runner – quite low likelihood to replay at all nproc concurrent RQG runner – On my notebook the first "replayer" needs less than 60s.

Matthias Leich made changes - 2018-08-03 12:56

Assignee		Marko Mäkelä [ marko ]
Summary	DRAFT: innobase/trx/trx0rec.cc .. Assertion `n_idx > 0' failed	innobase/trx/trx0rec.cc .. Assertion `n_idx > 0' failed

Marko Mäkelä made changes - 2019-03-26 15:59

Link

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

Marko Mäkelä made changes - 2019-03-26 15:59

Component/s		Virtual Columns [ 10803 ]
Fix Version/s		10.2 [ 14601 ]
Fix Version/s		10.4 [ 22408 ]
Assignee	Marko Mäkelä [ marko ]	Nikita Malyavin [ nikitamalyavin ]

Nikita Malyavin made changes - 2021-06-28 19:07

Link

This issue relates to ~~MDEV-24971~~ [ ~~MDEV-24971~~ ]

Nikita Malyavin made changes - 2021-07-05 13:13

Link

This issue relates to ~~MDEV-24971~~ [ ~~MDEV-24971~~ ]

Nikita Malyavin made changes - 2021-07-05 13:13

Link

This issue duplicates ~~MDEV-24971~~ [ ~~MDEV-24971~~ ]

Nikita Malyavin added a comment - 2021-07-05 13:13

People

Assignee:: Nikita Malyavin

Reporter:: Matthias Leich

Votes:: 0 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Created:: 2018-08-02 15:54

Updated:: 2021-07-05 13:13

Resolved:: 2021-07-05 13:13

Git Integration

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

MariaDB Server

Details

Description

Attachments

Attachments

Issue Links

Activity

People

Dates

Git Integration