mariabackup.absolute_ibdata_paths failed in buildbot with wrong result

mariabackup.absolute_ibdata_paths        w2 [ fail ]

        Test ended at 2018-06-20 09:39:02

CURRENT_TEST: mariabackup.absolute_ibdata_paths

/usr/bin/mariabackup based on MariaDB server 10.3.8-MariaDB Linux (ppc64le) 

mariabackup: cd to /dev/shm/var/2/tmp/backup/

mariabackup: This target seems to be not prepared yet.

mariabackup: using the following InnoDB configuration for recovery:

mariabackup:   innodb_data_home_dir = .

mariabackup:   innodb_data_file_path = absolute_path_ibdata1:3M;ibdata_second:1M:autoextend

mariabackup:   innodb_log_group_home_dir = .

mariabackup: Starting InnoDB instance for recovery.

mariabackup: Using 104857600 bytes for buffer pool (set by --use-memory parameter)

2018-06-20  9:38:58 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins

2018-06-20  9:38:58 0 [Note] InnoDB: Uses event mutexes

2018-06-20  9:38:58 0 [Note] InnoDB: Compressed tables use zlib 1.2.7

2018-06-20  9:38:58 0 [Note] InnoDB: Number of pools: 1

2018-06-20  9:38:58 0 [Note] InnoDB: Using POWER8 crc32 instructions

2018-06-20  9:38:58 0 [Note] InnoDB: Initializing buffer pool, total size = 100M, instances = 1, chunk size = 100M

2018-06-20  9:38:58 0 [Note] InnoDB: Completed initialization of buffer pool

2018-06-20  9:38:58 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().

2018-06-20  9:38:58 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=1642206

2018-06-20  9:38:58 0 [Note] InnoDB: Starting final batch to recover 13 pages from redo log.

Last binlog file , position 0

180620 09:38:59 completed OK!

/usr/bin/mariabackup based on MariaDB server 10.3.8-MariaDB Linux (ppc64le) 

180620 09:39:01 [01] Copying absolute_path_ibdata1 to /dev/shm/var/2/tmp/absolute_path_ibdata1

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ibdata_second to /dev/shm/var/2/mysqld.1/data/ibdata_second

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./xtrabackup_binlog_pos_innodb to /dev/shm/var/2/mysqld.1/data/xtrabackup_binlog_pos_innodb

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./xtrabackup_info to /dev/shm/var/2/mysqld.1/data/xtrabackup_info

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./ib_buffer_pool to /dev/shm/var/2/mysqld.1/data/ib_buffer_pool

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./aria_log_control to /dev/shm/var/2/mysqld.1/data/aria_log_control

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./aria_log.00000001 to /dev/shm/var/2/mysqld.1/data/aria_log.00000001

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./performance_schema/db.opt to /dev/shm/var/2/mysqld.1/data/performance_schema/db.opt

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/db.opt to /dev/shm/var/2/mysqld.1/data/mtr/db.opt

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/test_suppressions.frm to /dev/shm/var/2/mysqld.1/data/mtr/test_suppressions.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/test_suppressions.MYI to /dev/shm/var/2/mysqld.1/data/mtr/test_suppressions.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/test_suppressions.MYD to /dev/shm/var/2/mysqld.1/data/mtr/test_suppressions.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/ts_insert.TRN to /dev/shm/var/2/mysqld.1/data/mtr/ts_insert.TRN

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/test_suppressions.TRG to /dev/shm/var/2/mysqld.1/data/mtr/test_suppressions.TRG

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/global_suppressions.frm to /dev/shm/var/2/mysqld.1/data/mtr/global_suppressions.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/global_suppressions.MYI to /dev/shm/var/2/mysqld.1/data/mtr/global_suppressions.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/global_suppressions.MYD to /dev/shm/var/2/mysqld.1/data/mtr/global_suppressions.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/gs_insert.TRN to /dev/shm/var/2/mysqld.1/data/mtr/gs_insert.TRN

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mtr/global_suppressions.TRG to /dev/shm/var/2/mysqld.1/data/mtr/global_suppressions.TRG

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/db.frm to /dev/shm/var/2/mysqld.1/data/mysql/db.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/db.MYI to /dev/shm/var/2/mysqld.1/data/mysql/db.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/db.MYD to /dev/shm/var/2/mysqld.1/data/mysql/db.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/host.frm to /dev/shm/var/2/mysqld.1/data/mysql/host.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/host.MYI to /dev/shm/var/2/mysqld.1/data/mysql/host.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/host.MYD to /dev/shm/var/2/mysqld.1/data/mysql/host.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/user.frm to /dev/shm/var/2/mysqld.1/data/mysql/user.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/user.MYI to /dev/shm/var/2/mysqld.1/data/mysql/user.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/user.MYD to /dev/shm/var/2/mysqld.1/data/mysql/user.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/roles_mapping.frm to /dev/shm/var/2/mysqld.1/data/mysql/roles_mapping.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/roles_mapping.MYI to /dev/shm/var/2/mysqld.1/data/mysql/roles_mapping.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/roles_mapping.MYD to /dev/shm/var/2/mysqld.1/data/mysql/roles_mapping.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/func.frm to /dev/shm/var/2/mysqld.1/data/mysql/func.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/func.MYI to /dev/shm/var/2/mysqld.1/data/mysql/func.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/func.MYD to /dev/shm/var/2/mysqld.1/data/mysql/func.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/plugin.frm to /dev/shm/var/2/mysqld.1/data/mysql/plugin.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/plugin.MYI to /dev/shm/var/2/mysqld.1/data/mysql/plugin.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/plugin.MYD to /dev/shm/var/2/mysqld.1/data/mysql/plugin.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/servers.frm to /dev/shm/var/2/mysqld.1/data/mysql/servers.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/servers.MYI to /dev/shm/var/2/mysqld.1/data/mysql/servers.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/servers.MYD to /dev/shm/var/2/mysqld.1/data/mysql/servers.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/tables_priv.frm to /dev/shm/var/2/mysqld.1/data/mysql/tables_priv.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/tables_priv.MYI to /dev/shm/var/2/mysqld.1/data/mysql/tables_priv.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/tables_priv.MYD to /dev/shm/var/2/mysqld.1/data/mysql/tables_priv.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/columns_priv.frm to /dev/shm/var/2/mysqld.1/data/mysql/columns_priv.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/columns_priv.MYI to /dev/shm/var/2/mysqld.1/data/mysql/columns_priv.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/columns_priv.MYD to /dev/shm/var/2/mysqld.1/data/mysql/columns_priv.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_topic.frm to /dev/shm/var/2/mysqld.1/data/mysql/help_topic.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_topic.MYI to /dev/shm/var/2/mysqld.1/data/mysql/help_topic.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_topic.MYD to /dev/shm/var/2/mysqld.1/data/mysql/help_topic.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_category.frm to /dev/shm/var/2/mysqld.1/data/mysql/help_category.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_category.MYI to /dev/shm/var/2/mysqld.1/data/mysql/help_category.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_category.MYD to /dev/shm/var/2/mysqld.1/data/mysql/help_category.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_relation.frm to /dev/shm/var/2/mysqld.1/data/mysql/help_relation.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_relation.MYI to /dev/shm/var/2/mysqld.1/data/mysql/help_relation.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_relation.MYD to /dev/shm/var/2/mysqld.1/data/mysql/help_relation.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_keyword.frm to /dev/shm/var/2/mysqld.1/data/mysql/help_keyword.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_keyword.MYI to /dev/shm/var/2/mysqld.1/data/mysql/help_keyword.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/help_keyword.MYD to /dev/shm/var/2/mysqld.1/data/mysql/help_keyword.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_name.frm to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_name.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_name.MYI to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_name.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_name.MYD to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_name.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone.frm to /dev/shm/var/2/mysqld.1/data/mysql/time_zone.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone.MYI to /dev/shm/var/2/mysqld.1/data/mysql/time_zone.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone.MYD to /dev/shm/var/2/mysqld.1/data/mysql/time_zone.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition.frm to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition.MYI to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition.MYD to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition_type.frm to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition_type.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition_type.MYI to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition_type.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_transition_type.MYD to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_transition_type.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_leap_second.frm to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_leap_second.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_leap_second.MYI to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_leap_second.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/time_zone_leap_second.MYD to /dev/shm/var/2/mysqld.1/data/mysql/time_zone_leap_second.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proc.frm to /dev/shm/var/2/mysqld.1/data/mysql/proc.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proc.MYI to /dev/shm/var/2/mysqld.1/data/mysql/proc.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proc.MYD to /dev/shm/var/2/mysqld.1/data/mysql/proc.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/procs_priv.frm to /dev/shm/var/2/mysqld.1/data/mysql/procs_priv.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/procs_priv.MYI to /dev/shm/var/2/mysqld.1/data/mysql/procs_priv.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/procs_priv.MYD to /dev/shm/var/2/mysqld.1/data/mysql/procs_priv.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/general_log.frm to /dev/shm/var/2/mysqld.1/data/mysql/general_log.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/general_log.CSM to /dev/shm/var/2/mysqld.1/data/mysql/general_log.CSM

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/general_log.CSV to /dev/shm/var/2/mysqld.1/data/mysql/general_log.CSV

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/slow_log.frm to /dev/shm/var/2/mysqld.1/data/mysql/slow_log.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/slow_log.CSM to /dev/shm/var/2/mysqld.1/data/mysql/slow_log.CSM

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/slow_log.CSV to /dev/shm/var/2/mysqld.1/data/mysql/slow_log.CSV

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/event.frm to /dev/shm/var/2/mysqld.1/data/mysql/event.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/event.MYI to /dev/shm/var/2/mysqld.1/data/mysql/event.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/event.MYD to /dev/shm/var/2/mysqld.1/data/mysql/event.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/innodb_table_stats.frm to /dev/shm/var/2/mysqld.1/data/mysql/innodb_table_stats.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/innodb_index_stats.frm to /dev/shm/var/2/mysqld.1/data/mysql/innodb_index_stats.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/transaction_registry.frm to /dev/shm/var/2/mysqld.1/data/mysql/transaction_registry.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proxies_priv.frm to /dev/shm/var/2/mysqld.1/data/mysql/proxies_priv.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proxies_priv.MYI to /dev/shm/var/2/mysqld.1/data/mysql/proxies_priv.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/proxies_priv.MYD to /dev/shm/var/2/mysqld.1/data/mysql/proxies_priv.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/table_stats.frm to /dev/shm/var/2/mysqld.1/data/mysql/table_stats.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/table_stats.MYI to /dev/shm/var/2/mysqld.1/data/mysql/table_stats.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/table_stats.MYD to /dev/shm/var/2/mysqld.1/data/mysql/table_stats.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/column_stats.frm to /dev/shm/var/2/mysqld.1/data/mysql/column_stats.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/column_stats.MYI to /dev/shm/var/2/mysqld.1/data/mysql/column_stats.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/column_stats.MYD to /dev/shm/var/2/mysqld.1/data/mysql/column_stats.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/index_stats.frm to /dev/shm/var/2/mysqld.1/data/mysql/index_stats.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/index_stats.MYI to /dev/shm/var/2/mysqld.1/data/mysql/index_stats.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/index_stats.MYD to /dev/shm/var/2/mysqld.1/data/mysql/index_stats.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/gtid_slave_pos.MYI to /dev/shm/var/2/mysqld.1/data/mysql/gtid_slave_pos.MYI

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/gtid_slave_pos.MYD to /dev/shm/var/2/mysqld.1/data/mysql/gtid_slave_pos.MYD

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/gtid_slave_pos.frm to /dev/shm/var/2/mysqld.1/data/mysql/gtid_slave_pos.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/innodb_table_stats.ibd to /dev/shm/var/2/mysqld.1/data/mysql/innodb_table_stats.ibd

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/innodb_index_stats.ibd to /dev/shm/var/2/mysqld.1/data/mysql/innodb_index_stats.ibd

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./mysql/transaction_registry.ibd to /dev/shm/var/2/mysqld.1/data/mysql/transaction_registry.ibd

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./test/db.opt to /dev/shm/var/2/mysqld.1/data/test/db.opt

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./test/t.frm to /dev/shm/var/2/mysqld.1/data/test/t.frm

180620 09:39:01 [01]        ...done

180620 09:39:01 [01] Copying ./test/t.ibd to /dev/shm/var/2/mysqld.1/data/test/t.ibd

180620 09:39:01 [01]        ...done

180620 09:39:01 completed OK!

--- /usr/share/mysql-test/suite/mariabackup/absolute_ibdata_paths.result	2018-06-20 06:43:20.000000000 +0000

+++ /dev/shm/var/2/log/absolute_ibdata_paths.reject	2018-06-20 09:39:02.290179106 +0000

@@ -6,5 +6,4 @@

 # restart server

 SELECT * from t;

 i

-1

 DROP TABLE t;

mysqltest: Result length mismatch