$do_test.sh -b mysql-5.1 Logging: ./mysql-test-run.pl --record --force bug43531 090318 18:40:00 [Warning] Forcing shutdown of 1 plugins MySQL Version 5.1.34 Checking supported features... - skipping ndbcluster, mysqld not compiled with ndbcluster - SSL connections supported - binaries are debug compiled Collecting tests... vardir: /users/ssmirnova/src/mysql-5.1/mysql-test/var Checking leftover processes... Removing old var directory... Creating var directory '/users/ssmirnova/src/mysql-5.1/mysql-test/var'... Installing system database... Using server port 44590 ============================================================================== TEST RESULT TIME (ms) ------------------------------------------------------------ worker[1] Using MTR_BUILD_THREAD 250, with reserved ports 12500..12509 timer 3873: expired after 900 seconds worker[1] Trying to dump core for [mysqltest - pid: 3900, winpid: 3900] worker[1] Trying to dump core for [mysqld.1 - pid: 3869, winpid: 3869] main.bug43531 [ fail ] timeout after 900 seconds Test ended at 2009-03-18 18:55:58 Test case timeout after 900 seconds == /users/ssmirnova/src/mysql-5.1/mysql-test/var/log/bug43531.log == SET v2 = RAND() * 1000; DELETE FROM t1 WHERE s1 = v2; DELETE FROM t2 WHERE s1 = v2; END; COMMIT; IF (SELECT COUNT(*) FROM t1 WHERE s1 NOT IN (SELECT s1 FROM t2)) > 0 THEN SET @a:=1; END IF; ROLLBACK; IF (SELECT COUNT(*) FROM t2 WHERE s1 NOT IN (SELECT s1 FROM t1)) > 0 THEN SET @a:=1; END IF; ROLLBACK; SET v = v + 1; END WHILE; END// CALL p(); CALL p(); CALL p(); CALL p(); == /users/ssmirnova/src/mysql-5.1/mysql-test/var/tmp/analyze-timeout-mysqld.1.err == mysqltest: Could not open connection 'default' after 500 attempts: 2002 Can't connect to local MySQL server through socket '/users/ssmirnova/src/mysql-5.1/mysql-test/var/tmp/mysqld.1.sock' (111) - saving '/users/ssmirnova/src/mysql-5.1/mysql-test/var/log/main.bug43531/' to '/users/ssmirnova/src/mysql-5.1/mysql-test/var/log/main.bug43531/' - found 'core.3870' (0/5) Trying 'dbx' to get a backtrace Trying 'gdb' to get a backtrace Core generated by '/users/ssmirnova/src/mysql-5.1/sql/mysqld' 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). -------------------------- Using host libthread_db library "/lib/libthread_db.so.1". Core was generated by `/users/ssmirnova/src/mysql-5.1/sql/mysqld --defaults-group-suffix=.1 --defaults'. Program terminated with signal 6, Aborted. warning: svr4_current_sos: Can't read pathname for load map: Input/output error #0 0x002ce402 in __kernel_vsyscall () #0 0x002ce402 in __kernel_vsyscall () #1 0x0046264f in pthread_kill () from /lib/libpthread.so.0 #2 0x085a46c5 in my_write_core (sig=6) at stacktrace.c:310 #3 0x0824c685 in handle_segfault (sig=6) at mysqld.cc:2513 #4 #5 0x002ce402 in __kernel_vsyscall () #6 0x003afeb1 in ___newselect_nocancel () from /lib/libc.so.6 #7 0x0824e9bf in handle_connections_sockets (arg=0x0) at mysqld.cc:4948 #8 0x08254852 in main (argc=7, argv=0xbf9d5af4) at mysqld.cc:4447 Thread 6 (process 3872): #0 0x002ce402 in __kernel_vsyscall () #1 0x00465272 in do_sigwait () from /lib/libpthread.so.0 #2 0x00465305 in sigwait () from /lib/libpthread.so.0 #3 0x08254c93 in signal_hand (arg=0x0) at mysqld.cc:2715 #4 0x0045fbd4 in start_thread () from /lib/libpthread.so.0 #5 0x003b74fe in clone () from /lib/libc.so.6 Thread 5 (process 3916): #0 0x002ce402 in __kernel_vsyscall () #1 0x00461876 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib/libpthread.so.0 #2 0x085a8cd6 in safe_cond_wait (cond=0x967f78c, mp=0x979633c, file=0x873c4ef "thr_lock.c", line=422) at thr_mutex.c:237 #3 0x085a64c1 in wait_for_lock (wait=0x979637c, data=0x975f140, in_wait_list=0 '\0') at thr_lock.c:422 #4 0x085a6ec6 in thr_lock (data=0x975f140, owner=0x96a1814, lock_type=TL_READ) at thr_lock.c:704 #5 0x085a78f3 in thr_multi_lock (data=0x9798ff8, count=2, owner=0x96a1814) at thr_lock.c:973 #6 0x08247581 in mysql_lock_tables (thd=0x96a0ff8, tables=0x97dbcc8, count=2, flags=4, need_reopen=0xb73f007f) at lock.cc:279 #7 0x082a9c52 in lock_tables (thd=0x96a0ff8, tables=0x977a6a0, count=2, need_reopen=0xb73f007f) at sql_base.cc:5300 #8 0x082b5693 in open_and_lock_tables_derived (thd=0x96a0ff8, tables=0x977a6a0, derived=true) at sql_base.cc:4998 #9 0x0826db3c in open_and_lock_tables (thd=0x96a0ff8, tables=0x977a6a0) at mysql_priv.h:1536 #10 0x08417014 in sp_instr::exec_open_and_lock_tables (this=0x977b338, thd=0x96a0ff8, tables=0x977a6a0) at sp_head.cc:2789 #11 0x084171e1 in sp_lex_keeper::reset_lex_and_exec_core (this=0x977b36c, thd=0x96a0ff8, nextp=0xb73f02dc, open_tables=true, instr=0x977b338) at sp_head.cc:2724 #12 0x08417bea in sp_instr_jump_if_not::execute (this=0x977b338, thd=0x96a0ff8, nextp=0xb73f02dc) at sp_head.cc:3085 #13 0x084137f6 in sp_head::execute (this=0x9711470, thd=0x96a0ff8) at sp_head.cc:1248 #14 0x084152f6 in sp_head::execute_procedure (this=0x9711470, thd=0x96a0ff8, args=0x96a263c) at sp_head.cc:1977 #15 0x0826871f in mysql_execute_command (thd=0x96a0ff8) at sql_parse.cc:4252 #16 0x0826af74 in mysql_parse (thd=0x96a0ff8, inBuf=0x9724dc0 "CALL p()", length=8, found_semicolon=0xb73f12fc) at sql_parse.cc:5831 #17 0x0826bbb0 in dispatch_command (command=COM_QUERY, thd=0x96a0ff8, packet=0x96ef1f9 "", packet_length=8) at sql_parse.cc:1216 #18 0x0826cdce in do_command (thd=0x96a0ff8) at sql_parse.cc:857 #19 0x08259a05 in handle_one_connection (arg=0x96a0ff8) at sql_connect.cc:1115 #20 0x0045fbd4 in start_thread () from /lib/libpthread.so.0 #21 0x003b74fe in clone () from /lib/libc.so.6 Thread 4 (process 3917): #0 0x0857d40a in unreg_request (keycache=0x9663618, block=0x9681fb4, at_end=1) at mf_keycache.c:1339 #1 0x0857fe1e in key_cache_read (keycache=0x9663618, file=52, filepos=3072, level=3, buff=0xb73bd260 "\202#", length=1024, block_length=1024, return_buffer=0) at mf_keycache.c:2665 #2 0x0852da3c in _mi_fetch_keypage (info=0x97965e0, keyinfo=0x9796430, page=3072, level=3, buff=0xb73bd260 "\202#", return_buffer=0) at mi_page.c:31 #3 0x08542f6d in _mi_ck_real_delete (info=0x97965e0, keyinfo=0x9796430, key=0xb73be050 "\001###### key_length=5, root=0x9796570) at mi_delete.c:168 #4 0x08542dc4 in _mi_ck_delete (info=0x0, keynr=0, key=0xb73be050 "\001###### key_length=5) at mi_delete.c:142 #5 0x0853d7f3 in mi_update (info=0x97965e0, oldrec=0x9795e20 "########0^y\t", newrec=0x9795e18 "################0^y\t") at mi_update.c:124 #6 0x0851d47d in ha_myisam::update_row (this=0x9795d00, old_data=0x9795e20 "########0^y\t", new_data=0x9795e18 "################0^y\t") at ha_myisam.cc:1592 #7 0x08389403 in handler::ha_update_row (this=0x9795d00, old_data=0x9795e20 "########0^y\t", new_data=0x9795e18 "################0^y\t") at handler.cc:4602 #8 0x0830a0bb in mysql_update (thd=0x96fe0e0, table_list=0x97a0240, fields=@0x979fca8, values=@0x979fec0, conds=0x0, order_num=0, order=0x0, limit=18446744073709550335, handle_duplicates=DUP_ERROR, ignore=false) at sql_update.cc:640 #9 0x08264160 in mysql_execute_command (thd=0x96fe0e0) at sql_parse.cc:2981 #10 0x084174d1 in sp_instr_stmt::exec_core (this=0x97a0650, thd=0x96fe0e0, nextp=0xb73bf2dc) at sp_head.cc:2899 #11 0x0841720a in sp_lex_keeper::reset_lex_and_exec_core (this=0x97a0678, thd=0x96fe0e0, nextp=0xb73bf2dc, open_tables=false, instr=0x97a0650) at sp_head.cc:2728 #12 0x0841a287 in sp_instr_stmt::execute (this=0x97a0650, thd=0x96fe0e0, nextp=0xb73bf2dc) at sp_head.cc:2842 #13 0x084137f6 in sp_head::execute (this=0x9748b60, thd=0x96fe0e0) at sp_head.cc:1248 #14 0x084152f6 in sp_head::execute_procedure (this=0x9748b60, thd=0x96fe0e0, args=0x96ff724) at sp_head.cc:1977 #15 0x0826871f in mysql_execute_command (thd=0x96fe0e0) at sql_parse.cc:4252 #16 0x0826af74 in mysql_parse (thd=0x96fe0e0, inBuf=0x973bb30 "CALL p()", length=8, found_semicolon=0xb73c02fc) at sql_parse.cc:5831 #17 0x0826bbb0 in dispatch_command (command=COM_QUERY, thd=0x96fe0e0, packet=0x970d439 "", packet_length=8) at sql_parse.cc:1216 #18 0x0826cdce in do_command (thd=0x96fe0e0) at sql_parse.cc:857 #19 0x08259a05 in handle_one_connection (arg=0x96fe0e0) at sql_connect.cc:1115 #20 0x0045fbd4 in start_thread () from /lib/libpthread.so.0 #21 0x003b74fe in clone () from /lib/libc.so.6 Current language: auto; currently c Thread 3 (process 3918): #0 0x002ce402 in __kernel_vsyscall () #1 0x00461876 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib/libpthread.so.0 #2 0x085a8cd6 in safe_cond_wait (cond=0x9703fc4, mp=0x979633c, file=0x873c4ef "thr_lock.c", line=422) at thr_mutex.c:237 #3 0x085a64c1 in wait_for_lock (wait=0x979637c, data=0x9797dc0, in_wait_list=0 '\0') at thr_lock.c:422 #4 0x085a6ec6 in thr_lock (data=0x9797dc0, owner=0x973a97c, lock_type=TL_READ) at thr_lock.c:704 #5 0x085a78f3 in thr_multi_lock (data=0xb7204498, count=2, owner=0x973a97c) at thr_lock.c:973 #6 0x08247581 in mysql_lock_tables (thd=0x973a160, tables=0xb7206560, count=2, flags=4, need_reopen=0xb738e07f) at lock.cc:279 #7 0x082a9c52 in lock_tables (thd=0x973a160, tables=0x97cb848, count=2, need_reopen=0xb738e07f) at sql_base.cc:5300 #8 0x082b5693 in open_and_lock_tables_derived (thd=0x973a160, tables=0x97cb848, derived=true) at sql_base.cc:4998 #9 0x0826db3c in open_and_lock_tables (thd=0x973a160, tables=0x97cb848) at mysql_priv.h:1536 #10 0x08417014 in sp_instr::exec_open_and_lock_tables (this=0x97ce500, thd=0x973a160, tables=0x97cb848) at sp_head.cc:2789 #11 0x084171e1 in sp_lex_keeper::reset_lex_and_exec_core (this=0x97ce534, thd=0x973a160, nextp=0xb738e2dc, open_tables=true, instr=0x97ce500) at sp_head.cc:2724 #12 0x08417bea in sp_instr_jump_if_not::execute (this=0x97ce500, thd=0x973a160, nextp=0xb738e2dc) at sp_head.cc:3085 #13 0x084137f6 in sp_head::execute (this=0x9765bf0, thd=0x973a160) at sp_head.cc:1248 #14 0x084152f6 in sp_head::execute_procedure (this=0x9765bf0, thd=0x973a160, args=0x973b7a4) at sp_head.cc:1977 #15 0x0826871f in mysql_execute_command (thd=0x973a160) at sql_parse.cc:4252 #16 0x0826af74 in mysql_parse (thd=0x973a160, inBuf=0x9750540 "CALL p()", length=8, found_semicolon=0xb738f2fc) at sql_parse.cc:5831 #17 0x0826bbb0 in dispatch_command (command=COM_QUERY, thd=0x973a160, packet=0x9717481 "", packet_length=8) at sql_parse.cc:1216 #18 0x0826cdce in do_command (thd=0x973a160) at sql_parse.cc:857 #19 0x08259a05 in handle_one_connection (arg=0x973a160) at sql_connect.cc:1115 #20 0x0045fbd4 in start_thread () from /lib/libpthread.so.0 #21 0x003b74fe in clone () from /lib/libc.so.6 Thread 2 (process 3919): #0 0x002ce402 in __kernel_vsyscall () #1 0x00461876 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib/libpthread.so.0 #2 0x085a8cd6 in safe_cond_wait (cond=0x9746d5c, mp=0x979633c, file=0x873c4ef "thr_lock.c", line=422) at thr_mutex.c:237 #3 0x085a64c1 in wait_for_lock (wait=0x979637c, data=0x97fe090, in_wait_list=0 '\0') at thr_lock.c:422 #4 0x085a6ec6 in thr_lock (data=0x97fe090, owner=0x974f38c, lock_type=TL_READ) at thr_lock.c:704 #5 0x085a78f3 in thr_multi_lock (data=0xb72064c8, count=2, owner=0x974f38c) at thr_lock.c:973 #6 0x08247581 in mysql_lock_tables (thd=0x974eb70, tables=0xb72044d8, count=2, flags=4, need_reopen=0xb735d07f) at lock.cc:279 #7 0x082a9c52 in lock_tables (thd=0x974eb70, tables=0x97e7688, count=2, need_reopen=0xb735d07f) at sql_base.cc:5300 #8 0x082b5693 in open_and_lock_tables_derived (thd=0x974eb70, tables=0x97e7688, derived=true) at sql_base.cc:4998 #9 0x0826db3c in open_and_lock_tables (thd=0x974eb70, tables=0x97e7688) at mysql_priv.h:1536 #10 0x08417014 in sp_instr::exec_open_and_lock_tables (this=0x97ec380, thd=0x974eb70, tables=0x97e7688) at sp_head.cc:2789 #11 0x084171e1 in sp_lex_keeper::reset_lex_and_exec_core (this=0x97ec3b4, thd=0x974eb70, nextp=0xb735d2dc, open_tables=true, instr=0x97ec380) at sp_head.cc:2724 #12 0x08417bea in sp_instr_jump_if_not::execute (this=0x97ec380, thd=0x974eb70, nextp=0xb735d2dc) at sp_head.cc:3085 #13 0x084137f6 in sp_head::execute (this=0x9761bc8, thd=0x974eb70) at sp_head.cc:1248 #14 0x084152f6 in sp_head::execute_procedure (this=0x9761bc8, thd=0x974eb70, args=0x97501b4) at sp_head.cc:1977 #15 0x0826871f in mysql_execute_command (thd=0x974eb70) at sql_parse.cc:4252 #16 0x0826af74 in mysql_parse (thd=0x974eb70, inBuf=0x976dbf8 "CALL p()", length=8, found_semicolon=0xb735e2fc) at sql_parse.cc:5831 #17 0x0826bbb0 in dispatch_command (command=COM_QUERY, thd=0x974eb70, packet=0x9759c21 "", packet_length=8) at sql_parse.cc:1216 #18 0x0826cdce in do_command (thd=0x974eb70) at sql_parse.cc:857 #19 0x08259a05 in handle_one_connection (arg=0x974eb70) at sql_connect.cc:1115 #20 0x0045fbd4 in start_thread () from /lib/libpthread.so.0 #21 0x003b74fe in clone () from /lib/libc.so.6 Thread 1 (process 3870): #0 0x002ce402 in __kernel_vsyscall () #1 0x0046264f in pthread_kill () from /lib/libpthread.so.0 #2 0x085a46c5 in my_write_core (sig=6) at stacktrace.c:310 #3 0x0824c685 in handle_segfault (sig=6) at mysqld.cc:2513 #4 #5 0x002ce402 in __kernel_vsyscall () #6 0x003afeb1 in ___newselect_nocancel () from /lib/libc.so.6 #7 0x0824e9bf in handle_connections_sockets (arg=0x0) at mysqld.cc:4948 #8 0x08254852 in main (argc=7, argv=0xbf9d5af4) at mysqld.cc:4447 Retrying test, attempt(2/3)... mysql-test-run: *** ERROR: Got ^C signal =====mysql-5.1===== =====bug43531=====