Bug #45411 RPL tests fail to retrieve binlog position on Windows
Submitted: 9 Jun 20:46 Modified: 6 Oct 20:39
Reporter: Alexander Nozdrin
Status: Verified
Category:Server: Replication Severity:S3 (Non-critical)
Version:5.4 OS:Microsoft Windows
Assigned to: Libing Song Target Version:
Tags: tf54, test failure, pb2
Triage: Triaged: D1 (Critical)

[9 Jun 20:46] Alexander Nozdrin
Description:
The following tests fail in PB2:
  - rpl.rpl_init_slave
  - rpl.rpl_extraCol_innodb

Symptoms:
CURRENT_TEST: rpl.rpl_extraCol_innodb
mysqltest: In included file ".\extra\rpl_tests\rpl_extraSlave_Col.test": At line 752:
failed in 'select master_pos_wait('master-bin.000001', 531, 300)': 2013: Lost connection
to MySQL server during query

090605 17:54:24 [Note] Slave: received end packet from server, apparent master shutdown:

090605 17:54:24 [Note] Slave I/O thread: Failed reading log event, reconnecting to retry,
log 'master-bin.000001' at postion 6233

How to repeat:
http://pb2.norway.sun.com/web.py?template=mysql_show_test_failure&test_failure_id=2196302

http://pb2.norway.sun.com/web.py?template=mysql_show_test_failure&test_failure_id=2196306
[6 Oct 20:36] Sveta Smirnova
Backtrace:

ntdll!RtlpLowFragHeapFree
ntdll!RtlFreeHeap
kernel32!HeapFree
mysqld!free [f:\dd\vctools\crt_bld\self_x86\crt\src\free.c @ 110]
mysqld!mysql_create_frm
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\unireg.cc @ 419]
mysqld!rea_create_table
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\unireg.cc @ 505]
mysqld!mysql_create_table_no_lock
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_table.cc @
3712]
mysqld!create_temporary_table
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_table.cc @
5903]
mysqld!create_altered_table
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_table.cc @
5955]
mysqld!mysql_alter_table
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_table.cc @
7129]
mysqld!mysql_execute_command
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_parse.cc @
2930]
mysqld!mysql_parse
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\sql_parse.cc @
5999]
mysqld!Query_log_event::do_apply_event
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\log_event.cc @
3135]
mysqld!Query_log_event::do_apply_event
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\log_event.cc @
2936]
mysqld!apply_event_and_update_pos
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\slave.cc @ 2191]
mysqld!exec_relay_log_event
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\slave.cc @ 2318]
mysqld!handle_slave_sql
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\sql\slave.cc @ 3049]
mysqld!pthread_start
[g:\pb2\build\sb_0-566648-1244209369.17\mysql-5.4.4-alpha-win-x86\mysys\my_winthread.c @
61]
mysqld!_callthreadstartex [f:\dd\vctools\crt_bld\self_x86\crt\src\threadex.c @ 348]
mysqld!_threadstartex [f:\dd\vctools\crt_bld\self_x86\crt\src\threadex.c @ 326]
kernel32!BaseThreadInitThunk
ntdll!__RtlUserThreadStart
ntdll!_RtlUserThreadStart

Test output:

rpl.rpl_extraCol_innodb                  [ fail ]
        Test ended at 2009-06-05 16:45:52

CURRENT_TEST: rpl.rpl_extraCol_innodb
mysqltest: In included file ".\extra\rpl_tests\rpl_extraSlave_Col.test": At line 752:
failed in 'select master_pos_wait('master-bin.000001', 531, 300)': 2013: Lost connection
to MySQL server during query

The result from queries just before the failure was:
< snip >
6	b1b1b1b1b1b1b1b1
*** Select on Slave ****
SELECT * FROM t14a ORDER BY c1;
c1	c4	c5	c6	c7
1	b1b1b1b1b1b1b1b1	Kyle	1	CURRENT_TIMESTAMP
2	b1b1b1b1b1b1b1b1	JOE	1	CURRENT_TIMESTAMP
3	b1b1b1b1b1b1b1b1	QA	1	CURRENT_TIMESTAMP
4	b1b1b1b1b1b1b1b1	NULL	1	CURRENT_TIMESTAMP
5	b1b1b1b1b1b1b1b1	NULL	1	CURRENT_TIMESTAMP
6	b1b1b1b1b1b1b1b1	NULL	1	CURRENT_TIMESTAMP
*** connect to master and drop columns ***
ALTER TABLE t14 DROP COLUMN c2;
ALTER TABLE t14 DROP COLUMN c4;
*** Select from Master ***
SELECT * FROM t14 ORDER BY c1;
c1	c3	c5
1	Replication Testing Extra Col	Kyle
2	This Test Should work	JOE
3	If is does not, I will open a bug	QA
*** Select from Slave ***
[6 Oct 20:39] Sveta Smirnova
Thank you for the report.

Verified as described. Stack trace looks similar to bug #45449