MySQL Bugs: #74650: Assertion `inited == NONE' failed in handler::ha

Bug #74650	Assertion `inited == NONE' failed in handler::ha_close
Submitted:	31 Oct 2014 14:17	Modified:	20 Nov 2019 21:49
Reporter:	Ramesh Sivaraman	Email Updates:
Status:	Can't repeat	Impact on me:	None
Category:	MySQL Server: DML	Severity:	S3 (Non-critical)
Version:	5.6.20-debug	OS:	Linux (CentOS 7)
Assigned to:		CPU Architecture:	Any

Description:
mysqld: /ssd/ramesh/mysql-server/mysql-5.6/sql/handler.cc:2569: int handler::ha_close(): Assertion `inited == NONE' failed.
13:52:28 UTC - mysqld got signal 6 ;

GDB info ******
#0  0x00007f636f324771 in pthread_kill () from /lib64/libpthread.so.0
#1  0x0000000000a9035e in my_write_core (sig=6) at /ssd/ramesh/mysql-server/mysql-5.6/mysys/stacktrace.c:422
#2  0x0000000000723688 in handle_fatal_signal (sig=6) at /ssd/ramesh/mysql-server/mysql-5.6/sql/signal_handler.cc:230
#3  <signal handler called>
#4  0x00007f636e1305c9 in raise () from /lib64/libc.so.6
#5  0x00007f636e131cd8 in abort () from /lib64/libc.so.6
#6  0x00007f636e129536 in __assert_fail_base () from /lib64/libc.so.6
#7  0x00007f636e1295e2 in __assert_fail () from /lib64/libc.so.6
#8  0x0000000000637f11 in handler::ha_close (this=0x7f62d287f010) at /ssd/ramesh/mysql-server/mysql-5.6/sql/handler.cc:2569
#9  0x000000000088f5e5 in closefrm (table=0x7f62d295de00, free_share=false) at /ssd/ramesh/mysql-server/mysql-5.6/sql/table.cc:2448
#10 0x00000000007623ed in close_temporary (table=0x7f62d295de00, free_share=true, delete_table=true) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_base.cc:2225
#11 0x0000000000760c29 in close_temporary_tables (thd=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_base.cc:1570
#12 0x00000000009f75b6 in Start_log_event_v3::do_apply_event (this=0x7f62d2908600, rli=0x7f62d2949000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/log_event.cc:5240
#13 0x00000000009f8497 in Format_description_log_event::do_apply_event (this=0x7f62d2908600, rli=0x7f62d2949000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/log_event.cc:5719
#14 0x00000000009f17cb in Log_event::apply_event (this=0x7f62d2908600, rli=0x7f62d2949000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/log_event.cc:3100
#15 0x0000000000a2ec15 in mysql_client_binlog_statement (thd=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_binlog.cc:284
#16 0x00000000007d5c0c in mysql_execute_command (thd=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_parse.cc:4880
#17 0x00000000007d8fa4 in mysql_parse (thd=0x7f62dbf24000, rawbuf=0x7f62d281f010 "BINLOG ' SOgWTg8BAAAAbgAAAHIAAAAAAAQANS42LjMtbTUtZGVidWctbG9n", 'A' <repeats 24 times>, " ", 'A' <repeats 18 times>, "BI6BZOEzgNAAgAEgAEBAQEEgAAVgAEGggAAAAICAgCAAAAAAVAYI8='", length=159, parser_state=0x7f636f91be70) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_parse.cc:6245
#18 0x00000000007cc557 in dispatch_command (command=COM_QUERY, thd=0x7f62dbf24000, packet=0x7f62dbf62001 "BINLOG ' SOgWTg8BAAAAbgAAAHIAAAAAAAQANS42LjMtbTUtZGVidWctbG9n", 'A' <repeats 24 times>, " ", 'A' <repeats 18 times>, "BI6BZOEzgNAAgAEgAEBAQEEgAAVgAEGggAAAAICAgCAAAAAAVAYI8='", packet_length=159) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_parse.cc:1332
#19 0x00000000007cb646 in do_command (thd=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_parse.cc:1034
#20 0x0000000000793a61 in do_handle_one_connection (thd_arg=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_connect.cc:982
#21 0x000000000079354a in handle_one_connection (arg=0x7f62dbf24000) at /ssd/ramesh/mysql-server/mysql-5.6/sql/sql_connect.cc:898
#22 0x0000000000ad4b5c in pfs_spawn_thread (arg=0x7f636b7feb00) at /ssd/ramesh/mysql-server/mysql-5.6/storage/perfschema/pfs.cc:1860
#23 0x00007f636f31fdf3 in start_thread () from /lib64/libpthread.so.0
#24 0x00007f636e1f101d in clone () from /lib64/libc.so.6

How to repeat:
DROP DATABASE test;CREATE DATABASE test;USE test;
create temporary table t1(a int)SELECT 1 a;
HANDLER t1 OPEN;
handler t1 read next;
BINLOG ' SOgWTg8BAAAAbgAAAHIAAAAAAAQANS42LjMtbTUtZGVidWctbG9nAAAAAAAAAAAAAAAAAAAAAAAA AAAAAAAAAAAAAAAAAABI6BZOEzgNAAgAEgAEBAQEEgAAVgAEGggAAAAICAgCAAAAAAVAYI8=';

Hello Ramesh Sivaraman,

Thank you for the report and test case.
Confirmed the issue on 5.6.21 debug build.

Thanks,
Umesh

// 5.6.21 debug build(optimized build is not affected)

(gdb) bt
#0  0x0000000004e3b8ac in pthread_kill () from /lib64/libpthread.so.0
#1  0x0000000000aaa203 in my_write_core (sig=6) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/mysys/stacktrace.c:422
#2  0x00000000007140ea in handle_fatal_signal (sig=6) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/signal_handler.cc:230
#3  <signal handler called>
#4  0x0000000005945625 in raise () from /lib64/libc.so.6
#5  0x0000000005946e05 in abort () from /lib64/libc.so.6
#6  0x000000000593e74e in __assert_fail_base () from /lib64/libc.so.6
#7  0x000000000593e810 in __assert_fail () from /lib64/libc.so.6
#8  0x0000000000618be7 in handler::ha_close (this=0x19a63360) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/handler.cc:2569
#9  0x0000000000891c28 in closefrm (table=0x19a61dc0, free_share=false) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/table.cc:2448
#10 0x000000000075d0b4 in close_temporary (table=0x19a61dc0, free_share=true, delete_table=true) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_base.cc:2225
#11 0x000000000075d550 in close_temporary_tables (thd=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_base.cc:1570
#12 0x0000000000a07490 in Start_log_event_v3::do_apply_event (this=0x19ef4ec0, rli=0x19ef1cc0) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/log_event.cc:5260
#13 0x0000000000a07667 in Format_description_log_event::do_apply_event (this=0x19ef4ec0, rli=0x19ef1cc0) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/log_event.cc:5739
#14 0x0000000000a0a999 in Log_event::apply_event (this=0x19ef4ec0, rli=0x19ef1cc0) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/log_event.cc:3109
#15 0x0000000000a44119 in mysql_client_binlog_statement (thd=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_binlog.cc:284
#16 0x00000000007ce254 in mysql_execute_command (thd=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_parse.cc:4880
#17 0x00000000007cee85 in mysql_parse (thd=0x199a7a20,
    rawbuf=0x19c87740 "BINLOG ' SOgWTg8BAAAAbgAAAHIAAAAAAAQANS42LjMtbTUtZGVidWctbG9n", 'A' <repeats 24 times>, " ", 'A' <repeats 18 times>, "BI6BZOEzgNAAgAEgAEBAQEEgAAVgAEGggAAAAICAgCAAAAAAVAYI8='", length=159, parser_state=0x40b3680) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_parse.cc:6245
#18 0x00000000007d0a2f in dispatch_command (command=COM_QUERY, thd=0x199a7a20,
    packet=0x199ab4a1 "BINLOG ' SOgWTg8BAAAAbgAAAHIAAAAAAAQANS42LjMtbTUtZGVidWctbG9n", 'A' <repeats 24 times>, " ", 'A' <repeats 18 times>, "BI6BZOEzgNAAgAEgAEBAQEEgAAVgAEGggAAAAICAgCAAAAAAVAYI8='", packet_length=159) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_parse.cc:1332
#19 0x00000000007d2269 in do_command (thd=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_parse.cc:1034
#20 0x0000000000788571 in do_handle_one_connection (thd_arg=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_connect.cc:982
#21 0x0000000000788637 in handle_one_connection (arg=0x199a7a20) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/sql/sql_connect.cc:898
#22 0x0000000000d9bbe2 in pfs_spawn_thread (arg=0x19c86ed0) at /export/home/pb2/build/sb_0-13191046-1410443906.78/mysql-5.6.21/storage/perfschema/pfs.cc:1860
#23 0x0000000004e369d1 in start_thread () from /lib64/libpthread.so.0
#24 0x00000000059fb9dd in clone () from /lib64/libc.so.6

Posted by developer:
 
Not reproducible in 5.7.