[umshastr@hod03]/export/umesh/server/binaries/mysql-5.6.26/mysql-test: ./mtr rpl_bug77354 Logging: ./mtr rpl_bug77354 2015-06-15 08:59:09 0 [Note] /export/umesh/server/binaries/mysql-5.6.26/bin/mysqld (mysqld 5.6.26-enterprise-commercial-advanced) starting as process 21657 ... 2015-06-15 08:59:09 21657 [Note] Plugin 'FEDERATED' is disabled. 2015-06-15 08:59:09 21657 [Note] Binlog end 2015-06-15 08:59:09 21657 [Note] Shutting down plugin 'CSV' 2015-06-15 08:59:09 21657 [Note] Shutting down plugin 'MyISAM' MySQL Version 5.6.26 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.26/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 main.rpl_bug77354 [ fail ] Test ended at 2015-06-15 08:59:12 CURRENT_TEST: main.rpl_bug77354 mysqltest: In included file "./include/rpl_change_topology.inc": included from ./include/rpl_change_topology.inc at line 283: included from ./include/rpl_init.inc at line 263: included from ./include/master-slave.inc at line 40: At line 283: query 'CHANGE MASTER TO MASTER_HOST = '127.0.0.1', MASTER_PORT = $_rpl_port, MASTER_USER = 'root', $_rpl_change_master_position, MASTER_CONNECT_RETRY = 1' failed: 1777: CHANGE MASTER TO MASTER_AUTO_POSITION = 1 can only be executed when @@GLOBAL.GTID_MODE = ON. - saving '/export/umesh/server/binaries/mysql-5.6.26/mysql-test/var/log/main.rpl_bug77354/' to '/export/umesh/server/binaries/mysql-5.6.26/mysql-test/var/log/main.rpl_bug77354/' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 3 seconds executing testcases Completed: Failed 1/1 tests, 0.00% were successful. Failing test(s): main.rpl_bug77354 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-5.6.26/mysql-test: cp t/rpl_bug77354-master.opt t/rpl_bug77354-slave.opt [umshastr@hod03]/export/umesh/server/binaries/mysql-5.6.26/mysql-test: [umshastr@hod03]/export/umesh/server/binaries/mysql-5.6.26/mysql-test: ./mtr rpl_bug77354 Logging: ./mtr rpl_bug77354 2015-06-15 08:59:52 0 [Note] /export/umesh/server/binaries/mysql-5.6.26/bin/mysqld (mysqld 5.6.26-enterprise-commercial-advanced) starting as process 21736 ... 2015-06-15 08:59:52 21736 [Note] Plugin 'FEDERATED' is disabled. 2015-06-15 08:59:52 21736 [Note] Binlog end 2015-06-15 08:59:52 21736 [Note] Shutting down plugin 'CSV' 2015-06-15 08:59:52 21736 [Note] Shutting down plugin 'MyISAM' MySQL Version 5.6.26 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.26/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 [connection master] CREATE FUNCTION `test_func` () RETURNS varchar(30) CHARSET utf8 BEGIN DROP TEMPORARY TABLE IF EXISTS test_func_1; DROP TEMPORARY TABLE IF EXISTS test_func_2; DROP TEMPORARY TABLE IF EXISTS test_func_3; DROP TEMPORARY TABLE IF EXISTS test_func_4; RETURN "hello"; END// SELECT test_func(); test_func() hello DROP FUNCTION test_func; main.rpl_bug77354 [ fail ] Test ended at 2015-06-15 08:59:55 CURRENT_TEST: main.rpl_bug77354 === SHOW MASTER STATUS === ---- 1. ---- File slave-bin.000001 Position 779 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-2 ========================== === SHOW SLAVE STATUS === ---- 1. ---- Slave_IO_State Waiting for master to send event Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000001 Read_Master_Log_Pos 1377 Relay_Log_File slave-relay-bin.000002 Relay_Log_Pos 991 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 1837 Last_Error Error 'When @@SESSION.GTID_NEXT is set to a GTID, you must explicitly set it to a different value after a COMMIT or ROLLBACK. Please check GTID_NEXT variable manual page for detailed explanation. Current @@SESSION.GTID_NEXT is '208c188f-132c-11e5-8a8b-0010e05f3e06:2'.' on query. Default database: 'test'. Query: 'DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */' Skip_Counter 0 Exec_Master_Log_Pos 779 Relay_Log_Space 1793 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 1837 Last_SQL_Error Error 'When @@SESSION.GTID_NEXT is set to a GTID, you must explicitly set it to a different value after a COMMIT or ROLLBACK. Please check GTID_NEXT variable manual page for detailed explanation. Current @@SESSION.GTID_NEXT is '208c188f-132c-11e5-8a8b-0010e05f3e06:2'.' on query. Default database: 'test'. Query: 'DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */' Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 208c188f-132c-11e5-8a8b-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-5.6.26/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay Slave_SQL_Running_State Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp Last_SQL_Error_Timestamp 150615 09:59:55 Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-3 Executed_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-2 Auto_Position 1 ========================= === SHOW PROCESSLIST === ---- 1. ---- Id 2 User root Host localhost:52588 db test Command Sleep Time 0 State Info ---- 2. ---- Id 3 User root Host localhost:52589 db test Command Sleep Time 0 State Info ---- 3. ---- Id 4 User system user Host db Command Connect Time 0 State Waiting for master to send event Info ---- 4. ---- Id 6 User root Host localhost:52595 db test Command Query Time 0 State init Info SHOW PROCESSLIST ---- 5. ---- Id 7 User root Host localhost:52596 db test Command Sleep Time 0 State Info ======================== analyze: sync_with_master mysqltest: At line 32: sync_slave_with_master failed: 'select master_pos_wait('master-bin.000001', 1377, 300)' returned NULL indicating slave SQL thread failure == /export/umesh/server/binaries/mysql-5.6.26/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-06-15 09:59:55 @@SERVER_ID 1 @@SERVER_UUID 208c188f-132c-11e5-8a8b-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 208c188f-132c-11e5-8a8b-0010e05f3e06:1-3 @@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.000001 Position 1377 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-3 **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; Server_id 2 Host 127.0.0.1 Port 13001 Master_id 1 Slave_UUID 20c22eb8-132c-11e5-8a8b-0010e05f3e06 **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 5 root localhost:54871 NULL Binlog Dump GTID 0 Master has sent all binlog to slave; waiting for binlog to be updated NULL 8 root localhost NULL Query 0 init SHOW PROCESSLIST **** SHOW BINARY LOGS on default **** SHOW BINARY LOGS; Log_name File_size master-bin.000001 1377 **** SHOW BINLOG EVENTS on default **** binlog_name = 'master-bin.000001' SHOW BINLOG EVENTS IN 'master-bin.000001'; Log_name Pos Event_type Server_id End_log_pos Info master-bin.000001 4 Format_desc 1 120 Server ver: 5.6.26-enterprise-commercial-advanced-log, Binlog ver: 4 master-bin.000001 120 Previous_gtids 1 151 master-bin.000001 151 Gtid 1 199 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:1' master-bin.000001 199 Query 1 580 use `test`; CREATE DEFINER=`root`@`localhost` FUNCTION `test_func`() RETURNS varchar(30) CHARSET utf8 BEGIN DROP TEMPORARY TABLE IF EXISTS test_func_1; DROP TEMPORARY TABLE IF EXISTS test_func_2; DROP TEMPORARY TABLE IF EXISTS test_func_3; DROP TEMPORARY TABLE IF EXISTS test_func_4; RETURN "hello"; END master-bin.000001 580 Gtid 1 628 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:2' master-bin.000001 628 Query 1 779 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_1` /* generated by server */ master-bin.000001 779 Query 1 930 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */ master-bin.000001 930 Query 1 1081 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_3` /* generated by server */ master-bin.000001 1081 Query 1 1232 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_4` /* generated by server */ master-bin.000001 1232 Gtid 1 1280 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:3' master-bin.000001 1280 Query 1 1377 use `test`; DROP FUNCTION test_func **** 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.26/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-06-15 09:59:55 @@SERVER_ID 2 @@SERVER_UUID 20c22eb8-132c-11e5-8a8b-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 208c188f-132c-11e5-8a8b-0010e05f3e06:1-2 @@GLOBAL.GTID_PURGED @@GLOBAL.GTID_OWNED @@SESSION.GTID_EXECUTED @@SESSION.GTID_OWNED **** SHOW SLAVE STATUS on default **** SHOW SLAVE STATUS; Slave_IO_State Waiting for master to send event Master_Host 127.0.0.1 Master_User root Master_Port 13000 Connect_Retry 1 Master_Log_File master-bin.000001 Read_Master_Log_Pos 1377 Relay_Log_File slave-relay-bin.000002 Relay_Log_Pos 991 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 1837 Last_Error Error 'When @@SESSION.GTID_NEXT is set to a GTID, you must explicitly set it to a different value after a COMMIT or ROLLBACK. Please check GTID_NEXT variable manual page for detailed explanation. Current @@SESSION.GTID_NEXT is '208c188f-132c-11e5-8a8b-0010e05f3e06:2'.' on query. Default database: 'test'. Query: 'DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */' Skip_Counter 0 Exec_Master_Log_Pos 779 Relay_Log_Space 1793 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 NULL Master_SSL_Verify_Server_Cert No Last_IO_Errno 0 Last_IO_Error Last_SQL_Errno 1837 Last_SQL_Error Error 'When @@SESSION.GTID_NEXT is set to a GTID, you must explicitly set it to a different value after a COMMIT or ROLLBACK. Please check GTID_NEXT variable manual page for detailed explanation. Current @@SESSION.GTID_NEXT is '208c188f-132c-11e5-8a8b-0010e05f3e06:2'.' on query. Default database: 'test'. Query: 'DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */' Replicate_Ignore_Server_Ids Master_Server_Id 1 Master_UUID 208c188f-132c-11e5-8a8b-0010e05f3e06 Master_Info_File /export/umesh/server/binaries/mysql-5.6.26/mysql-test/var/mysqld.2/data/master.info SQL_Delay 0 SQL_Remaining_Delay NULL Slave_SQL_Running_State Master_Retry_Count 10 Master_Bind Last_IO_Error_Timestamp Last_SQL_Error_Timestamp 150615 09:59:55 Master_SSL_Crl Master_SSL_Crlpath Retrieved_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-3 Executed_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-2 Auto_Position 1 **** SHOW MASTER STATUS on default **** SHOW MASTER STATUS; File slave-bin.000001 Position 779 Binlog_Do_DB Binlog_Ignore_DB Executed_Gtid_Set 208c188f-132c-11e5-8a8b-0010e05f3e06:1-2 **** SHOW SLAVE HOSTS on default **** SHOW SLAVE HOSTS; **** SHOW PROCESSLIST on default **** SHOW PROCESSLIST; Id User Host db Command Time State Info 4 system user NULL Connect 0 Waiting for master to send event 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 779 **** 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.26-enterprise-commercial-advanced-log, Binlog ver: 4 slave-bin.000001 120 Previous_gtids 2 151 slave-bin.000001 151 Gtid 1 199 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:1' slave-bin.000001 199 Query 1 580 use `test`; CREATE DEFINER=`root`@`localhost` FUNCTION `test_func`() RETURNS varchar(30) CHARSET utf8 BEGIN DROP TEMPORARY TABLE IF EXISTS test_func_1; DROP TEMPORARY TABLE IF EXISTS test_func_2; DROP TEMPORARY TABLE IF EXISTS test_func_3; DROP TEMPORARY TABLE IF EXISTS test_func_4; RETURN "hello"; END slave-bin.000001 580 Gtid 1 628 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:2' slave-bin.000001 628 Query 1 779 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_1` /* generated by server */ **** SHOW RELAYLOG EVENTS on default **** relaylog_name = 'slave-relay-bin.000002' SHOW RELAYLOG EVENTS IN 'slave-relay-bin.000002'; Log_name Pos Event_type Server_id End_log_pos Info slave-relay-bin.000002 4 Format_desc 2 120 Server ver: 5.6.26-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000002 120 Previous_gtids 2 151 slave-relay-bin.000002 151 Rotate 1 0 master-bin.000001;pos=4 slave-relay-bin.000002 199 Format_desc 1 120 Server ver: 5.6.26-enterprise-commercial-advanced-log, Binlog ver: 4 slave-relay-bin.000002 315 Rotate 0 363 master-bin.000001;pos=151 slave-relay-bin.000002 363 Gtid 1 199 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:1' slave-relay-bin.000002 411 Query 1 580 use `test`; CREATE DEFINER=`root`@`localhost` FUNCTION `test_func`() RETURNS varchar(30) CHARSET utf8 BEGIN DROP TEMPORARY TABLE IF EXISTS test_func_1; DROP TEMPORARY TABLE IF EXISTS test_func_2; DROP TEMPORARY TABLE IF EXISTS test_func_3; DROP TEMPORARY TABLE IF EXISTS test_func_4; RETURN "hello"; END slave-relay-bin.000002 792 Gtid 1 628 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:2' slave-relay-bin.000002 840 Query 1 779 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_1` /* generated by server */ slave-relay-bin.000002 991 Query 1 930 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_2` /* generated by server */ slave-relay-bin.000002 1142 Query 1 1081 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_3` /* generated by server */ slave-relay-bin.000002 1293 Query 1 1232 DROP TEMPORARY TABLE IF EXISTS `test`.`test_func_4` /* generated by server */ slave-relay-bin.000002 1444 Gtid 1 1280 SET @@SESSION.GTID_NEXT= '208c188f-132c-11e5-8a8b-0010e05f3e06:3' slave-relay-bin.000002 1492 Query 1 1377 use `test`; DROP FUNCTION test_func **** 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.26/mysql-test/var/log/main.rpl_bug77354/' to '/export/umesh/server/binaries/mysql-5.6.26/mysql-test/var/log/main.rpl_bug77354/' -------------------------------------------------------------------------- The servers were restarted 0 times Spent 0.000 of 3 seconds executing testcases Completed: Failed 1/1 tests, 0.00% were successful. Failing test(s): main.rpl_bug77354 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-5.6.26/mysql-test: