innodb.innodb_bug56680 fails in 5.5.11
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
percona-projects-qa |
Fix Released
|
Undecided
|
Unassigned |
Bug Description
I get the following assertion failure when running innodb.
innodb.
Test ended at 2011-04-11 03:49:59
CURRENT_TEST: innodb.
mysqltest: At line 81: query 'DELETE FROM bug56680 WHERE a=1' failed: 2013: Lost connection to MySQL server during query
The result from queries just before the failure was:
< snip >
b
x
SET GLOBAL tx_isolation=
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
INSERT INTO bug56680 SELECT 0,b,c FROM bug56680;
BEGIN;
SELECT b FROM bug56680 LIMIT 2;
b
x
x
BEGIN;
More results from queries before failure can be found in /home/alexey.
Server [mysqld.1 - pid: 5214, winpid: 5214, exit: 256] failed during test run
Server log from this test:
----------SERVER LOG START-----------
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
InnoDB: DEBUG: update_statistics for test/bug56680.
110411 13:49:58 InnoDB: Assertion failure in thread 1082792256 in file /home/alexey.
InnoDB: Failing assertion: mutex_own(
InnoDB: We intentionally generate a memory trap.
InnoDB: Submit a detailed bug report to http://
InnoDB: If you get repeated assertion failures or crashes, even
InnoDB: immediately after the mysqld startup, there may be
InnoDB: corruption in the InnoDB tablespace. Please refer to
InnoDB: http://
InnoDB: about forcing recovery.
110411 13:49:58 - mysqld got signal 6 ;
This could be because you hit a bug. It is also possible that this binary
or one of the libraries it was linked against is corrupt, improperly built,
or misconfigured. This error can also be caused by malfunctioning hardware.
We will try our best to scrape up some info that will hopefully help diagnose
the problem, but since we have already crashed, something is definitely wrong
and this may fail.
key_buffer_
read_buffer_
max_used_
max_threads=151
thread_count=2
connection_count=2
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_
bytes of memory
Hope that's ok; if not, decrease some variables in the equation.
Thread pointer: 0xe65fe00
Attempting backtrace. You can use the following information to find out
where mysqld died. If you see no messages after this, something went
terribly wrong...
stack_bottom = 0x408a10a0 thread_stack 0x40000
/home/alexey.
/home/alexey.
/lib64/
/lib64/
/lib64/
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/home/alexey.
/lib64/
/lib64/
Trying to get some variables.
Some pointers may be invalid and cause the dump to abort.
Query (0xe5d7ad0): DELETE FROM bug56680 WHERE a=1
Connection ID (thread ID): 618
Status: NOT_KILLED
The manual page at http://
information that should help you find out what is causing the crash.
Writing a core file
----------SERVER LOG END-------------
- saving '/home/
- found 'core.5215' (0/5)
Trying 'dbx' to get a backtrace
Trying 'gdb' to get a backtrace
Core generated by '/home/
Output from gdb follows. The first stack trace is from the failing thread.
The following stack traces are from all threads (so the failing one is
duplicated).
-------
[New Thread 7534]
[New Thread 5230]
[New Thread 5229]
[New Thread 5228]
[New Thread 5227]
[New Thread 5226]
[New Thread 5225]
[New Thread 5224]
[New Thread 5222]
[New Thread 5221]
[New Thread 5220]
[New Thread 5219]
[New Thread 5218]
[New Thread 5217]
[New Thread 5215]
Core was generated by `/home/
Program terminated with signal 6, Aborted.
#0 0x0000003bbe00bd02 in pthread_kill () from /lib64/
#0 0x0000003bbe00bd02 in pthread_kill () from /lib64/
#1 0x00000000004fecc7 in handle_segfault (sig=6) at /home/alexey.
#2 <signal handler called>
#3 0x0000003bbd830265 in raise () from /lib64/libc.so.6
#4 0x0000003bbd831d10 in abort () from /lib64/libc.so.6
#5 0x0000000000923458 in buf_flush_page_try (buf_pool=
#6 0x0000000000919de8 in buf_page_get_gen (space=145, zip_size=0, offset=9, rw_latch=2, guess=0x0, mode=<value optimized out>,
file=0xb6c3e8 "/home/
at /home/alexey.
#7 0x00000000008f492c in btr_cur_
file=0xb6c3e8 "/home/
at /home/alexey.
#8 0x000000000088e2f9 in btr_pcur_open_func (index=0xe5ccd28, entry=0xe65f7d8, mode=4098, pcur=0x4089ee60, mtr=0x4089e990)
at /home/alexey.
#9 row_search_
#10 0x00000000008a6c54 in row_upd (thr=0xe5acd60) at /home/alexey.
#11 row_upd_step (thr=0xe5acd60) at /home/alexey.
#12 0x0000000000885e51 in row_update_
#13 0x000000000085f42f in ha_innobase:
#14 0x00000000006d49f4 in handler:
#15 0x00000000007f1e68 in mysql_delete (thd=0xe65fe00, table_list=
at /home/alexey.
#16 0x0000000000598770 in mysql_execute_
#17 0x000000000059a6eb in mysql_parse (thd=0xe65fe00, rawbuf=<value optimized out>, length=<value optimized out>, parser_
at /home/alexey.
#18 0x000000000059bd4c in dispatch_command (command=COM_QUERY, thd=0xe65fe00, packet=0xe59b8f1 "DELETE FROM bug56680 WHERE a=1", packet_length=30)
at /home/alexey.
#19 0x000000000059c20c in do_command (thd=0xe65fe00) at /home/alexey.
#20 0x0000000000651fb7 in do_handle_
#21 0x00000000006520b4 in handle_
#22 0x0000000000a5b7bb in pfs_spawn_thread (arg=<value optimized out>) at /home/alexey.
#23 0x0000003bbe00673d in start_thread () from /lib64/
#24 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 16 (Thread 5215):
#0 0x0000003bbd8cb186 in poll () from /lib64/libc.so.6
#1 0x0000000000501179 in handle_
#2 0x0000000000506c31 in mysqld_main (argc=46, argv=0xdd49918) at /home/alexey.
#3 0x0000003bbd81d994 in __libc_start_main () from /lib64/libc.so.6
#4 0x00000000004fcee9 in _start ()
Thread 15 (Thread 5217):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=0, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=0) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 14 (Thread 5218):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=1, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=1) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 13 (Thread 5219):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=2, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=2) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 12 (Thread 5220):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=3, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=3) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 11 (Thread 5221):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=4, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=4) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 10 (Thread 5222):
#0 0x0000003199c005b4 in ?? () from /usr/lib64/
#1 0x00000000009b3bf8 in os_aio_
at /home/alexey.
#2 os_aio_linux_handle (global_seg=5, message1=
at /home/alexey.
#3 0x0000000000957942 in fil_aio_wait (segment=5) at /home/alexey.
#4 0x00000000008b18f0 in io_handler_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 9 (Thread 5224):
#0 0x0000003bbe00b150 in pthread_
#1 0x000000000084b8c2 in safe_cond_timedwait (cond=0xe18a918, mp=0xe18a8a0, abstime=0x462a70a0, file=0xffffffff
at /home/alexey.
#2 0x00000000009b67a7 in os_cond_wait_timed (event=0xe18a8a0, time_in_usec=<value optimized out>, reset_sig_
at /home/alexey.
#3 os_event_
at /home/alexey.
#4 0x00000000008af0f1 in srv_lock_
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 8 (Thread 5225):
#0 0x0000003bbe00b150 in pthread_
#1 0x000000000084b8c2 in safe_cond_timedwait (cond=0xe18a858, mp=0xe18a7e0, abstime=0x46ca8050, file=0xffffffff
at /home/alexey.
#2 0x00000000009b67a7 in os_cond_wait_timed (event=0xe18a7e0, time_in_usec=<value optimized out>, reset_sig_
at /home/alexey.
#3 os_event_
at /home/alexey.
#4 0x00000000008b1438 in srv_error_
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 7 (Thread 5226):
#0 0x0000003bbe00b150 in pthread_
#1 0x000000000084b8c2 in safe_cond_timedwait (cond=0xe18a9d8, mp=0xe18a960, abstime=0x476a9090, file=0xffffffff
at /home/alexey.
#2 0x00000000009b67a7 in os_cond_wait_timed (event=0xe18a960, time_in_usec=<value optimized out>, reset_sig_
at /home/alexey.
#3 os_event_
at /home/alexey.
#4 0x00000000008b08ab in srv_monitor_thread (arg=<value optimized out>) at /home/alexey.
#5 0x0000003bbe00673d in start_thread () from /lib64/
#6 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 6 (Thread 5227):
#0 0x0000003bbd8cd212 in select () from /lib64/libc.so.6
#1 0x00000000009b755f in os_thread_sleep (tm=<value optimized out>) at /home/alexey.
#2 0x00000000008ab4ac in srv_LRU_
#3 0x0000003bbe00673d in start_thread () from /lib64/
#4 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 5 (Thread 5228):
#0 0x0000003bbd8cd212 in select () from /lib64/libc.so.6
#1 0x00000000009b755f in os_thread_sleep (tm=<value optimized out>) at /home/alexey.
#2 0x00000000008acfa2 in srv_master_thread (arg=<value optimized out>) at /home/alexey.
#3 0x0000003bbe00673d in start_thread () from /lib64/
#4 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 4 (Thread 5229):
#0 0x0000003bbd8cd212 in select () from /lib64/libc.so.6
#1 0x00000000009b755f in os_thread_sleep (tm=<value optimized out>) at /home/alexey.
#2 0x00000000008b10b3 in srv_purge_thread (arg=<value optimized out>) at /home/alexey.
#3 0x0000003bbe00673d in start_thread () from /lib64/
#4 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 3 (Thread 5230):
#0 0x0000003bbe00e838 in do_sigwait () from /lib64/
#1 0x0000003bbe00e8dd in sigwait () from /lib64/
#2 0x000000000050246c in signal_hand (arg=<value optimized out>) at /home/alexey.
#3 0x0000000000a5b7bb in pfs_spawn_thread (arg=<value optimized out>) at /home/alexey.
#4 0x0000003bbe00673d in start_thread () from /lib64/
#5 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 2 (Thread 7534):
#0 0x0000003bbe00d91b in read () from /lib64/
#1 0x0000000000a922fa in vio_read (vio=0xe5c8030, buf=0xe666c90 "\001", size=4) at /home/alexey.
#2 0x000000000050a249 in my_real_read (net=0xe615170, complen=0x415d8ef0) at /home/alexey.
#3 0x000000000050a53e in my_net_read (net=0x13) at /home/alexey.
#4 0x000000000059c164 in do_command (thd=0xe614ef0) at /home/alexey.
#5 0x0000000000651fb7 in do_handle_
#6 0x00000000006520b4 in handle_
#7 0x0000000000a5b7bb in pfs_spawn_thread (arg=<value optimized out>) at /home/alexey.
#8 0x0000003bbe00673d in start_thread () from /lib64/
#9 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Thread 1 (Thread 7536):
#0 0x0000003bbe00bd02 in pthread_kill () from /lib64/
#1 0x00000000004fecc7 in handle_segfault (sig=6) at /home/alexey.
#2 <signal handler called>
#3 0x0000003bbd830265 in raise () from /lib64/libc.so.6
#4 0x0000003bbd831d10 in abort () from /lib64/libc.so.6
#5 0x0000000000923458 in buf_flush_page_try (buf_pool=
#6 0x0000000000919de8 in buf_page_get_gen (space=145, zip_size=0, offset=9, rw_latch=2, guess=0x0, mode=<value optimized out>,
file=0xb6c3e8 "/home/
at /home/alexey.
#7 0x00000000008f492c in btr_cur_
file=0xb6c3e8 "/home/
at /home/alexey.
#8 0x000000000088e2f9 in btr_pcur_open_func (index=0xe5ccd28, entry=0xe65f7d8, mode=4098, pcur=0x4089ee60, mtr=0x4089e990)
at /home/alexey.
#9 row_search_
#10 0x00000000008a6c54 in row_upd (thr=0xe5acd60) at /home/alexey.
#11 row_upd_step (thr=0xe5acd60) at /home/alexey.
#12 0x0000000000885e51 in row_update_
#13 0x000000000085f42f in ha_innobase:
#14 0x00000000006d49f4 in handler:
#15 0x00000000007f1e68 in mysql_delete (thd=0xe65fe00, table_list=
at /home/alexey.
#16 0x0000000000598770 in mysql_execute_
#17 0x000000000059a6eb in mysql_parse (thd=0xe65fe00, rawbuf=<value optimized out>, length=<value optimized out>, parser_
at /home/alexey.
#18 0x000000000059bd4c in dispatch_command (command=COM_QUERY, thd=0xe65fe00, packet=0xe59b8f1 "DELETE FROM bug56680 WHERE a=1", packet_length=30)
at /home/alexey.
#19 0x000000000059c20c in do_command (thd=0xe65fe00) at /home/alexey.
#20 0x0000000000651fb7 in do_handle_
#21 0x00000000006520b4 in handle_
#22 0x0000000000a5b7bb in pfs_spawn_thread (arg=<value optimized out>) at /home/alexey.
#23 0x0000003bbe00673d in start_thread () from /lib64/
#24 0x0000003bbd8d3f6d in clone () from /lib64/libc.so.6
Changed in percona-projects-qa: | |
status: | New → Confirmed |
assignee: | nobody → Yasufumi Kinoshita (yasufumi-kinoshita) |
Changed in percona-projects-qa: | |
status: | Fix Committed → Fix Released |
The added debug code for the bug56680 (http:// lists.mysql. com/commits/ 121051) was not applied innodb_ split_buf_ pool_mutex. patch yet.
It is only for DEBUG code. Not affects to normal binary.
I will look soon.