Bug #15623 Test case rpl000001 and rpl_error_ignored_table failure on MacOSX
Submitted: 9 Dec 2005 9:56 Modified: 6 Jan 2006 5:18
Reporter: Kristian Nielsen Email Updates:
Status: Closed Impact on me:
None 
Category:MySQL Server: Replication Severity:S2 (Serious)
Version:clone-5.0.17-release OS:Mac OS X (MacOSX)
Assigned to: Ramil Kalimullin CPU Architecture:Any

[9 Dec 2005 9:56] Kristian Nielsen
Description:
The test case rpl000001 failed on most/all MacOSX platforms during a test build of the 5.0.17 release:

09746: rpl000001                      [ fail ]
09747: 
09748: Errors are (from /Users/mysqldev/xserve-64bit/test/mysql-max-5.0.17-osx10.4-powerpc-64bit/mysql-test/var/log/mysqltest-time) :
09749: mysqltest: At line 96: query 'reap' failed with wrong errno 2013 instead of 1053...
09750: (the last lines may be the most important ones)

There is another bug (BUG#12455) on rpl000001 test failures, but it looks like this one is different; it has another failure message, and seems to happen only for MacOSX.

How to repeat:
Run the test suite on MacOSX
[9 Dec 2005 10:06] Kristian Nielsen
The test case rpl_error_ignored_table fails on the exact same set of platforms, could be related:

11798: rpl_error_ignored_table        [ fail ]
11799: 
11800: Errors are (from /Users/mysqldev/powermacg5/test/mysql-max-5.0.17-osx10.3-powerpc/mysql-test/var/log/mysqltest-time) :
11801: mysqltest: At line 49: query 'reap' failed with wrong errno 2013 instead of 0...
11802: (the last lines may be the most important ones)
[13 Dec 2005 19:19] Magnus Blåudd
Debug compiled on powermacg5, ran test rpl000001.
Below is the mysqltest.trace from when the "reap" command is executed.

| | | | | | | | | | | | | | | | | | | | | >run_query_normal
| | | | | | | | | | | | | | | | | | | | | | enter: flags: 2
| | | | | | | | | | | | | | | | | | | | | | enter: query: 'reap'
| | | | | | | | | | | | | | | | | | | | | | >cli_read_query_result
| | | | | | | | | | | | | | | | | | | | | | | >vio_is_blocking
| | | | | | | | | | | | | | | | | | | | | | | | exit: 1
| | | | | | | | | | | | | | | | | | | | | | | <vio_is_blocking
| | | | | | | | | | | | | | | | | | | | | | | >vio_read_buff
| | | | | | | | | | | | | | | | | | | | | | | | enter: sd: 7, buf: 0x0x180d600, size: 4
| | | | | | | | | | | | | | | | | | | | | | | | >vio_read
| | | | | | | | | | | | | | | | | | | | | | | | | enter: sd: 7, buf: 0x0xb6000, size: 16384
| | | | | | | | | | | | | | | | | | | | | | | | | exit: 0
| | | | | | | | | | | | | | | | | | | | | | | | <vio_read
| | | | | | | | | | | | | | | | | | | | | | | <vio_read_buff
| | | | | | | | | | | | | | | | | | | | | | | info: vio_read returned 0,  errno: 0
| | | | | | | | | | | | | | | | | | | | | | | >vio_blocking
| | | | | | | | | | | | | | | | | | | | | | | | enter: set_blocking_mode: 1  old_mode: 1
| | | | | | | | | | | | | | | | | | | | | | | | exit: 0
| | | | | | | | | | | | | | | | | | | | | | | <vio_blocking
| | | | | | | | | | | | | | | | | | | | | | | >vio_read_buff
| | | | | | | | | | | | | | | | | | | | | | | | enter: sd: 7, buf: 0x0x180d600, size: 4
| | | | | | | | | | | | | | | | | | | | | | | | >vio_read
| | | | | | | | | | | | | | | | | | | | | | | | | enter: sd: 7, buf: 0x0xb6000, size: 16384
| | | | | | | | | | | | | | | | | | | | | | | | | exit: 0
| | | | | | | | | | | | | | | | | | | | | | | | <vio_read
| | | | | | | | | | | | | | | | | | | | | | | <vio_read_buff
| | | | | | | | | | | | | | | | | | | | | | | info: vio_read returned 0,  errno: 0
| | | | | | | | | | | | | | | | | | | | | | | error: Couldn't read packet: remain: 4  errno: 0  length: 0
| | | | | | | | | | | | | | | | | | | | | | | >vio_blocking
| | | | | | | | | | | | | | | | | | | | | | | | enter: set_blocking_mode: 1  old_mode: 1
| | | | | | | | | | | | | | | | | | | | | | | | exit: 0
| | | | | | | | | | | | | | | | | | | | | | | <vio_blocking
| | | | | | | | | | | | | | | | | | | | | | | error: Wrong connection or packet. fd: TCP/IP (7)  len: -1
| | | | | | | | | | | | | | | | | | | | | | | >end_server
| | | | | | | | | | | | | | | | | | | | | | | | info: Net: TCP/IP (7)
| | | | | | | | | | | | | | | | | | | | | | | | >vio_close
| | | | | | | | | | | | | | | | | | | | | | | | <vio_close
| | | | | | | | | | | | | | | | | | | | | | | | >my_free
| | | | | | | | | | | | | | | | | | | | | | | | | my: ptr: 0xb6000
| | | | | | | | | | | | | | | | | | | | | | | | <my_free
| | | | | | | | | | | | | | | | | | | | | | | | >my_free
| | | | | | | | | | | | | | | | | | | | | | | | | my: ptr: 0x400910
| | | | | | | | | | | | | | | | | | | | | | | | <my_free
| | | | | | | | | | | | | | | | | | | | | | | | >net_end
| | | | | | | | | | | | | | | | | | | | | | | | | >my_free
| | | | | | | | | | | | | | | | | | | | | | | | | | my: ptr: 0x180d600
| | | | | | | | | | | | | | | | | | | | | | | | | <my_free
| | | | | | | | | | | | | | | | | | | | | | | | <net_end
| | | | | | | | | | | | | | | | | | | | | | | | >free_old_query
| | | | | | | | | | | | | | | | | | | | | | | | | >init_alloc_root
| | | | | | | | | | | | | | | | | | | | | | | | | | enter: root: 0x5ef84
| | | | | | | | | | | | | | | | | | | | | | | | | <init_alloc_root
| | | | | | | | | | | | | | | | | | | | | | | | <free_old_query
| | | | | | | | | | | | | | | | | | | | | | | >set_mysql_error
| | | | | | | | | | | | | | | | | | | | | | | | enter: error :2013 'Lost connection to MySQL server during query'
| | | | | | | | | | | | | | | | | | | | | | | <set_mysql_error
| | | | | | | | | | | | | | | | | | | | | | <cli_read_query_result
| | | | | | | | | | | | | | | | | | | | | | >handle_error
| | | | | | | | | | | | | | | | | | | | | | | info: i: 1  expected_errors: 1
| | | | | | | | | | | | | | | | | | | | | | | >die
| | | | | | | | | | | | | | | | | | | | | | | | >free_used_memory
| | | | | | | | | | | | | | | | | | | | | | | | | >close_cons
| | | | | | | | | | | | | | | | | | | | | | | | | | >mysql_close
| | | | | | | | | | | | | | | | | | | | | | | | | | | >free_old_query
| | | | | | | | | | | | | | | | | | | | | | | | | | | | >init_alloc_root
| | | | | | | | | | | | | | | | | | | | | | | | | | | | | enter: root: 0x5fab8
| | | | | | | | | | | | | | | | | | | | | | | | | | | | <init_alloc_root
| | | | | | | | | | | | | | | | | | | | | | | | | | | <free_old_query
[13 Dec 2005 19:27] Magnus Blåudd
And this is the part where "thread 4" has been killed by "thread 5".

Thread 4 writes " err: Server shutdown in progress" but gets error 9 in net_flush/net_write

T@4    : | | | <alloc_root
T@4    : | | <push_warning
T@4    : | | >query_cache_abort
T@5    : | | | | | packet_header: Memory: 0xf044a230  Bytes: (4)
07 00 00 01
T@4    : | | <query_cache_abort
T@5    : | | | | | >net_flush
T@4    : | <my_message_sql
T@4    : | >net_send_error
T@4    : | | enter: sql_errno: 0  err: Server shutdown in progress
T@4    : | | >net_write_command
T@4    : | | | enter: length: 35
T@4    : | | <net_write_command
T@4    : | | >net_flush
T@4    : | | | >vio_is_blocking
T@4    : | | | | exit: 0
T@4    : | | | <vio_is_blocking
T@4    : | | | >net_real_write
T@4    : | | | | >vio_write
T@4    : | | | | | enter: sd: -1, buf: 0x0x32af000, size: 40
T@4    : | | | | | vio_error: Got error on write: 9
T@4    : | | | | | exit: -1
T@4    : | | | | <vio_write
T@4    : | | | <net_real_write
T@4    : | | <net_flush
T@4    : | <net_send_error
T@4    : | >free_root
T@4    : | | enter: root: 0x385fc20  flags: 1
T@4    : | <free_root
T@4    : <dispatch_command
T@4    : >close_connection
T@4    : | enter: fd: TCP/IP (29)  error: ''
T@4    : | >vio_close
T@4    : | <vio_close
T@4    : <close_connection
T@4    : >end_thread
T@4    : | >THD::cleanup
T@4    : | | >ha_rollback_trans
T@4    : | | | >push_warning
T@
[14 Dec 2005 11:32] Magnus Blåudd
This must be related to http://bugs.mysql.com/bug.php?id=9712
[14 Dec 2005 17:46] Ramil Kalimullin
http://lists.mysql.com/internals/33246
[14 Dec 2005 18:31] Ramil Kalimullin
fixed in 5.0.17
[6 Jan 2006 5:18] Paul Dubois
Note in 5.0.17 changelog.
[25 May 2006 17:21] Paul Dubois
See also Bug#16900.