Failing assertion: i->in_files.count > 0 in file log0online.cc line 1511 | abort in log_online_bitmap_iterator_init
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
| Percona Server moved to https://jira.percona.com/projects/PS |
Fix Released
|
Critical
|
Laurynas Biveinis | |
| 5.1 |
Fix Released
|
High
|
Laurynas Biveinis | |
| 5.5 |
Fix Released
|
High
|
Laurynas Biveinis | |
| 5.6 |
Fix Released
|
Critical
|
Laurynas Biveinis |
Bug Description
2013-05-24 18:37:58 18c7f700 InnoDB: Assertion failure in thread 415758080 in file log0online.cc line 1511
InnoDB: Failing assertion: i->in_files.count > 0
Query (20fc4ef0): SELECT COUNT(*) FROM INFORMATION_
Thread 2 (LWP 10130):
+bt
#0 0x0000003fde20c69c in pthread_kill () from /lib64/
#1 0x00000000006e47ad in handle_fatal_signal (sig=6) at /ssd/5.
#2 <signal handler called>
#3 0x0000003fdde328a5 in raise () from /lib64/libc.so.6
#4 0x0000003fdde34085 in abort () from /lib64/libc.so.6
#5 0x00000000009e7703 in log_online_
#6 0x00000000009b37f8 in i_s_innodb_
#7 0x0000000000797c0d in do_fill_table (thd=0x11da0530, table_list=
#8 0x0000000000797ef6 in get_schema_
#9 0x000000000078fdf5 in JOIN::prepare_
#10 0x000000000074837a in JOIN::exec (this=0x20fc5e00) at /ssd/5.
#11 0x0000000000790208 in mysql_execute_
#12 0x0000000000790964 in mysql_select (thd=0x11da0530, tables=0x20fc5260, wild_num=0, fields=..., conds=<optimized out>, order=0x11da31b8, group=0x11da30f0, having=0x0, select_
#13 0x0000000000790b38 in handle_select (thd=0x11da0530, result=0x20fc5dd8, setup_tables_
#14 0x0000000000765a7d in execute_
#15 0x0000000000767fd7 in mysql_execute_
#16 0x000000000076d110 in mysql_parse (thd=0x11da0530, rawbuf=<optimized out>, length=<optimized out>, parser_
#17 0x000000000076dbaf in dispatch_command (command=<optimized out>, thd=0x11da0530, packet=<optimized out>, packet_
#18 0x000000000076e800 in do_command (thd=0x11da0530) at /ssd/5.
#19 0x000000000073910f in do_handle_
#20 0x0000000000739220 in handle_
#21 0x0000000000c0b949 in pfs_spawn_thread (arg=0x188f04f0) at /ssd/5.
#22 0x0000003fde207851 in start_thread () from /lib64/
#23 0x0000003fddee890d in clone () from /lib64/libc.so.6
Related branches
- Stewart Smith (community): Approve on 2013-05-31
-
Diff: 1153 lines (+400/-142) (has conflicts)16 files modifiedPercona-Server/mysql-test/suite/innodb_plugin/r/percona_changed_page_bmp.result (+7/-0)
Percona-Server/mysql-test/suite/innodb_plugin/r/percona_changed_pages.result (+58/-33)
Percona-Server/mysql-test/suite/innodb_plugin/r/percona_changed_pages_empty.result (+1/-0)
Percona-Server/mysql-test/suite/innodb_plugin/t/percona_changed_page_bmp.test (+33/-0)
Percona-Server/mysql-test/suite/innodb_plugin/t/percona_changed_page_bmp_flush.test (+2/-2)
Percona-Server/mysql-test/suite/innodb_plugin/t/percona_changed_pages.test (+99/-28)
Percona-Server/mysql-test/suite/innodb_plugin/t/percona_changed_pages_empty.test (+4/-2)
Percona-Server/storage/innodb_plugin/handler/i_s.cc (+8/-6)
Percona-Server/storage/innodb_plugin/include/log0log.h (+3/-1)
Percona-Server/storage/innodb_plugin/include/log0online.h (+2/-0)
Percona-Server/storage/innodb_plugin/include/os0file.h (+4/-2)
Percona-Server/storage/innodb_plugin/log/log0log.c (+13/-3)
Percona-Server/storage/innodb_plugin/log/log0online.c (+162/-54)
Percona-Server/storage/innodb_plugin/log/log0recv.c (+4/-3)
Percona-Server/storage/innodb_plugin/os/os0file.c (+0/-2)
Percona-Server/storage/innodb_plugin/srv/srv0start.c (+0/-6)
- Stewart Smith (community): Approve on 2013-05-31
-
Diff: 1312 lines (+537/-143) (has conflicts)19 files modifiedPercona-Server/mysql-test/suite/innodb/r/percona_changed_page_bmp.result (+7/-0)
Percona-Server/mysql-test/suite/innodb/r/percona_changed_pages.result (+58/-33)
Percona-Server/mysql-test/suite/innodb/r/percona_changed_pages_empty.result (+1/-0)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_page_bmp.test (+33/-0)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_page_bmp_flush.test (+2/-2)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_pages.test (+99/-28)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_pages_empty.test (+4/-2)
Percona-Server/mysql-test/suite/sys_vars/r/all_vars.result (+0/-2)
Percona-Server/mysql-test/suite/sys_vars/r/innodb_max_changed_pages_basic.result (+78/-0)
Percona-Server/mysql-test/suite/sys_vars/t/innodb_max_changed_pages_basic.test (+60/-0)
Percona-Server/storage/innobase/handler/i_s.cc (+8/-6)
Percona-Server/storage/innobase/include/log0log.h (+3/-1)
Percona-Server/storage/innobase/include/log0online.h (+2/-0)
Percona-Server/storage/innobase/include/os0file.h (+4/-2)
Percona-Server/storage/innobase/log/log0log.c (+13/-3)
Percona-Server/storage/innobase/log/log0online.c (+161/-53)
Percona-Server/storage/innobase/log/log0recv.c (+4/-3)
Percona-Server/storage/innobase/os/os0file.c (+0/-2)
Percona-Server/storage/innobase/srv/srv0start.c (+0/-6)
- Stewart Smith (community): Approve on 2013-05-31
-
Diff: 1321 lines (+545/-145)18 files modifiedPercona-Server/mysql-test/suite/innodb/r/percona_changed_page_bmp.result (+8/-1)
Percona-Server/mysql-test/suite/innodb/r/percona_changed_pages.result (+59/-33)
Percona-Server/mysql-test/suite/innodb/r/percona_changed_pages_empty.result (+1/-0)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_page_bmp.test (+34/-4)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_page_bmp_flush.test (+2/-2)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_pages.test (+100/-28)
Percona-Server/mysql-test/suite/innodb/t/percona_changed_pages_empty.test (+4/-2)
Percona-Server/mysql-test/suite/sys_vars/r/all_vars.result (+0/-2)
Percona-Server/mysql-test/suite/sys_vars/r/innodb_max_changed_pages_basic.result (+78/-0)
Percona-Server/mysql-test/suite/sys_vars/t/innodb_max_changed_pages_basic.test (+60/-0)
Percona-Server/storage/innobase/handler/i_s.cc (+8/-6)
Percona-Server/storage/innobase/include/log0log.h (+3/-1)
Percona-Server/storage/innobase/include/log0online.h (+2/-0)
Percona-Server/storage/innobase/include/os0file.h (+4/-2)
Percona-Server/storage/innobase/log/log0log.cc (+13/-3)
Percona-Server/storage/innobase/log/log0online.cc (+165/-56)
Percona-Server/storage/innobase/log/log0recv.cc (+4/-3)
Percona-Server/storage/innobase/os/os0file.cc (+0/-2)
Roel Van de Paar (roel11) wrote : | #1 |
Roel Van de Paar (roel11) wrote : | #2 |
Roel Van de Paar (roel11) wrote : | #3 |
Roel Van de Paar (roel11) wrote : | #4 |
Roel Van de Paar (roel11) wrote : | #5 |
Blocks bitmap testing
Roel Van de Paar (roel11) wrote : | #6 |
Another Valgrind found in another run (looks related, but may be different issue altogether)
Thread 12:
Conditional jump or move depends on uninitialised value(s)
at 0x9E70F7: log_online_
by 0x9E8831: log_online_
by 0x9E8AA2: log_online_
by 0xA8FC94: srv_redo_
by 0x3FDE207850: start_thread (in /lib64/
by 0x3FDDEE890C: clone (in /lib64/
The crash is either bug 1179974, either requires fixing that bug first. Closing as duplicate and raising 1179974 priority.
The Valgrind errors are yet another case of bug 1182793.
Roel Van de Paar (roel11) wrote : | #8 |
This bug is still visible in QA tree (with latest updates 29/5/13). New details:
2013-05-29 03:15:34 7f169ffa0700 InnoDB: Assertion failure in thread 139735149971200 in file log0online.cc line 1562
InnoDB: Failing assertion: i->in_files.count > 0
#4 0x0000003fdde34085 in abort () from /lib64/libc.so.6
#5 0x0000000000972c1c in log_online_
Attaching full details in files.
Roel Van de Paar (roel11) wrote : | #9 |
Roel Van de Paar (roel11) wrote : | #10 |
Percona now uses JIRA for bug reports so this bug report is migrated to: https:/
++Valgrinds seen in error log:
2013-05-24 17:15:32 10035 [Note] InnoDB: Highest supported file format is Barracuda. start_bitmap_ file() (log0online.cc:526) read_init( ) (log0online.cc:725) start_or_ create_ for_mysql( ) (srv0start.cc:2327) init(void* ) (ha_innodb.cc:3492) handlerton( st_plugin_ int*) (handler.cc:690) initialize( st_plugin_ int*) (sql_plugin. cc:1126) cc:1417) components( ) (mysqld.cc:4872) libc-2. 12.so)
==10035== Conditional jump or move depends on uninitialised value(s)
==10035== at 0x9E70F7: log_online_
==10035== by 0x9E92CF: log_online_
==10035== by 0xA94A3C: init_log_online() (srv0start.cc:1456)
==10035== by 0xA97D81: innobase_
==10035== by 0x9A2E19: innobase_
==10035== by 0x63CA4A: ha_initialize_
==10035== by 0x77503F: plugin_
==10035== by 0x7771A9: plugin_init(int*, char**, int) (sql_plugin.
==10035== by 0x624981: init_server_
==10035== by 0x6293CF: mysqld_main(int, char**) (mysqld.cc:5503)
==10035== by 0x3FDDE1ECDC: (below main) (in /lib64/
==10035==
2013-05-24 17:15:33 10035 [Note] InnoDB: starting tracking changed pages from LSN 1625977
and
2013-05-24 17:15:41 10035 [Note] /ssd/Percona- Server- 5.6.10- alpha60. 2-347-debug- valgrind. Linux.x86_ 64/bin/ mysqld- debug: ready for connections. alpha60. 2-debug- log' socket: '/ssd/271485/ current1_ 4/tmp/master. sock' port: 13060 Percona Server with XtraDB (GPL), Release alpha60. 2, Revision 347-debug- valgrind innodb_ default_ int` ( `c65` , `c50` , `c74` ) VALUES ( e , 'all' , '22:02:49.049457' ) start_bitmap_ file() (log0online.cc:526) purge_changed_ page_bitmaps( unsigned long) (log0online. cc:1688) purge_changed_ page_bitmaps( unsigned long long) (ha_innodb.cc:3633) foreach_ with_mask( THD*, char (*)(THD*, st_plugin_int**, void*), int, unsigned int, void*) (sql_plugin. cc:2087) changed_ page_bitmaps( unsigned long long) (handler.cc:7095) acl_and_ cache(THD* , unsigned long, TABLE_LIST*, int*) (sql_reload.cc:383) command( THD*) (sql_parse.cc:4374) command( enum_server_ command, THD*, char*, unsigned int) (sql_parse.cc:1359) one_connection( THD*) (sql_connect. cc:1617) one_connection (sql_connect. cc:1528) libpthread- 2.12.so) libc-2. 12.so)
Version: '5.6.11-
2013-05-25T01:37:32 [11566] Query: INSERT IGNORE INTO `view_table100_
==10035== Thread 24:
==10035== Conditional jump or move depends on uninitialised value(s)
==10035== at 0x9E70F7: log_online_
==10035== by 0x9E7451: log_online_
==10035== by 0x9919A8: innobase_
==10035== by 0x7722A5: plugin_
==10035== by 0x6362B7: ha_purge_
==10035== by 0x781736: reload_
==10035== by 0x767C33: mysql_execute_
==10035== by 0x76D10F: mysql_parse(THD*, char*, unsigned int, Parser_state*) (sql_parse.cc:6443)
==10035== by 0x76DBAE: dispatch_
==10035== by 0x76E7FF: do_command(THD*) (sql_parse.cc:1051)
==10035== by 0x73910E: do_handle_
==10035== by 0x73921F: handle_
==10035== by 0xC0B948: pfs_spawn_thread (pfs.cc:1853)
==10035== by 0x3FDE207850: start_thread (in /lib64/
==10035== by 0x3FDDEE890C: clone (in /lib64/
==10035==