// 5.6.25 [umshastr@hod03]/export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test: ./mtr rpl_bug77817 Logging: ./mtr rpl_bug77817 2015-07-24 08:01:52 0 [Note] /export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/bin/mysqld (mysqld 5.6.25) starting as process 32282 ... 2015-07-24 08:01:52 32282 [Note] Plugin 'FEDERATED' is disabled. 2015-07-24 08:01:52 32282 [Note] Binlog end 2015-07-24 08:01:52 32282 [Note] Shutting down plugin 'CSV' 2015-07-24 08:01:52 32282 [Note] Shutting down plugin 'MyISAM' MySQL Version 5.6.25 Checking supported features... - SSL connections supported Collecting tests... Checking leftover processes... Removing old var directory... Creating var directory '/export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var'... Installing system database... ============================================================================== TEST RESULT TIME (ms) or COMMENT -------------------------------------------------------------------------- worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009 include/master-slave.inc Warnings: Note #### Sending passwords in plain text without SSL/TLS is extremely insecure. Note #### Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information. [connection master] [On Master] create table t1(f1 longtext); insert into t1 values(repeat('a', 1073741824/4)); create table t2 like t1; flush logs; insert into t2 select concat(f1,f1,f1,f1) from t1; select count(*) from t2; count(*) 1 main.rpl_bug77817 [ fail ] Test ended at 2015-07-24 08:07:10 CURRENT_TEST: main.rpl_bug77817 === SHOW MASTER STATUS === ---- 1. ---- File slave-bin.000001 Position 268435981 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set ========================== === SHOW SLAVE STATUS === ---- 1. ---- Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 238 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 284 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 120 Relay_Log_Space 623 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event 'master-bin.000001' at 4, the last event read from './master-bin.000002' at 238, the last byte read from './master-bin.000002' at 257.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 7c82fc98-31c9-11e5-922d-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay Slave_SQL_Running_State Slave has read all relay log; waiting for the slave I/O thread to update it Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:02:09 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 ========================= === SHOW PROCESSLIST === ---- 1. ---- Id 2 User root Host localhost:54977 db test Command Sleep Time 314 State Info ---- 2. ---- Id 3 User root Host localhost:54978 db test Command Sleep Time 314 State Info ---- 3. ---- Id 5 User system user Host db Command Connect Time 310 State Slave has read all relay log; waiting for the slave I/O thread to update it Info ---- 4. ---- Id 6 User root Host localhost:54984 db test Command Query Time 0 State init Info SHOW PROCESSLIST ---- 5. ---- Id 7 User root Host localhost:54985 db test Command Sleep Time 314 State Info ======================== analyze: sync_with_master mysqltest: At line 14: sync_slave_with_master failed: 'select master_pos_wait('master-bin.000003', 120, 300)' returned -1 indicating timeout after 300 seconds == /export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/tmp/analyze-sync_with_master-mysqld.1.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:07:10 @@SERVER_ID 1 @@SERVER_UUID 7c82fc98-31c9-11e5-922d-0010e05f3e06 @@PORT 13000 **** GTID_* on default **** SELECT @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File master-bin.000003 Position 120 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 8 root localhost NULL Query 0 init SHOW PROCESSLIST **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size master-bin.000001 268436047 master-bin.000002 1073742223 master-bin.000003 120 **** SHOW BINLOG EVENTS on default **** binlog_name = 'master-bin.000003' SHOW BINLOG EVENTS IN 'master-bin.000003'; Log_name Pos Event_type Server_id End_log_pos Info master-bin.000003 4 Format_desc 1 120 Server ver: 5.6.25-log, Binlog ver: 4 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'No such row' SHOW RELAYLOG EVENTS IN 'No such row'; Log_name Pos Event_type Server_id End_log_pos Info **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position rand_seed: '' _rand_state: '' extra debug info if any: '' connection default; == /export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/tmp/analyze-sync_with_master-mysqld.2.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:07:10 @@SERVER_ID 2 @@SERVER_UUID 7cb7c52a-31c9-11e5-922d-0010e05f3e06 @@PORT 13001 **** GTID_* on default **** SELECT @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 238 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 284 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 120 Relay_Log_Space 623 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event 'master-bin.000001' at 4, the last event read from './master-bin.000002' at 238, the last byte read from './master-bin.000002' at 257.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 7c82fc98-31c9-11e5-922d-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay NULL Slave_SQL_Running_State Slave has read all relay log; waiting for the slave I/O thread to update it Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:02:09 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File slave-bin.000001 Position 268435981 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 5 system user NULL Connect 311 Slave has read all relay log; waiting for the slave I/O thread to update it NULL 8 root localhost NULL Query 0 init SHOW PROCESSLIST **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size slave-bin.000001 268435981 **** SHOW BINLOG EVENTS on default **** binlog_name = 'slave-bin.000001' SHOW BINLOG EVENTS IN 'slave-bin.000001'; Log_name Pos Event_type Server_id End_log_pos Info slave-bin.000001 4 Format_desc 2 120 Server ver: 5.6.25-log, Binlog ver: 4 slave-bin.000001 120 Query 1 222 use `test`; create table t1(f1 longtext) slave-bin.000001 222 Query 1 285 BEGIN slave-bin.000001 285 Table_map 1 331 table_id: 81 (test.t1) slave-bin.000001 331 Write_rows 1 268435827 table_id: 81 flags: STMT_END_F slave-bin.000001 268435827 Query 1 268435891 COMMIT slave-bin.000001 268435891 Query 1 268435981 use `test`; create table t2 like t1 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'slave-relay-bin.000004' SHOW RELAYLOG EVENTS IN 'slave-relay-bin.000004'; Log_name Pos Event_type Server_id End_log_pos Info slave-relay-bin.000004 4 Format_desc 2 120 Server ver: 5.6.25-log, Binlog ver: 4 slave-relay-bin.000004 120 Rotate 1 0 master-bin.000002;pos=4 slave-relay-bin.000004 168 Format_desc 1 120 Server ver: 5.6.25-log, Binlog ver: 4 slave-relay-bin.000004 284 Query 1 192 BEGIN slave-relay-bin.000004 356 Table_map 1 238 table_id: 82 (test.t2) **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position rand_seed: '' _rand_state: '' extra debug info if any: '' connection default; - saving '/export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/log/main.rpl_bug77817/' to '/export/umesh/server/binaries/mysql-5.6.25-linux-glibc2.5-x86_64/mysql-test/var/log/main.rpl_bug77817/' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 318 seconds executing testcases Completed: Failed 1/1 tests, 0.00% were successful. Failing test(s): main.rpl_bug77817 The log files in var/log may give you some hint of what went wrong. If you want to report this error, please read first the documentation at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html mysql-test-run: *** ERROR: there were failing test cases // 5.6.27 [umshastr@hod03]/export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test: ./mtr rpl_bug77817 Logging: ./mtr rpl_bug77817 2015-07-24 08:20:02 0 [Note] /export/umesh/server/binaries/mysql-advanced-5.6.27/bin/mysqld (mysqld 5.6.27-enterprise-commercial-advanced) starting as process 1134 ... 2015-07-24 08:20:02 1134 [Note] Plugin 'FEDERATED' is disabled. 2015-07-24 08:20:02 1134 [Note] Binlog end 2015-07-24 08:20:02 1134 [Note] Shutting down plugin 'CSV' 2015-07-24 08:20:02 1134 [Note] Shutting down plugin 'MyISAM' MySQL Version 5.6.27 Checking supported features... - SSL connections supported Collecting tests... Removing old var directory... Creating var directory '/export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var'... Installing system database... ============================================================================== TEST RESULT TIME (ms) or COMMENT -------------------------------------------------------------------------- worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009 include/master-slave.inc Warnings: Note #### Sending passwords in plain text without SSL/TLS is extremely insecure. Note #### Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information. [connection master] [On Master] set binlog_format='row'; create table t1(f1 longtext); insert into t1 values(repeat('a', 1073741824/4)); create table t2 like t1; flush logs; insert into t2 select concat(f1,f1,f1,f1) from t1; select count(*) from t2; count(*) 1 main.rpl_bug77817 [ fail ] Test ended at 2015-07-24 08:25:24 CURRENT_TEST: main.rpl_bug77817 === SHOW MASTER STATUS === ---- 1. ---- File slave-bin.000001 Position 268435981 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set ========================== === SHOW SLAVE STATUS === ---- 1. ---- Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 238 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 284 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 120 Relay_Log_Space 623 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event 'master-bin.000001' at 4, the last event read from './master-bin.000002' at 238, the last byte read from './master-bin.000002' at 257.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 063a2c92-31cc-11e5-923e-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay Slave_SQL_Running_State Slave has read all relay log; waiting for the slave I/O thread to update it Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:20:23 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 ========================= === SHOW PROCESSLIST === ---- 1. ---- Id 2 User root Host localhost:55021 db test Command Sleep Time 318 State Info ---- 2. ---- Id 3 User root Host localhost:55022 db test Command Sleep Time 318 State Info ---- 3. ---- Id 5 User system user Host db Command Connect Time 314 State Slave has read all relay log; waiting for the slave I/O thread to update it Info ---- 4. ---- Id 6 User root Host localhost:55028 db test Command Query Time 0 State init Info SHOW PROCESSLIST ---- 5. ---- Id 7 User root Host localhost:55029 db test Command Sleep Time 318 State Info ======================== analyze: sync_with_master mysqltest: At line 14: sync_slave_with_master failed: 'select master_pos_wait('master-bin.000003', 120, 300)' returned -1 indicating timeout after 300 seconds == /export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/tmp/analyze-sync_with_master-mysqld.1.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:25:24 @@SERVER_ID 1 @@SERVER_UUID 063a2c92-31cc-11e5-923e-0010e05f3e06 @@PORT 13000 **** GTID_* on default **** SELECT @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File master-bin.000003 Position 120 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 8 root localhost NULL Query 0 init SHOW PROCESSLIST **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size master-bin.000001 268436047 master-bin.000002 1073742223 master-bin.000003 120 **** SHOW BINLOG EVENTS on default **** binlog_name = 'master-bin.000003' SHOW BINLOG EVENTS IN 'master-bin.000003'; Log_name Pos Event_type Server_id End_log_pos Info master-bin.000003 4 Format_desc 1 120 Server ver: 5.6.27-enterprise-commercial-advanced-log, Binlog ver: 4 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'No such row' SHOW RELAYLOG EVENTS IN 'No such row'; Log_name Pos Event_type Server_id End_log_pos Info **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position rand_seed: '' _rand_state: '' extra debug info if any: '' connection default; == /export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/tmp/analyze-sync_with_master-mysqld.2.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:25:24 @@SERVER_ID 2 @@SERVER_UUID 066e39c7-31cc-11e5-923e-0010e05f3e06 @@PORT 13001 **** GTID_* on default **** SELECT @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 238 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 284 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 120 Relay_Log_Space 623 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event 'master-bin.000001' at 4, the last event read from './master-bin.000002' at 238, the last byte read from './master-bin.000002' at 257.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 063a2c92-31cc-11e5-923e-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay NULL Slave_SQL_Running_State Slave has read all relay log; waiting for the slave I/O thread to update it Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:20:23 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File slave-bin.000001 Position 268435981 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 5 system user NULL Connect 315 Slave has read all relay log; waiting for the slave I/O thread to update it NULL 8 root localhost NULL Query 0 init SHOW PROCESSLIST **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size slave-bin.000001 268435981 **** SHOW BINLOG EVENTS on default **** binlog_name = 'slave-bin.000001' SHOW BINLOG EVENTS IN 'slave-bin.000001'; Log_name Pos Event_type Server_id End_log_pos Info slave-bin.000001 4 Format_desc 2 120 Server ver: 5.6.27-enterprise-commercial-advanced-log, Binlog ver: 4 slave-bin.000001 120 Query 1 222 use `test`; create table t1(f1 longtext) slave-bin.000001 222 Query 1 285 BEGIN slave-bin.000001 285 Table_map 1 331 table_id: 81 (test.t1) slave-bin.000001 331 Write_rows 1 268435827 table_id: 81 flags: STMT_END_F slave-bin.000001 268435827 Query 1 268435891 COMMIT slave-bin.000001 268435891 Query 1 268435981 use `test`; create table t2 like t1 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'slave-relay-bin.000004' SHOW RELAYLOG EVENTS IN 'slave-relay-bin.000004'; Log_name Pos Event_type Server_id End_log_pos Info slave-relay-bin.000004 4 Format_desc 2 120 Server ver: 5.6.27-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000004 120 Rotate 1 0 master-bin.000002;pos=4 slave-relay-bin.000004 168 Format_desc 1 120 Server ver: 5.6.27-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000004 284 Query 1 192 BEGIN slave-relay-bin.000004 356 Table_map 1 238 table_id: 82 (test.t2) **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position rand_seed: '' _rand_state: '' extra debug info if any: '' connection default; - saving '/export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/log/main.rpl_bug77817/' to '/export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test/var/log/main.rpl_bug77817/' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 322 seconds executing testcases Completed: Failed 1/1 tests, 0.00% were successful. Failing test(s): main.rpl_bug77817 The log files in var/log may give you some hint of what went wrong. If you want to report this error, please read first the documentation at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html mysql-test-run: *** ERROR: there were failing test cases [umshastr@hod03]/export/umesh/server/binaries/mysql-advanced-5.6.27/mysql-test: // 5.7.9 [umshastr@hod03]/export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test: ./mtr rpl_bug77817 Logging: ./mtr rpl_bug77817 MySQL Version 5.7.9 Checking supported features... - SSL connections supported Collecting tests... Checking leftover processes... Removing old var directory... Creating var directory '/export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var'... Installing system database... ============================================================================== TEST RESULT TIME (ms) or COMMENT -------------------------------------------------------------------------- worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009 include/master-slave.inc Warnings: Note #### Sending passwords in plain text without SSL/TLS is extremely insecure. Note #### Storing MySQL user name or password information in the master info repository is not secure and is therefore not recommended. Please consider using the USER and PASSWORD connection options for START SLAVE; see the 'START SLAVE Syntax' in the MySQL Manual for more information. [connection master] [On Master] set binlog_format='row'; create table t1(f1 longtext); insert into t1 values(repeat('a', 1073741824/4)); create table t2 like t1; flush logs; insert into t2 select concat(f1,f1,f1,f1) from t1; select count(*) from t2; count(*) 1 main.rpl_bug77817 [ fail ] Test ended at 2015-07-24 08:38:46 CURRENT_TEST: main.rpl_bug77817 === SHOW MASTER STATUS === ---- 1. ---- File slave-bin.000001 Position 268436177 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set ========================== === SHOW SLAVE STATUS === ---- 1. ---- Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 337 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 369 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 154 Relay_Log_Space 807 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event '' at 4, the last event read from './master-bin.000002' at 337, the last byte read from './master-bin.000002' at 356.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID d59953e4-31cd-11e5-97c2-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay Slave_SQL_Running_State Slave has read all relay log; waiting for more updates Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:33:44 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 Replicate_Rewrite_DB Channel_Name ========================= === SHOW PROCESSLIST === ---- 1. ---- Id 3 User root Host localhost:55045 db test Command Sleep Time 342 State Info ---- 2. ---- Id 4 User root Host localhost:55046 db test Command Sleep Time 342 State Info ---- 3. ---- Id 6 User system user Host db Command Connect Time 333 State Slave has read all relay log; waiting for more updates Info ---- 4. ---- Id 7 User root Host localhost:55052 db test Command Query Time 0 State starting Info SHOW PROCESSLIST ---- 5. ---- Id 8 User root Host localhost:55053 db test Command Sleep Time 342 State Info ======================== analyze: sync_with_master mysqltest: At line 14: sync_slave_with_master failed: 'select master_pos_wait('master-bin.000003', 154, 300)' returned -1 indicating timeout after 300 seconds safe_process[2381]: Child process: 2382, exit: 1 == /export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var/tmp/analyze-sync_with_master-mysqld.1.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:38:44 @@SERVER_ID 1 @@SERVER_UUID d59953e4-31cd-11e5-97c2-0010e05f3e06 @@PORT 13000 **** GTID_* on default **** SELECT @@GLOBAL.GTID_MODE, @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_MODE OFF @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED Warnings: Level Warning Code 1681 Message '@@SESSION.GTID_EXECUTED' is deprecated and will be removed in a future release. Level Warning Code 1681 Message '@@SESSION.GTID_EXECUTED' is deprecated and will be removed in a future release. **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; **** replication_connection_status on default **** SELECT * FROM performance_schema.replication_connection_status; **** replication_applier_status_by_worker on default **** SELECT * FROM performance_schema.replication_applier_status_by_worker; **** replication_applier_status_by_coordinator on default **** SELECT * FROM performance_schema.replication_applier_status_by_coordinator; **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File master-bin.000003 Position 154 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 9 root localhost NULL Query 0 starting SHOW PROCESSLIST **** SELECT * FROM performance_schema.threads **** SELECT * FROM performance_schema.threads; THREAD_ID NAME TYPE PROCESSLIST_ID PROCESSLIST_USER PROCESSLIST_HOST PROCESSLIST_DB PROCESSLIST_COMMAND PROCESSLIST_TIME PROCESSLIST_STATE PROCESSLIST_INFO PARENT_THREAD_ID ROLE INSTRUMENTED HISTORY CONNECTION_TYPE 1 thread/sql/main BACKGROUND NULL NULL NULL NULL NULL 342 NULL NULL NULL NULL YES YES NULL 2 thread/sql/thread_timer_notifier BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL 1 NULL YES YES NULL 3 thread/innodb/io_ibuf_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 4 thread/innodb/io_log_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 5 thread/innodb/io_read_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 6 thread/innodb/io_read_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 7 thread/innodb/io_write_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 8 thread/innodb/io_write_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 9 thread/innodb/page_cleaner_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 11 thread/innodb/srv_lock_timeout_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 12 thread/innodb/srv_error_monitor_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 13 thread/innodb/srv_monitor_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 14 thread/innodb/srv_master_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 15 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 16 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 17 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 18 thread/innodb/srv_purge_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 19 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 20 thread/innodb/buf_dump_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 21 thread/innodb/dict_stats_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 22 thread/sql/signal_handler BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL 1 NULL YES YES NULL 23 thread/sql/compress_gtid_table FOREGROUND 1 NULL NULL NULL Daemon 342 Suspending NULL 1 NULL YES YES NULL 31 thread/sql/one_connection FOREGROUND 9 root localhost NULL Query 0 Sending data SELECT * FROM performance_schema.threads NULL NULL YES YES Socket **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size master-bin.000001 268436234 master-bin.000002 1073742280 master-bin.000003 154 **** SHOW BINLOG EVENTS on default **** binlog_name = 'master-bin.000003' SHOW BINLOG EVENTS IN 'master-bin.000003'; Log_name Pos Event_type Server_id End_log_pos Info master-bin.000003 4 Format_desc 1 123 Server ver: 5.7.9-enterprise-commercial-advanced-log, Binlog ver: 4 master-bin.000003 123 Previous_gtids 1 154 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'No such row' SHOW RELAYLOG EVENTS IN 'No such row'; Log_name Pos Event_type Server_id End_log_pos Info **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id Channel_name **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position Channel_name **** mysql.gtid_executed on default **** SELECT * FROM mysql.gtid_executed; source_uuid interval_start interval_end rpl_topology= rand_seed: '' _rand_state: '' extra debug info if any: '' rpl_topology= connection default; == /export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var/tmp/analyze-sync_with_master-mysqld.2.err == DO_NOT_CHECK_IN_THIS_LINE: include/show_rpl_debug_info.inc should only be used for debugging. Never check in a test that calls it on success. CURRENT_CONNECTION = 'default' ############################## default ############################## **** SHOW WARNINGS on default **** SHOW WARNINGS; Level Code Message **** SELECT replication-related variables on default **** SELECT NOW(), @@SERVER_ID, @@SERVER_UUID, @@PORT; NOW() 2015-07-24 09:38:44 @@SERVER_ID 2 @@SERVER_UUID d5a312fa-31cd-11e5-97d7-0010e05f3e06 @@PORT 13001 **** GTID_* on default **** SELECT @@GLOBAL.GTID_MODE, @@GLOBAL.GTID_EXECUTED, @@GLOBAL.GTID_PURGED, @@GLOBAL.GTID_OWNED, @@SESSION.GTID_EXECUTED, @@SESSION.GTID_OWNED; @@GLOBAL.GTID_MODE OFF @@GLOBAL.GTID_EXECUTED @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED Warnings: Level Warning Code 1681 Message '@@SESSION.GTID_EXECUTED' is deprecated and will be removed in a future release. Level Warning Code 1681 Message '@@SESSION.GTID_EXECUTED' is deprecated and will be removed in a future release. **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; Slave_IO_State Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000002 Read_Master_Log_Pos 337 Relay_Log_File slave-relay-bin.000004 Relay_Log_Pos 369 Relay_Master_Log_File master-bin.000002 Slave_IO_Running No Slave_SQL_Running Yes 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 Skip_Counter 0 Exec_Master_Log_Pos 154 Relay_Log_Space 807 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 0 Master_SSL_Verify_Server_Cert No Last_IO_Errno 1236 Last_IO_Error Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event '' at 4, the last event read from './master-bin.000002' at 337, the last byte read from './master-bin.000002' at 356.' Last_SQL_Errno 0 Last_SQL_Error Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID d59953e4-31cd-11e5-97c2-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay NULL Slave_SQL_Running_State Slave has read all relay log; waiting for more updates Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp 150724 09:33:44 Last_SQL_Error_Timestamp Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set Executed_Gtid_Set Auto_Position 0 Replicate_Rewrite_DB Channel_Name **** replication_connection_status on default **** SELECT * FROM performance_schema.replication_connection_status; CHANNEL_NAME GROUP_NAME SOURCE_UUID d59953e4-31cd-11e5-97c2-0010e05f3e06 THREAD_ID NULL SERVICE_STATE OFF COUNT_RECEIVED_HEARTBEATS 0 LAST_HEARTBEAT_TIMESTAMP 0000-00-00 00:00:00 RECEIVED_TRANSACTION_SET LAST_ERROR_NUMBER 1236 LAST_ERROR_MESSAGE Got fatal error 1236 from master when reading data from binary log: 'log event entry exceeded max_allowed_packet; Increase max_allowed_packet on master; the first event '' at 4, the last event read from './master-bin.000002' at 337, the last byte read from './master-bin.000002' at 356.' LAST_ERROR_TIMESTAMP 2015-07-24 09:33:44 **** replication_applier_status_by_worker on default **** SELECT * FROM performance_schema.replication_applier_status_by_worker; **** replication_applier_status_by_coordinator on default **** SELECT * FROM performance_schema.replication_applier_status_by_coordinator; CHANNEL_NAME THREAD_ID 28 SERVICE_STATE ON LAST_ERROR_NUMBER 0 LAST_ERROR_MESSAGE LAST_ERROR_TIMESTAMP 0000-00-00 00:00:00 **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File slave-bin.000001 Position 268436177 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 6 system user NULL Connect 333 Slave has read all relay log; waiting for more updates NULL 9 root localhost NULL Query 0 starting SHOW PROCESSLIST **** SELECT * FROM performance_schema.threads **** SELECT * FROM performance_schema.threads; THREAD_ID NAME TYPE PROCESSLIST_ID PROCESSLIST_USER PROCESSLIST_HOST PROCESSLIST_DB PROCESSLIST_COMMAND PROCESSLIST_TIME PROCESSLIST_STATE PROCESSLIST_INFO PARENT_THREAD_ID ROLE INSTRUMENTED HISTORY CONNECTION_TYPE 1 thread/sql/main BACKGROUND NULL NULL NULL NULL NULL 342 NULL NULL NULL NULL YES YES NULL 2 thread/sql/thread_timer_notifier BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL 1 NULL YES YES NULL 3 thread/innodb/io_ibuf_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 4 thread/innodb/io_log_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 5 thread/innodb/io_read_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 6 thread/innodb/io_read_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 7 thread/innodb/io_write_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 8 thread/innodb/io_write_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 9 thread/innodb/page_cleaner_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 11 thread/innodb/srv_lock_timeout_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 12 thread/innodb/srv_error_monitor_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 13 thread/innodb/srv_monitor_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 14 thread/innodb/srv_master_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 15 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 16 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 17 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 18 thread/sql/background BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 19 thread/innodb/srv_purge_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 20 thread/innodb/buf_dump_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 21 thread/innodb/dict_stats_thread BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL NULL NULL YES YES NULL 22 thread/sql/signal_handler BACKGROUND NULL NULL NULL NULL NULL NULL NULL NULL 1 NULL YES YES NULL 23 thread/sql/compress_gtid_table FOREGROUND 1 NULL NULL NULL Daemon 342 Suspending NULL 1 NULL YES YES NULL 28 thread/sql/slave_sql FOREGROUND 6 root localhost NULL Connect 333 Slave has read all relay log; waiting for more updates NULL 25 NULL YES YES NULL 31 thread/sql/one_connection FOREGROUND 9 root localhost NULL Query 0 Sending data SELECT * FROM performance_schema.threads NULL NULL YES YES Socket **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size slave-bin.000001 268436177 **** SHOW BINLOG EVENTS on default **** binlog_name = 'slave-bin.000001' SHOW BINLOG EVENTS IN 'slave-bin.000001'; Log_name Pos Event_type Server_id End_log_pos Info slave-bin.000001 4 Format_desc 2 123 Server ver: 5.7.9-enterprise-commercial-advanced-log, Binlog ver: 4 slave-bin.000001 123 Previous_gtids 2 154 slave-bin.000001 154 Anonymous_Gtid 1 219 SET @@SESSION.GTID_NEXT= 'ANONYMOUS' slave-bin.000001 219 Query 1 321 use `test`; create table t1(f1 longtext) slave-bin.000001 321 Anonymous_Gtid 1 386 SET @@SESSION.GTID_NEXT= 'ANONYMOUS' slave-bin.000001 386 Query 1 449 BEGIN slave-bin.000001 449 Table_map 1 495 table_id: 213 (test.t1) slave-bin.000001 495 Write_rows 1 268435991 table_id: 213 flags: STMT_END_F slave-bin.000001 268435991 Xid 1 268436022 COMMIT /* xid=199 */ slave-bin.000001 268436022 Anonymous_Gtid 1 268436087 SET @@SESSION.GTID_NEXT= 'ANONYMOUS' slave-bin.000001 268436087 Query 1 268436177 use `test`; create table t2 like t1 **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'slave-relay-bin.000004' SHOW RELAYLOG EVENTS IN 'slave-relay-bin.000004'; Log_name Pos Event_type Server_id End_log_pos Info slave-relay-bin.000004 4 Format_desc 2 123 Server ver: 5.7.9-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000004 123 Previous_gtids 2 154 slave-relay-bin.000004 154 Rotate 1 0 master-bin.000002;pos=4 slave-relay-bin.000004 202 Format_desc 1 123 Server ver: 5.7.9-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000004 321 Rotate 0 369 master-bin.000002;pos=154 slave-relay-bin.000004 369 Anonymous_Gtid 1 219 SET @@SESSION.GTID_NEXT= 'ANONYMOUS' slave-relay-bin.000004 434 Query 1 291 BEGIN slave-relay-bin.000004 506 Table_map 1 337 table_id: 214 (test.t2) **** slave_relay_info on default **** SELECT * FROM mysql.slave_relay_log_info; Number_of_lines Relay_log_name Relay_log_pos Master_log_name Master_log_pos Sql_delay Number_of_workers Id Channel_name **** slave_master_info on default **** SELECT * FROM mysql.slave_master_info; Number_of_lines Master_log_name Master_log_pos Host User_name User_password Port Connect_retry Enabled_ssl Ssl_ca Ssl_capath Ssl_cert Ssl_cipher Ssl_key Ssl_verify_server_cert Heartbeat Bind Ignored_server_ids Uuid Retry_count Ssl_crl Ssl_crlpath Enabled_auto_position Channel_name **** mysql.gtid_executed on default **** SELECT * FROM mysql.gtid_executed; source_uuid interval_start interval_end rpl_topology= rand_seed: '' _rand_state: '' extra debug info if any: '' rpl_topology= connection default; - the logfile can be found in '/export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test/var/log/main.rpl_bug77817/rpl_bug77817.log' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 347 seconds executing testcases Completed: Failed 1/1 tests, 0.00% were successful. Failing test(s): main.rpl_bug77817 The log files in var/log may give you some hint of what went wrong. If you want to report this error, please read first the documentation at http://dev.mysql.com/doc/mysql/en/mysql-test-suite.html mysql-test-run: *** ERROR: there were failing test cases [umshastr@hod03]/export/umesh/server/binaries/mysql-advanced-5.7.9/mysql-test: