Details

    • Technical task
    • Status: In Progress (View Workflow)
    • Major
    • Resolution: Unresolved
    • 10.11, 11.4, 11.8, 12.3
    • 10.11, 11.4, 11.8, 12.3, 13.1
    • Tests, MTR
    • None

    Description

      Summary

      main b2a8c2234dbc276fe3947633c9ffea454badf48f

      perl ./mysql-test-run.pl --suite=rpl --big --parallel=8 --force --max-test-fail=0 --retry=2
       
      The servers were restarted 576 times
      Spent 9491.410 of 3371 seconds executing testcases
       
      Completed: Failed 30/1219 tests, 97.54% were successful.
       
      Failing test(s): rpl.rpl_non_direct_mixed_mixing_engines rpl.create_or_replace_mix2 rpl.rpl_binlog_directory rpl.rpl_do_grant rpl.rpl_row_mixing_engines rpl.rpl_read_old_relay_log_info rpl.rpl_row_mysqlbinlog rpl.rpl_upgrade_master_info rpl.rpl_semi_sync_after_sync
       
      The log files in var/log may give you some hint of what went wrong.
       
      If you want to report this error, MariaDB's bug tracker is found at
      https://jira.mariadb.org
       
      Errors/warnings were found in logfiles during server shutdown after running the
      following sequence(s) of tests:
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
      120 tests were skipped, 89 by the test itself.
      

      10.11 88787fc732650bba2efa3acb50284fc62cd4c3d7

      Spent 7245.894 of 2792 seconds executing testcases
       
      Completed: Failed 20/1181 tests, 98.31% were successful.
       
      Failing test(s): rpl.rpl_non_direct_mixed_mixing_engines rpl.rpl_row_mixing_engines rpl.rpl_read_old_relay_log_info rpl.rpl_row_mysqlbinlog rpl.rpl_upgrade_master_info rpl.rpl_semi_sync_after_sync
       
      The log files in var/log may give you some hint of what went wrong.
       
      If you want to report this error, MariaDB's bug tracker is found at
      https://jira.mariadb.org
       
      Errors/warnings were found in logfiles during server shutdown after running the
      following sequence(s) of tests:
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_read_old_relay_log_info
          rpl.rpl_upgrade_master_info
          rpl.rpl_upgrade_master_info
      127 tests were skipped, 96 by the test itself.
      

      Per test

      rpl.rpl_non_direct_mixed_mixing_engines 'mix' w1 [ fail ]
              Test ended at 2026-09-11 13:13:56
       
      CURRENT_TEST: rpl.rpl_non_direct_mixed_mixing_engines
      mysqltest: In included file "./include/rpl_init.inc": 
      included from ./include/master-slave.inc at line 39:
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_non_direct_mixed_mixing_engines.test at line 10:
      At line 176: query 'SET GLOBAL gtid_slave_pos= ""' failed: ER_SLAVE_MUST_STOP (1198): This operation cannot be performed as you have a running slave ''; run STOP SLAVE '' first
      

      (same on retry)

      rpl.create_or_replace_mix2 'mix'         w1 [ fail ]
              Test ended at 2026-09-11 13:14:05
       
      CURRENT_TEST: rpl.create_or_replace_mix2
      mysqltest: In included file "./include/rpl_init.inc": 
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/create_or_replace.inc at line 6:
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/create_or_replace_mix2.test at line 7:
      At line 176: query 'SET GLOBAL gtid_slave_pos= ""' failed: ER_SLAVE_MUST_STOP (1198): This operation cannot be performed as you have a running slave ''; run STOP SLAVE '' first
       
      The result from queries just before the failure was:
      set @@global.create_tmp_table_binlog_formats="mixed";
      Warnings:
      Warning	1292	Truncated incorrect create_tmp_table_binlog_formats value: 'MIXED'
      set @@session.create_tmp_table_binlog_formats="mixed";
      Warnings:
      Warning	1292	Truncated incorrect create_tmp_table_binlog_formats value: 'MIXED'
      include/rpl_init.inc [topology=1->2]
      

      (passed on retry)

      rpl.rpl_binlog_directory 'mix'           w5 [ fail ]
              Test ended at 2026-09-11 13:18:15
       
      CURRENT_TEST: rpl.rpl_binlog_directory
      --- C:/home/Builds/main/mysql-test/suite/rpl/r/rpl_binlog_directory.result	2026-09-03 11:12:02.047393600 +0300
      +++ C:\home\Builds\main\mysql-test\suite\rpl\r\rpl_binlog_directory.reject	2026-09-11 13:18:14.157966800 +0300
      @@ -23,9 +23,9 @@
       master-bin.000002	#
       master-bin.000002	#
       *** Contents of master-bin.index (including directory path):
      -./master-bin.000001
      -binlog_dir/master-bin.000002
      -./master-bin.000002
      +.\master-bin.000001
      +binlog_dir\master-bin.000002
      +.\master-bin.000002
       SELECT * FROM t1 ORDER BY a;
       a	b
       1	10
       
      Result content mismatch
      

      (same on retry)

      rpl.rpl_do_grant 'mix'                   w3 [ fail ]
              Test ended at 2026-09-11 13:23:31
       
      CURRENT_TEST: rpl.rpl_do_grant
      --- C:/home/Builds/main/mysql-test/suite/rpl/r/rpl_do_grant.result	2026-09-03 11:12:02.098066000 +0300
      +++ C:\home\Builds\main\mysql-test\suite\rpl\r\rpl_do_grant.reject	2026-09-11 13:23:31.147370300 +0300
      @@ -164,6 +164,8 @@
       "Test the user who creates a function on master doesn't exist on slave."
       "Hence SQL thread ACL_GLOBAL privilege jumps in and no mysql.procs_priv is inserted"
       DROP USER 'create_rout_db'@'localhost';
      +Warnings:
      +Note	4227	Dropped users 'create_rout_db'@'localhost' have active connections. Use KILL CONNECTION if they should not be used anymore.
       connection create_rout_db_master;
       CREATE FUNCTION upgrade_alter_func() RETURNS CHAR(30)
       BEGIN
       
      Result length mismatch
      

      (same on retry)

      rpl.rpl_row_mixing_engines 'row'         w6 [ fail ]
              Test ended at 2026-09-11 13:24:17
       
      CURRENT_TEST: rpl.rpl_row_mixing_engines
      mysqltest: In included file "./include/rpl_init.inc": 
      included from ./include/master-slave.inc at line 39:
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_row_mixing_engines.test at line 10:
      At line 176: query 'SET GLOBAL gtid_slave_pos= ""' failed: ER_SLAVE_MUST_STOP (1198): This operation cannot be performed as you have a running slave ''; run STOP SLAVE '' first
       
      The result from queries just before the failure was:
      include/master-slave.inc
      

      Something very odd happens on retry:

      rpl.rpl_row_mixing_engines 'row'         w6 [ skipped ]  Test requires: 'big_test'
      

      (We do have --big, and the test just ran, so it's all wrong)

      rpl.rpl_read_old_relay_log_info 'mix'    w3 [ fail ]
              Test ended at 2026-09-11 13:28:41
       
      CURRENT_TEST: rpl.rpl_read_old_relay_log_info
      mysqltest: In included file "./include/rpl_end.inc": 
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_read_old_relay_log_info.test at line 74:
      At line 93: query 'CHANGE MASTER TO master_use_gtid=Slave_Pos' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      < snip >
      INSERT INTO t1 VALUES (1);
      DROP TABLE t1;
      connection slave;
      ==== Check that we still understand the old format of relay-log.info ====
      include/stop_slave.inc
      include/reset_slave.inc
      # Read relay-log.info
      START SLAVE IO_THREAD;
      include/wait_for_slave_io_to_start.inc
      # Check that relay log coordinates are equal to those we saved in old-format_relay-log.info
      = , 0, slave-relay-bin.000001, 4
      #
      # MDEV-38020: Master & relay log info files read 2^31 and above incorrectly
      #
      connection server_2;
      include/stop_slave.inc
      include/rpl_restart_server.inc [server_number=2 parameters: --skip-slave-start]
      include/assert.inc [relay_log_pos should be 2147483648 after restart]
      include/rpl_end.inc
      

      (same on retry)

      rpl.rpl_row_mysqlbinlog 'row'            w8 [ fail ]
              Test ended at 2026-09-11 13:29:16
       
      CURRENT_TEST: rpl.rpl_row_mysqlbinlog
      --------------
      insert into t5 values(_cp932'�\');
      --------------
       
      ERROR 1064 (42000) at line 1: You have an error in your SQL syntax; check the manual that corresponds to your MariaDB server version for the right syntax to use near ''?╜\')' at line 1
      mysqltest: At line 244: exec of 'C:\home\Builds\main\client\Debug\mariadb.exe --defaults-file=C:/home/Builds/main/mysql-test/var/8/my.cnf --default-character-set=cp932 test -e "insert into t5 values(_cp932'â\');"' failed, error: 1, status: 1, errno: 2
      Output from before failure:
      create table t5 (f text character set cp932);
       
       
       
      The result from queries just before the failure was:
      < snip >
      /*!*/;
      DELIMITER ;
      # End of log file
      ROLLBACK /* added by mysqlbinlog */;
      /*!50003 SET COMPLETION_TYPE=@OLD_COMPLETION_TYPE*/;
      /*!50530 SET @@SESSION.PSEUDO_SLAVE_MODE=0*/;
      --- Test 8 switch internal charset --
      connection slave;
      stop slave;
      include/wait_for_slave_to_stop.inc
      connection master;
      include/kill_binlog_dump_threads.inc
      reset master;
      connection slave;
      include/reset_slave.inc
      start slave;
      include/wait_for_slave_to_start.inc
      connection master;
      create table t4 (f text character set utf8);
      create table t5 (f text character set cp932);
      

      (same on retry)

       
      rpl.rpl_upgrade_master_info 'mix'        w3 [ fail ]
              Test ended at 2026-09-11 13:33:13
       
      CURRENT_TEST: rpl.rpl_upgrade_master_info
      mysqltest: At line 28: query 'CHANGE MASTER TO master_host='127.0.0.1', master_port=$SERVER_MYPORT_1' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      include/master-slave.inc
      [connection master]
      *** MDEV-9383: Server fails to read master.info after upgrade 10.0 -> 10.1 ***
      connection slave;
      include/stop_slave.inc
      include/rpl_stop_server.inc [server_number=2]
      include/rpl_start_server.inc [server_number=2]
      connection master;
      CREATE TABLE t1 (a INT PRIMARY KEY);
      INSERT INTO t1 VALUES (1);
      include/save_master_gtid.inc
      connection slave;
      CHANGE MASTER TO master_host='127.0.0.1', master_port=SERVER_MYPORT_1;
       
       - saving 'C:/home/Builds/main/mysql-test/var/3/log/rpl.rpl_upgrade_master_info-mix/' to 'C:/home/Builds/main/mysql-test/var/log/rpl.rpl_upgrade_master_info-mix/'
       
      Retrying test rpl.rpl_upgrade_master_info, attempt(2/2)...
       
      ***Warnings generated in error logs during shutdown after running tests: rpl.rpl_upgrade_master_info
       
      2026-09-11 13:33:12 0 [ERROR] Error reading master configuration
      2026-09-11 13:33:12 0 [ERROR] Failed to initialize the master info structure
      2026-09-11 13:33:13 4 [ERROR] Error reading master configuration
      

      (same on retry)

      rpl.rpl_read_old_relay_log_info 'row'    w8 [ fail ]
              Test ended at 2026-09-11 13:35:57
       
      CURRENT_TEST: rpl.rpl_read_old_relay_log_info
      mysqltest: In included file "./include/rpl_end.inc": 
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_read_old_relay_log_info.test at line 74:
      At line 93: query 'CHANGE MASTER TO master_use_gtid=Slave_Pos' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      < snip >
      INSERT INTO t1 VALUES (1);
      DROP TABLE t1;
      connection slave;
      ==== Check that we still understand the old format of relay-log.info ====
      include/stop_slave.inc
      include/reset_slave.inc
      # Read relay-log.info
      START SLAVE IO_THREAD;
      include/wait_for_slave_io_to_start.inc
      # Check that relay log coordinates are equal to those we saved in old-format_relay-log.info
      = , 0, slave-relay-bin.000001, 4
      #
      # MDEV-38020: Master & relay log info files read 2^31 and above incorrectly
      #
      connection server_2;
      include/stop_slave.inc
      include/rpl_restart_server.inc [server_number=2 parameters: --skip-slave-start]
      include/assert.inc [relay_log_pos should be 2147483648 after restart]
      include/rpl_end.inc
      

      (same on retry)

      rpl.rpl_do_grant 'row'                   w3 [ fail ]
              Test ended at 2026-09-11 13:39:13
       
      CURRENT_TEST: rpl.rpl_do_grant
      --- C:/home/Builds/main/mysql-test/suite/rpl/r/rpl_do_grant.result	2026-09-03 11:12:02.098066000 +0300
      +++ C:\home\Builds\main\mysql-test\suite\rpl\r\rpl_do_grant.reject	2026-09-11 13:39:12.573201300 +0300
      @@ -164,6 +164,8 @@
       "Test the user who creates a function on master doesn't exist on slave."
       "Hence SQL thread ACL_GLOBAL privilege jumps in and no mysql.procs_priv is inserted"
       DROP USER 'create_rout_db'@'localhost';
      +Warnings:
      +Note	4227	Dropped users 'create_rout_db'@'localhost' have active connections. Use KILL CONNECTION if they should not be used anymore.
       connection create_rout_db_master;
       CREATE FUNCTION upgrade_alter_func() RETURNS CHAR(30)
       BEGIN
       
      Result length mismatch
      

      (same on retry)

      rpl.rpl_upgrade_master_info 'row'        w8 [ fail ]
              Test ended at 2026-09-11 13:40:09
       
      CURRENT_TEST: rpl.rpl_upgrade_master_info
      mysqltest: At line 28: query 'CHANGE MASTER TO master_host='127.0.0.1', master_port=$SERVER_MYPORT_1' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      include/master-slave.inc
      [connection master]
      *** MDEV-9383: Server fails to read master.info after upgrade 10.0 -> 10.1 ***
      connection slave;
      include/stop_slave.inc
      include/rpl_stop_server.inc [server_number=2]
      include/rpl_start_server.inc [server_number=2]
      connection master;
      CREATE TABLE t1 (a INT PRIMARY KEY);
      INSERT INTO t1 VALUES (1);
      include/save_master_gtid.inc
      connection slave;
      CHANGE MASTER TO master_host='127.0.0.1', master_port=SERVER_MYPORT_1;
      

      (same on retry)

      rpl.rpl_semi_sync_after_sync 'stmt'      w6 [ fail ]
              Test ended at 2026-09-11 13:41:53
       
      CURRENT_TEST: rpl.rpl_semi_sync_after_sync
      mysqltest: In included file "./include/rpl_init.inc": 
      included from ./include/master-slave.inc at line 39:
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_semi_sync.test at line 10:
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_semi_sync_after_sync.test at line 4:
      At line 176: query 'SET GLOBAL gtid_slave_pos= ""' failed: ER_SLAVE_MUST_STOP (1198): This operation cannot be performed as you have a running slave ''; run STOP SLAVE '' first
       
      The result from queries just before the failure was:
      set global rpl_semi_sync_master_wait_point=AFTER_SYNC;
      include/master-slave.inc
      

      (passes on retry)

      rpl.rpl_do_grant 'stmt'                  w5 [ fail ]
              Test ended at 2026-09-11 13:50:50
       
      CURRENT_TEST: rpl.rpl_do_grant
      --- C:/home/Builds/main/mysql-test/suite/rpl/r/rpl_do_grant.result	2026-09-03 11:12:02.098066000 +0300
      +++ C:\home\Builds\main\mysql-test\suite\rpl\r\rpl_do_grant.reject	2026-09-11 13:50:50.050499000 +0300
      @@ -164,6 +164,8 @@
       "Test the user who creates a function on master doesn't exist on slave."
       "Hence SQL thread ACL_GLOBAL privilege jumps in and no mysql.procs_priv is inserted"
       DROP USER 'create_rout_db'@'localhost';
      +Warnings:
      +Note	4227	Dropped users 'create_rout_db'@'localhost' have active connections. Use KILL CONNECTION if they should not be used anymore.
       connection create_rout_db_master;
       CREATE FUNCTION upgrade_alter_func() RETURNS CHAR(30)
       BEGIN
       
      Result length mismatch
      

      (same on retry)

      rpl.rpl_read_old_relay_log_info 'stmt'   w1 [ fail ]
              Test ended at 2026-09-11 13:52:24
       
      CURRENT_TEST: rpl.rpl_read_old_relay_log_info
      mysqltest: In included file "./include/rpl_end.inc": 
      included from C:/home/Builds/main/mysql-test/suite/rpl/t/rpl_read_old_relay_log_info.test at line 74:
      At line 93: query 'CHANGE MASTER TO master_use_gtid=Slave_Pos' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      < snip >
      INSERT INTO t1 VALUES (1);
      DROP TABLE t1;
      connection slave;
      ==== Check that we still understand the old format of relay-log.info ====
      include/stop_slave.inc
      include/reset_slave.inc
      # Read relay-log.info
      START SLAVE IO_THREAD;
      include/wait_for_slave_io_to_start.inc
      # Check that relay log coordinates are equal to those we saved in old-format_relay-log.info
      = , 0, slave-relay-bin.000001, 4
      #
      # MDEV-38020: Master & relay log info files read 2^31 and above incorrectly
      #
      connection server_2;
      include/stop_slave.inc
      include/rpl_restart_server.inc [server_number=2 parameters: --skip-slave-start]
      include/assert.inc [relay_log_pos should be 2147483648 after restart]
      include/rpl_end.inc
      

      (same on retry)

      rpl.rpl_upgrade_master_info 'stmt'       w1 [ fail ]
              Test ended at 2026-09-11 13:53:32
       
      CURRENT_TEST: rpl.rpl_upgrade_master_info
      mysqltest: At line 28: query 'CHANGE MASTER TO master_host='127.0.0.1', master_port=$SERVER_MYPORT_1' failed: ER_MASTER_INFO (1201): Could not initialize master info structure for ''; more error messages can be found in the MariaDB error log
       
      The result from queries just before the failure was:
      include/master-slave.inc
      [connection master]
      *** MDEV-9383: Server fails to read master.info after upgrade 10.0 -> 10.1 ***
      connection slave;
      include/stop_slave.inc
      include/rpl_stop_server.inc [server_number=2]
      include/rpl_start_server.inc [server_number=2]
      connection master;
      CREATE TABLE t1 (a INT PRIMARY KEY);
      INSERT INTO t1 VALUES (1);
      include/save_master_gtid.inc
      connection slave;
      CHANGE MASTER TO master_host='127.0.0.1', master_port=SERVER_MYPORT_1;
      

      (same on retry)

      Other

      Unclear what it relates to:

      rpl.rpl_temp_table_mix_row 'mix'         w1 [ pass ]   8543
      perl : worker[04] mysql-test-run: WARNING: Process [mysqld.1 - pid: -1, winpid: -1] died after mysql-test-run waited 0 
      seconds for C:/home/Builds/main/mysql-test/var/4/run/mysqld.1.pid to be created.
      At line:1 char:1
      + perl ./mysql-test-run.pl --suite=rpl --big --parallel=8 --force --max ...
      + ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
          + CategoryInfo          : NotSpecified: (worker[04] mysq... to be created.:String) [], RemoteException
          + FullyQualifiedErrorId : NativeCommandError
       
      Died at lib/My/SafeProcess.pm line 378.
      

      Attachments

        1. rpl-out.txt
          242 kB
        2. rpl-out2.txt
          1.49 MB

        Activity

          People

            elenst Elena Stepanova
            elenst Elena Stepanova
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

            Dates

              Created:
              Updated:

              Git Integration

                Error rendering 'com.xiplink.jira.git.jira_git_plugin:git-issue-webpanel'. Please contact your Jira administrators.