rpl.rpl_incident 'row' [ fail ] ==8144== Memcheck, a memory error detector. ==8144== Copyright (C) 2002-2007, and GNU GPL'd, by Julian Seward et al. ==8144== Using LibVEX rev 1732, a library for dynamic binary translation. ==8144== Copyright (C) 2004-2007, and GNU GPL'd, by OpenWorks LLP. ==8144== Using valgrind-3.2.3, a dynamic binary instrumentation framework. ==8144== Copyright (C) 2000-2007, and GNU GPL'd, by Julian Seward et al. ==8144== For more details, rerun with: -v ==8144== ERROR: Attempting to dump binlog '/dev/shm/var-rpl_binlog_row-120/log/slave-bin.000001', which was not closed properly. Most probably, mysqld is still writing it, or it crashed. Rerun with --force-if-open to ignore this problem. --- /data0/pushbuild/pb3/pb/bzr_mysql-5.1-5.1.29-rc/44/mysql-5.1.29-pb44/mysql-test/suite/rpl/r/rpl_incident.result 2008-10-09 19:13:03.000000000 +0300 +++ /data0/pushbuild/pb3/pb/bzr_mysql-5.1-5.1.29-rc/44/mysql-5.1.29-pb44/mysql-test/suite/rpl/r/rpl_incident.reject 2008-10-10 03:57:20.000000000 +0300 @@ -20,82 +20,168 @@ 3 4 **** On Slave **** -SELECT * FROM t1; -a -1 -2 -3 +**** TEST CASE BUG! PRINTING DEBUG INFO! **** +**** Dear developer, if you see this in the output of a test +**** case run, please add all the information below as a +**** comment to BUG#38077. If it's a pushbuild failure, please +**** include a link to the push page. +**** Thank you! /Sven +SHOW BINLOG EVENTS; +Log_name Pos Event_type Server_id End_log_pos Info +slave-bin.000001 4 Format_desc 2 106 Server ver: 5.1.29-pb44-debug-log, Binlog ver: 4 +slave-bin.000001 106 Query 2 216 use `test`; drop table if exists t1,t2,t3,t4,t5,t6,t7,t8,t9 +**** master binlog **** +/*!40019 SET @@session.max_insert_delayed_threads=0*/; +/*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/; +DELIMITER /*!*/; +# at 4 +#081010 3:57:18 server id 1 end_log_pos 106 +# Position Timestamp Type Master ID Size Master Pos Flags +# 4 6e a8 ee 48 0f 01 00 00 00 66 00 00 00 6a 00 00 00 00 00 +# 17 04 00 35 2e 31 2e 32 39 2d 70 62 34 34 2d 64 65 |..5.1.29.pb44.de| +# 27 62 75 67 2d 6c 6f 67 00 00 00 00 00 00 00 00 00 |bug.log.........| +# 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................| +# 47 00 00 00 00 6e a8 ee 48 13 38 0d 00 08 00 12 00 |....n..H.8......| +# 57 04 04 04 04 12 00 00 53 00 04 1a 08 00 00 00 08 |.......S........| +# 67 08 08 02 |...| +# Start: binlog v 4, server v 5.1.29-pb44-debug-log created 081010 3:57:18 at startup +ROLLBACK/*!*/; +BINLOG ' +bqjuSA8BAAAAZgAAAGoAAAAAAAQANS4xLjI5LXBiNDQtZGVidWctbG9nAAAAAAAAAAAAAAAAAAAA +AAAAAAAAAAAAAAAAAABuqO5IEzgNAAgAEgAEBAQEEgAAUwAEGggAAAAICAgC +'/*!*/; +# at 106 +#081010 3:57:19 server id 1 end_log_pos 192 +# Position Timestamp Type Master ID Size Master Pos Flags +# 6a 6f a8 ee 48 02 01 00 00 00 56 00 00 00 c0 00 00 00 10 00 +# 7d 02 00 00 00 00 00 00 00 04 00 00 1a 00 00 00 40 |................| +# 8d 00 00 01 00 00 00 00 00 00 00 00 06 03 73 74 64 |.............std| +# 9d 04 08 00 08 00 08 00 74 65 73 74 00 43 52 45 41 |.......test.CREA| +# ad 54 45 20 54 41 42 4c 45 20 74 31 20 28 61 20 49 |TE.TABLE.t1..a.I| +# bd 4e 54 29 |NT.| +# Query thread_id=2 exec_time=0 error_code=0 +use test/*!*/; +SET TIMESTAMP=1223600239/*!*/; +SET @@session.pseudo_thread_id=2/*!*/; +SET @@session.foreign_key_checks=1, @@session.sql_auto_is_null=1, @@session.unique_checks=1/*!*/; +SET @@session.sql_mode=0/*!*/; +SET @@session.auto_increment_increment=1, @@session.auto_increment_offset=1/*!*/; +/*!\C latin1 *//*!*/; +SET @@session.character_set_client=8,@@session.collation_connection=8,@@session.collation_server=8/*!*/; +SET @@session.lc_time_names=0/*!*/; +SET @@session.collation_database=DEFAULT/*!*/; +CREATE TABLE t1 (a INT) +/*!*/; +# at 192 +#081010 3:57:19 server id 1 end_log_pos 260 +# Position Timestamp Type Master ID Size Master Pos Flags +# c0 6f a8 ee 48 02 01 00 00 00 44 00 00 00 04 01 00 00 00 00 +# d3 02 00 00 00 00 00 00 00 04 00 00 1a 00 00 00 40 |................| +# e3 00 00 01 00 00 00 00 00 00 00 00 06 03 73 74 64 |.............std| +# f3 04 08 00 08 00 08 00 74 65 73 74 00 42 45 47 49 |.......test.BEGI| +# 103 4e |N| +# Query thread_id=2 exec_time=0 error_code=0 +SET TIMESTAMP=1223600239/*!*/; +BEGIN +/*!*/; +# at 260 +# at 301 +#081010 3:57:19 server id 1 end_log_pos 301 +# Position Timestamp Type Master ID Size Master Pos Flags +# 104 6f a8 ee 48 13 01 00 00 00 29 00 00 00 2d 01 00 00 00 00 +# 117 10 00 00 00 00 00 00 00 04 74 65 73 74 00 02 74 |.........test..t| +# 127 31 00 01 03 00 01 |1.....| +# Table_map: `test`.`t1` mapped to number 16 +#081010 3:57:19 server id 1 end_log_pos 345 +# Position Timestamp Type Master ID Size Master Pos Flags +# 12d 6f a8 ee 48 17 01 00 00 00 2c 00 00 00 59 01 00 00 10 00 +# 140 10 00 00 00 00 00 01 00 01 ff fe 01 00 00 00 fe |................| +# 150 02 00 00 00 fe 03 00 00 00 |.........| +# Write_rows: table id 16 flags: STMT_END_F + +BINLOG ' +b6juSBMBAAAAKQAAAC0BAAAAABAAAAAAAAAABHRlc3QAAnQxAAEDAAE= +b6juSBcBAAAALAAAAFkBAAAQABAAAAAAAAEAAf/+AQAAAP4CAAAA/gMAAAA= +'/*!*/; +# at 345 +#081010 3:57:19 server id 1 end_log_pos 414 +# Position Timestamp Type Master ID Size Master Pos Flags +# 159 6f a8 ee 48 02 01 00 00 00 45 00 00 00 9e 01 00 00 00 00 +# 16c 02 00 00 00 00 00 00 00 04 00 00 1a 00 00 00 40 |................| +# 17c 00 00 01 00 00 00 00 00 00 00 00 06 03 73 74 64 |.............std| +# 18c 04 08 00 08 00 08 00 74 65 73 74 00 43 4f 4d 4d |.......test.COMM| +# 19c 49 54 |IT| +# Query thread_id=2 exec_time=0 error_code=0 +SET TIMESTAMP=1223600239/*!*/; +COMMIT +/*!*/; +# at 414 +#081010 3:57:19 server id 1 end_log_pos 436 +# Position Timestamp Type Master ID Size Master Pos Flags +# 19e 6f a8 ee 48 1a 01 00 00 00 16 00 00 00 b4 01 00 00 00 00 +# 1b1 01 00 00 |...| +# +# Incident: LOST_EVENTS# at 436 +#081010 3:57:19 server id 1 end_log_pos 480 +# Position Timestamp Type Master ID Size Master Pos Flags +# 1b4 6f a8 ee 48 04 01 00 00 00 2c 00 00 00 e0 01 00 00 00 00 +# 1c7 04 00 00 00 00 00 00 00 6d 61 73 74 65 72 2d 62 |........master.b| +# 1d7 69 6e 2e 30 30 30 30 30 32 |in.000002| +# Rotate to master-bin.000002 pos: 4 +DELIMITER ; +# End of log file +ROLLBACK /* added by mysqlbinlog */; +/*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/; +**** slave binlog **** +/*!40019 SET @@session.max_insert_delayed_threads=0*/; +/*!50003 SET @OLD_COMPLETION_TYPE=@@COMPLETION_TYPE,COMPLETION_TYPE=0*/; +DELIMITER /*!*/; +# at 4 +#081010 3:57:15 server id 2 end_log_pos 106 +# Position Timestamp Type Master ID Size Master Pos Flags +# 4 6b a8 ee 48 0f 02 00 00 00 66 00 00 00 6a 00 00 00 01 00 +# 17 04 00 35 2e 31 2e 32 39 2d 70 62 34 34 2d 64 65 |..5.1.29.pb44.de| +# 27 62 75 67 2d 6c 6f 67 00 00 00 00 00 00 00 00 00 |bug.log.........| +# 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................| +# 47 00 00 00 00 6b a8 ee 48 13 38 0d 00 08 00 12 00 |....k..H.8......| +# 57 04 04 04 04 12 00 00 53 00 04 1a 08 00 00 00 08 |.......S........| +# 67 08 08 02 |...| +# Start: binlog v 4, server v 5.1.29-pb44-debug-log created 081010 3:57:15 at startup +# Warning: this binlog was not closed properly. Most probably mysqld crashed writing it. +ROLLBACK/*!*/; +BINLOG ' +a6juSA8CAAAAZgAAAGoAAAABAAQANS4xLjI5LXBiNDQtZGVidWctbG9nAAAAAAAAAAAAAAAAAAAA +AAAAAAAAAAAAAAAAAABrqO5IEzgNAAgAEgAEBAQEEgAAUwAEGggAAAAICAgC +'/*!*/; +DELIMITER ; +# End of log file +ROLLBACK /* added by mysqlbinlog */; +/*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/; +**** slave status **** SHOW SLAVE STATUS; -Slave_IO_State # +Slave_IO_State Waiting for master to send event Master_Host 127.0.0.1 Master_User root -Master_Port MASTER_PORT +Master_Port 11200 Connect_Retry 1 -Master_Log_File # -Read_Master_Log_Pos # -Relay_Log_File # -Relay_Log_Pos # -Relay_Master_Log_File master-bin.000001 -Slave_IO_Running Yes -Slave_SQL_Running No -Replicate_Do_DB -Replicate_Ignore_DB -Replicate_Do_Table -Replicate_Ignore_Table -Replicate_Wild_Do_Table -Replicate_Wild_Ignore_Table -Last_Errno 1590 -Last_Error The incident LOST_EVENTS occured on the master. Message: -Skip_Counter 0 -Exec_Master_Log_Pos # -Relay_Log_Space # -Until_Condition None -Until_Log_File -Until_Log_Pos 0 -Master_SSL_Allowed No -Master_SSL_CA_File -Master_SSL_CA_Path -Master_SSL_Cert -Master_SSL_Cipher -Master_SSL_Key -Seconds_Behind_Master # -Master_SSL_Verify_Server_Cert No -Last_IO_Errno 0 -Last_IO_Error -Last_SQL_Errno 1590 -Last_SQL_Error The incident LOST_EVENTS occured on the master. Message: -SET GLOBAL SQL_SLAVE_SKIP_COUNTER=1; -START SLAVE; -SELECT * FROM t1; -a -1 -2 -3 -4 -SHOW SLAVE STATUS; -Slave_IO_State # -Master_Host 127.0.0.1 -Master_User root -Master_Port MASTER_PORT -Connect_Retry 1 -Master_Log_File # -Read_Master_Log_Pos # -Relay_Log_File # -Relay_Log_Pos # +Master_Log_File master-bin.000002 +Read_Master_Log_Pos 318 +Relay_Log_File slave-relay-bin.000004 +Relay_Log_Pos 252 Relay_Master_Log_File master-bin.000002 Slave_IO_Running Yes -Slave_SQL_Running Yes +Slave_SQL_Running No Replicate_Do_DB Replicate_Ignore_DB Replicate_Do_Table Replicate_Ignore_Table Replicate_Wild_Do_Table Replicate_Wild_Ignore_Table -Last_Errno 0 -Last_Error +Last_Errno 1146 +Last_Error Error 'Table 'test.t1' doesn't exist' on opening tables Skip_Counter 0 -Exec_Master_Log_Pos # -Relay_Log_Space # +Exec_Master_Log_Pos 106 +Relay_Log_Space 765 Until_Condition None Until_Log_File Until_Log_Pos 0 @@ -105,10 +191,30 @@ Master_SSL_Cert Master_SSL_Cipher Master_SSL_Key -Seconds_Behind_Master # +Seconds_Behind_Master NULL Master_SSL_Verify_Server_Cert No Last_IO_Errno 0 Last_IO_Error -Last_SQL_Errno 0 -Last_SQL_Error -DROP TABLE t1; +Last_SQL_Errno 1146 +Last_SQL_Error Error 'Table 'test.t1' doesn't exist' on opening tables +**** slave's master status **** +SHOW MASTER STATUS; +File Position Binlog_Do_DB Binlog_Ignore_DB +slave-bin.000001 216 +**** slave binlog events **** +[on master] +**** master status **** +SHOW MASTER STATUS; +File Position Binlog_Do_DB Binlog_Ignore_DB +master-bin.000002 318 +**** master binlog events **** +SHOW BINLOG EVENTS; +Log_name Pos Event_type Server_id End_log_pos Info +master-bin.000001 4 Format_desc 1 106 Server ver: 5.1.29-pb44-debug-log, Binlog ver: 4 +master-bin.000001 106 Query 1 192 use `test`; CREATE TABLE t1 (a INT) +master-bin.000001 192 Query 1 260 use `test`; BEGIN +master-bin.000001 260 Table_map 1 301 table_id: 16 (test.t1) +master-bin.000001 301 Write_rows 1 345 table_id: 16 flags: STMT_END_F +master-bin.000001 345 Query 1 414 use `test`; COMMIT +master-bin.000001 414 Incident 1 436 #1 (LOST_EVENTS): +master-bin.000001 436 Rotate 1 480 master-bin.000002;pos=4 mysqltest: Result content mismatch ==8144== ==8144== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 3 from 1) ==8144== malloc/free: in use at exit: 1,744 bytes in 6 blocks. ==8144== malloc/free: 3,116 allocs, 3,110 frees, 837,971 bytes allocated. ==8144== For counts of detected errors, rerun with: -v ==8144== searching for pointers to 6 not-freed blocks. ==8144== checked 9,712,920 bytes. ==8144== ==8144== LEAK SUMMARY: ==8144== definitely lost: 0 bytes in 0 blocks. ==8144== possibly lost: 0 bytes in 0 blocks. ==8144== still reachable: 0 bytes in 0 blocks. ==8144== suppressed: 1,744 bytes in 6 blocks. Stopping All Servers Restoring snapshot of databases Resuming Tests