rpl.rpl_incident 'row' [ fail ] ==628== Memcheck, a memory error detector. ==628== Copyright (C) 2002-2007, and GNU GPL'd, by Julian Seward et al. ==628== Using LibVEX rev 1732, a library for dynamic binary translation. ==628== Copyright (C) 2004-2007, and GNU GPL'd, by OpenWorks LLP. ==628== Using valgrind-3.2.3, a dynamic binary instrumentation framework. ==628== Copyright (C) 2000-2007, and GNU GPL'd, by Julian Seward et al. ==628== For more details, rerun with: -v ==628== ERROR: Attempting to dump binlog '/dev/shm/var-ps_stm-121/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-bugteam/357/mysql-5.1.31-pb357/mysql-test/suite/rpl/r/rpl_incident.result 2009-01-08 05:37:53.000000000 +0300 +++ /data0/pushbuild/pb3/pb/bzr_mysql-5.1-bugteam/357/mysql-5.1.31-pb357/mysql-test/suite/rpl/r/rpl_incident.reject 2009-01-08 10:43:19.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.31-pb357-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 +#090108 10:43:17 server id 1 end_log_pos 106 +# Position Timestamp Type Master ID Size Master Pos Flags +# 4 95 ae 65 49 0f 01 00 00 00 66 00 00 00 6a 00 00 00 00 00 +# 17 04 00 35 2e 31 2e 33 31 2d 70 62 33 35 37 2d 64 |..5.1.31.pb357.d| +# 27 65 62 75 67 2d 6c 6f 67 00 00 00 00 00 00 00 00 |ebug.log........| +# 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................| +# 47 00 00 00 00 95 ae 65 49 13 38 0d 00 08 00 12 00 |......eI.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.31-pb357-debug-log created 090108 10:43:17 at startup +ROLLBACK/*!*/; +BINLOG ' +la5lSQ8BAAAAZgAAAGoAAAAAAAQANS4xLjMxLXBiMzU3LWRlYnVnLWxvZwAAAAAAAAAAAAAAAAAA +AAAAAAAAAAAAAAAAAACVrmVJEzgNAAgAEgAEBAQEEgAAUwAEGggAAAAICAgC +'/*!*/; +# at 106 +#090108 10:43:17 server id 1 end_log_pos 192 +# Position Timestamp Type Master ID Size Master Pos Flags +# 6a 95 ae 65 49 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=1231400597/*!*/; +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 +#090108 10:43:17 server id 1 end_log_pos 260 +# Position Timestamp Type Master ID Size Master Pos Flags +# c0 95 ae 65 49 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=1231400597/*!*/; +BEGIN +/*!*/; +# at 260 +# at 301 +#090108 10:43:17 server id 1 end_log_pos 301 +# Position Timestamp Type Master ID Size Master Pos Flags +# 104 95 ae 65 49 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 +#090108 10:43:17 server id 1 end_log_pos 345 +# Position Timestamp Type Master ID Size Master Pos Flags +# 12d 95 ae 65 49 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 ' +la5lSRMBAAAAKQAAAC0BAAAAABAAAAAAAAAABHRlc3QAAnQxAAEDAAE= +la5lSRcBAAAALAAAAFkBAAAQABAAAAAAAAEAAf/+AQAAAP4CAAAA/gMAAAA= +'/*!*/; +# at 345 +#090108 10:43:17 server id 1 end_log_pos 414 +# Position Timestamp Type Master ID Size Master Pos Flags +# 159 95 ae 65 49 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=1231400597/*!*/; +COMMIT +/*!*/; +# at 414 +#090108 10:43:18 server id 1 end_log_pos 436 +# Position Timestamp Type Master ID Size Master Pos Flags +# 19e 96 ae 65 49 1a 01 00 00 00 16 00 00 00 b4 01 00 00 00 00 +# 1b1 01 00 00 |...| +# +# Incident: LOST_EVENTS# at 436 +#090108 10:43:18 server id 1 end_log_pos 480 +# Position Timestamp Type Master ID Size Master Pos Flags +# 1b4 96 ae 65 49 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 +#090108 10:43:13 server id 2 end_log_pos 106 +# Position Timestamp Type Master ID Size Master Pos Flags +# 4 91 ae 65 49 0f 02 00 00 00 66 00 00 00 6a 00 00 00 01 00 +# 17 04 00 35 2e 31 2e 33 31 2d 70 62 33 35 37 2d 64 |..5.1.31.pb357.d| +# 27 65 62 75 67 2d 6c 6f 67 00 00 00 00 00 00 00 00 |ebug.log........| +# 37 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................| +# 47 00 00 00 00 91 ae 65 49 13 38 0d 00 08 00 12 00 |......eI.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.31-pb357-debug-log created 090108 10:43:13 at startup +# Warning: this binlog was not closed properly. Most probably mysqld crashed writing it. +ROLLBACK/*!*/; +BINLOG ' +ka5lSQ8CAAAAZgAAAGoAAAABAAQANS4xLjMxLXBiMzU3LWRlYnVnLWxvZwAAAAAAAAAAAAAAAAAA +AAAAAAAAAAAAAAAAAACRrmVJEzgNAAgAEgAEBAQEEgAAUwAEGggAAAAICAgC +'/*!*/; +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 11210 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.31-pb357-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 ==628== ==628== ERROR SUMMARY: 0 errors from 0 contexts (suppressed: 3 from 1) ==628== malloc/free: in use at exit: 1,744 bytes in 6 blocks. ==628== malloc/free: 2,850 allocs, 2,844 frees, 838,445 bytes allocated. ==628== For counts of detected errors, rerun with: -v ==628== searching for pointers to 6 not-freed blocks. ==628== checked 9,712,840 bytes. ==628== ==628== LEAK SUMMARY: ==628== definitely lost: 0 bytes in 0 blocks. ==628== possibly lost: 0 bytes in 0 blocks. ==628== still reachable: 0 bytes in 0 blocks. ==628== suppressed: 1,744 bytes in 6 blocks. Stopping All Servers