$ ./mtr main.mtr_unit_tests
Logging: /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/mysql-test-run.pl main.mtr_unit_tests
MySQL Version 8.0.26
Path length (118) is longer than maximum supported length (108) and will be truncated at /usr/lib64/perl5/5.34/x86_64-linux/Socket.pm line 880.
Too long tmpdir path '/var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/mysql-test/var/tmp' creating a shorter one
- Using tmpdir: '/tmp/4CEIPGCueI'
Checking supported features
Using 'all' suites
Collecting tests
Removing old var directory
Creating var directory '/var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/mysql-test/var'
Installing system database
Using parallel: 1
==============================================================================
TEST NAME RESULT TIME (ms) COMMENT
------------------------------------------------------------------------------
[ 50%] main.mtr_unit_tests [ fail ]
Test ended at 2021-11-14 04:34:37
CURRENT_TEST: main.mtr_unit_tests
mysqltest: At line 12: Command "perl $MYSQL_TEST_DIR/lib/t/testMyConfigFactory.t 2>&1" failed.
Output from before failure:
ok 1 - use My::ConfigFactory;
you must pass 'worker' at /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/lib/t/testMyConfigFactory.t line 65.
1..1
# Looks like your test exited with 25 just after 1.
exec of 'perl /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/lib/t/testMyConfigFactory.t 2>&1' failed, error: 6400, status: 25, errno: 0.
The result from queries just before the failure was:
ok 1 - use My::SafeProcess::Base;
ok 2 - safe_fork
ok 3 # skip forkbomb
1..3
ok 1 - use My::File::Path;
ok 2 - Check subdir is created
ok 3 - Check test_todir is created
ok 4 - Check 'test1' is created
ok 5 - Check 'test2' is created
ok 6 - Check 'test3' is created
ok 7 - Check testdir is gone
ok 8 - Check test_todir is gone
1..8
ok 1 - use My::Find;
========================================
mysqld_exe found
========================================
mysql_exe found
========================================
charset_dir found
========================================
1..1
ok 1 - use My::Options;
test0
: ['--binlog-format=row', '--binlog-format=ms']
: ['--binlog-format=row', '--binlog-format=statement']
: ['--binlog-format=statement']
ok 2
SET GLOBAL binlog_format=statement
test1
: ['--binlog-format=row', '--binlog-format=statement']
: ['--binlog-format=row', '--binlog-format=mixed']
: ['--binlog-format=mixed']
ok 3
SET GLOBAL binlog_format=mixed
test2
: ['--binlog-format=row', '--binlog-format=mixed']
: ['--binlog-format=row', '--binlog-format=statement']
: ['--binlog-format=statement']
ok 4
SET GLOBAL binlog_format=statement
test3
: ['--binlog-format=mixed', '--binlog-format=row']
: ['--binlog-format=statement', '--binlog-format=row']
: ['']
ok 5
test4
: ['--binlog-format=row']
: ['']
: ['--binlog-format=default']
ok 6
SET GLOBAL binlog_format=default
test5
: ['']
: ['--binlog-format=row']
: ['--binlog-format=row']
ok 7
SET GLOBAL binlog_format=row
test6
: ['']
: ['max_binlog_size=1']
: ['--max_binlog_size=1']
ok 8
SET GLOBAL max_binlog_size=1
test7
: ['max_binlog_size=1']
: ['max_binlog_size=1']
: ['']
ok 9
test8
: ['max_binlog_size=1']
: ['']
: ['--max_binlog_size=default']
ok 10
SET GLOBAL max_binlog_size=default
test9
: ['']
: ['max_binlog_size=1', '--binlog-format=row']
: ['--max_binlog_size=1', '--binlog-format=row']
ok 11
SET GLOBAL binlog_format=row; SET GLOBAL max_binlog_size=1
test10
: ['--binlog-format=statement']
: ['max_binlog_size=1', '--binlog-format=row']
: ['--max_binlog_size=1', '--binlog-format=row']
ok 12
SET GLOBAL binlog_format=row; SET GLOBAL max_binlog_size=1
test11
: ['--binlog-format=statement']
: ['max_binlog_size=1', '--binlog-format=statement']
: ['--max_binlog_size=1']
ok 13
SET GLOBAL max_binlog_size=1
test12
: ['--binlog-format=statement']
: ['max_binlog_size=1', '--binlog-format=statement']
: ['--max_binlog_size=1']
ok 14
SET GLOBAL max_binlog_size=1
test13
: ['--binlog-format=statement']
: ['--relay-log=/path/to/a/relay-log', '--binlog-format=row']
: ['--relay-log=/path/to/a/relay-log', '--binlog-format=row']
ok 15
SET GLOBAL binlog_format=row; SET GLOBAL relay_log=/path/to/a/relay-log
test14
: ['--binlog-format=statement']
: ['--relay-log=/path/to/a/relay-log', 'max_binlog_size=1']
: ['--max_binlog_size=1', '--relay-log=/path/to/a/relay-log', '--binlog-format=default']
ok 16
SET GLOBAL binlog_format=default; SET GLOBAL max_binlog_size=1; SET GLOBAL relay_log=/path/to/a/relay-log
test15
: ['--slow-query-log=0']
: ['--slow-query-log']
: ['--slow-query-log']
ok 17
SET GLOBAL slow_query_log=
1..17
ok 1 - use My::File::Path;
ok 2 - Check subdir is created
ok 3 - Check testdir is gone
ok 4 - Check subdir is created
ok 5 - Check one dir was chmoded
ok 6 - Check testdir is gone
ok 7 - Check subdir is created
ok 8 - Check one file was chmoded
ok 9 - Check testdir is gone
1..9
ok 1 - use My::SafeProcess;
ok 2 - check exit_status, 32
ok 3 - concurrent, 16
ok 4 - check stdout
ok 5 - check stderr
ok 6 - check stdout
ok 7 - check stderr
ok 8 - check stdout
ok 9 - check stderr
ok 10 - check stdout
ok 11 - check stderr
Hello stdout
ok 12 - check stdout
ok 13 - check stderr
Hello stderr
ok 14 - check stdout
ok 15 - check stderr
ok 16 - check stdout
ok 17 - check stderr
ok 18 - check stdout
ok 19 - check stderr
1..19
ok 1 - use My::Config;
ok 2 - config is a My::Config
[mysqld]
option1=values2
option2=value4
option4
basedir=thebasedir
[mysqld_1]
[mysqld_2]
[mysqld.9]
[client]
socket=asocketpath
ok 3 - group mysqld_2 exists
ok 4 - group mysqld_1 exists
ok 5 - group mysqld.9 exists
ok 6 - group mysqld.9 has suffix .9
ok 7 - group mysqld exists
ok 8 - group client exists
ok 9 - group mysqld_3 does not exist
ok 10 - options in [mysqld] is 4
ok 11 - group [nonexist] is not defined
ok 12 - 5 groups
ok 13 - checking groups 1
ok 14 - checking groups 2
ok 15 - checking groups 3
ok 16 - checking groups 4
ok 17 - checking groups 5
ok 18 - 4 groups like mysqld
ok 19 - checking like("mysqld") 1
ok 20 - checking like("mysqld") 2
ok 21 - checking like("mysqld") 3
ok 22 - checking like("mysqld") 4
ok 23 - checking like("not")
ok 24 - first_like
ok 25 - mysqld_option4 exists, does not have a value
ok 26 - mysqld_option4 exists
ok 27 - mysqld_option2 exists
ok 28 - mysqld_option5 does not exists
ok 29 - config2 is a My::Config
ok 30 - test.cnf is equal to test2.cnf
ok 31 - config3 is a My::Config
[mysqld]
option1=values3
option2=value4
option4
basedir=anotherbasedir
[client]
socket=asocketpath
ok 32 - mysqld_basedir has been overriden by value in test_include.cnf
ok 33 - mysqld_option1 has been overriden by value in test_include.cnf
ok 34 - mysqld_option2 is from included file
ok 35 - client.socket is from included file
ok 36 - mysqld_option4 exists, does not have a value
[mysqld]
option1=values3
option2=value4
option4
basedir=anotherbasedir
[client]
socket=asocketpath
1..36
ok 1 - use My::ConfigFactory;
you must pass 'worker' at /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/lib/t/testMyConfigFactory.t line 65.
1..1
# Looks like your test exited with 25 just after 1.
exec of 'perl /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/lib/t/testMyConfigFactory.t 2>&1' failed, error: 6400, status: 25, errno: 0.
safe_process[7881]: Child process: 7882, exit: 1
Server [mysqld.1 - pid: 7829, winpid: 7829] log:
Server log from this test:
----------SERVER LOG START-----------
2021-11-14T03:34:36.152441Z 0 [Warning] [MY-010139] [Server] Changed limits: max_open_files: 1024 (requested 8161)
2021-11-14T03:34:36.152445Z 0 [Warning] [MY-010142] [Server] Changed limits: table_open_cache: 431 (requested 4000)
2021-11-14T03:34:36.302344Z 0 [Warning] [MY-010099] [Server] Insecure configuration for --secure-file-priv: Data directory is accessible through --secure-file-priv. Consider choosing a different directory.
2021-11-14T03:34:36.302350Z 0 [Warning] [MY-010101] [Server] Insecure configuration for --secure-file-priv: Location is accessible to all OS users. Consider choosing a different directory.
2021-11-14T03:34:36.302401Z 0 [Note] [MY-010949] [Server] Basedir set to /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/.
2021-11-14T03:34:36.302408Z 0 [System] [MY-010116] [Server] /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/runtime_output_directory/mysqld (mysqld 8.0.26-16) starting as process 7830
2021-11-14T03:34:36.303529Z 0 [Warning] [MY-013711] [Server] Manifest file '/var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/runtime_output_directory/mysqld.my' is not read-only. For better security, please make sure that the file is read-only.
2021-11-14T03:34:36.409013Z 0 [Warning] [MY-010075] [Server] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: ca5b14cc-44fb-11ec-9c79-00155d000a01.
2021-11-14T03:34:36.410518Z 0 [Note] [MY-012366] [InnoDB] Using Linux native AIO
2021-11-14T03:34:36.410689Z 0 [Note] [MY-010747] [Server] Plugin 'FEDERATED' is disabled.
2021-11-14T03:34:36.411591Z 1 [System] [MY-013576] [InnoDB] InnoDB initialization has started.
2021-11-14T03:34:36.411633Z 1 [Note] [MY-013546] [InnoDB] Atomic write enabled
2021-11-14T03:34:36.411677Z 1 [Note] [MY-012932] [InnoDB] PUNCH HOLE support available
2021-11-14T03:34:36.411709Z 1 [Note] [MY-012944] [InnoDB] Uses event mutexes
2021-11-14T03:34:36.411744Z 1 [Note] [MY-012945] [InnoDB] GCC builtin __atomic_thread_fence() is used for memory barrier
2021-11-14T03:34:36.411780Z 1 [Note] [MY-012948] [InnoDB] Compressed tables use zlib 1.2.11
2021-11-14T03:34:36.413311Z 1 [Note] [MY-013251] [InnoDB] Number of pools: 1
2021-11-14T03:34:36.413475Z 1 [Note] [MY-012951] [InnoDB] Using CPU crc32 instructions
2021-11-14T03:34:36.413717Z 1 [Note] [MY-012203] [InnoDB] Directories to scan './'
2021-11-14T03:34:36.413787Z 1 [Note] [MY-012204] [InnoDB] Scanning './'
2021-11-14T03:34:36.415214Z 1 [Note] [MY-012208] [InnoDB] Completed space ID check of 8 files.
2021-11-14T03:34:36.415696Z 1 [Note] [MY-012955] [InnoDB] Initializing buffer pool, total size = 24.000000M, instances = 1, chunk size =24.000000M
2021-11-14T03:34:36.416544Z 1 [Note] [MY-012957] [InnoDB] Completed initialization of buffer pool
2021-11-14T03:34:36.417864Z 0 [Note] [MY-011952] [InnoDB] If the mysqld execution user is authorized, page cleaner and LRU manager thread priority can be changed. See the man page of setpriority().
2021-11-14T03:34:36.418081Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_0.dblwr' for doublewrite
2021-11-14T03:34:36.418306Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_1.dblwr' for doublewrite
2021-11-14T03:34:36.448818Z 1 [Note] [MY-013566] [InnoDB] Double write buffer files: 2
2021-11-14T03:34:36.448917Z 1 [Note] [MY-013565] [InnoDB] Double write buffer pages per instance: 4
2021-11-14T03:34:36.448986Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_0.dblwr' for doublewrite
2021-11-14T03:34:36.449046Z 1 [Note] [MY-013532] [InnoDB] Using './#ib_16384_1.dblwr' for doublewrite
2021-11-14T03:34:36.449343Z 1 [Note] [MY-013086] [InnoDB] Starting to parse redo log at lsn = 18015773, whereas checkpoint_lsn = 18016132 and start_lsn = 18015744
2021-11-14T03:34:36.452040Z 1 [Note] [MY-013083] [InnoDB] Log background threads are being started...
2021-11-14T03:34:36.452365Z 1 [Note] [MY-012532] [InnoDB] Applying a batch of 0 redo log records ...
2021-11-14T03:34:36.452434Z 1 [Note] [MY-012535] [InnoDB] Apply batch completed!
2021-11-14T03:34:36.452625Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_001'.
2021-11-14T03:34:36.452785Z 1 [Note] [MY-013252] [InnoDB] Using undo tablespace './undo_002'.
2021-11-14T03:34:36.453031Z 1 [Note] [MY-012910] [InnoDB] Opened 2 existing undo tablespaces.
2021-11-14T03:34:36.453114Z 1 [Note] [MY-011980] [InnoDB] GTID recovery trx_no: 1080
2021-11-14T03:34:36.456890Z 1 [Note] [MY-011825] [InnoDB] Parallel initialization of rseg complete
2021-11-14T03:34:36.457016Z 1 [Note] [MY-011825] [InnoDB] Time taken to initialize rseg using 4 thread: 3899 ms.
2021-11-14T03:34:36.457146Z 1 [Note] [MY-012923] [InnoDB] Creating shared tablespace for temporary tables
2021-11-14T03:34:36.457262Z 1 [Note] [MY-012265] [InnoDB] Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2021-11-14T03:34:36.465156Z 1 [Note] [MY-012266] [InnoDB] File './ibtmp1' size is now 12 MB.
2021-11-14T03:34:36.465485Z 1 [Note] [MY-013627] [InnoDB] Scanning temp tablespace dir:'./#innodb_temp/'
2021-11-14T03:34:36.472316Z 1 [Note] [MY-013018] [InnoDB] Created 128 and tracked 128 new rollback segment(s) in the temporary tablespace. 128 are now active.
2021-11-14T03:34:36.472622Z 1 [Note] [MY-012976] [InnoDB] Percona XtraDB (http://www.percona.com) 8.0.26-16 started; log sequence number 18016142
2021-11-14T03:34:36.472878Z 1 [System] [MY-013577] [InnoDB] InnoDB initialization has ended.
2021-11-14T03:34:36.476156Z 1 [Note] [MY-011089] [Server] Data dictionary restarting version '80023'.
2021-11-14T03:34:36.517324Z 1 [Note] [MY-012357] [InnoDB] Reading DD tablespace files
2021-11-14T03:34:36.518065Z 1 [Note] [MY-012356] [InnoDB] Scanned 10 tablespaces. Validated 10.
2021-11-14T03:34:36.524284Z 1 [Note] [MY-010006] [Server] Using data dictionary with version '80023'.
2021-11-14T03:34:36.528734Z 0 [Note] [MY-011332] [Server] Plugin mysqlx reported: 'IPv6 is available'
2021-11-14T03:34:36.529499Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. bind-address: '::' port: 13020'
2021-11-14T03:34:36.529607Z 0 [Note] [MY-011323] [Server] Plugin mysqlx reported: 'X Plugin ready for connections. socket: '/tmp/4CEIPGCueI/mysqlx.1.sock''
2021-11-14T03:34:36.571493Z 0 [Note] [MY-010902] [Server] Thread priority attribute setting in Resource Group SQL shall be ignored due to unsupported platform or insufficient privilege.
2021-11-14T03:34:36.580345Z 0 [Note] [MY-012487] [InnoDB] DDL log recovery : begin
2021-11-14T03:34:36.580570Z 0 [Note] [MY-012488] [InnoDB] DDL log recovery : end
2021-11-14T03:34:36.580829Z 0 [Note] [MY-011946] [InnoDB] Loading buffer pool(s) from /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/mysql-test/var/mysqld.1/data/ib_buffer_pool
2021-11-14T03:34:36.582099Z 0 [Note] [MY-011946] [InnoDB] Buffer pool(s) load completed at 211114 6:34:36
2021-11-14T03:34:36.584191Z 0 [Note] [MY-012922] [InnoDB] Waiting for purge to start
2021-11-14T03:34:36.638026Z 0 [Note] [MY-010303] [Server] Skipping generation of SSL certificates as options related to SSL are specified.
2021-11-14T03:34:36.638216Z 0 [Warning] [MY-013746] [Server] A deprecated TLS version TLSv1 is enabled for channel mysql_main
2021-11-14T03:34:36.638329Z 0 [Warning] [MY-013746] [Server] A deprecated TLS version TLSv1.1 is enabled for channel mysql_main
2021-11-14T03:34:36.640266Z 0 [Warning] [MY-010068] [Server] CA certificate /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/mysql/mysql-test/std_data/cacert.pem is self signed.
2021-11-14T03:34:36.640402Z 0 [System] [MY-013602] [Server] Channel mysql_main configured to support TLS. Encrypted connections are now supported for this channel.
2021-11-14T03:34:36.640545Z 0 [Note] [MY-010310] [Server] Skipping generation of RSA key pair as --sha256_password_auto_generate_rsa_keys is set to OFF.
2021-11-14T03:34:36.640664Z 0 [Note] [MY-010310] [Server] Skipping generation of RSA key pair as --caching_sha2_password_auto_generate_rsa_keys is set to OFF.
2021-11-14T03:34:36.640912Z 0 [Note] [MY-010252] [Server] Server hostname (bind-address): '*'; port: 13000
2021-11-14T03:34:36.641086Z 0 [Note] [MY-010253] [Server] IPv6 is available.
2021-11-14T03:34:36.641213Z 0 [Note] [MY-010264] [Server] - '::' resolves to '::';
2021-11-14T03:34:36.641339Z 0 [Note] [MY-010251] [Server] Server socket created on IP: '::'.
2021-11-14T03:34:36.642019Z 0 [Warning] [MY-011810] [Server] Insecure configuration for --pid-file: Location '/var/tmp' in the path is accessible to all OS users. Consider choosing a different directory.
2021-11-14T03:34:36.643382Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-mysqlx-ssl=DISABLED'.
2021-11-14T03:34:36.643528Z 0 [Warning] [MY-000067] [Server] unknown variable 'loose-debug-sync-timeout=600'.
2021-11-14T03:34:36.651584Z 0 [Note] [MY-011025] [Repl] Failed to start slave threads for channel ''.
2021-11-14T03:34:36.652757Z 5 [Note] [MY-010051] [Server] Event Scheduler: scheduler thread started with id 5
2021-11-14T03:34:36.652969Z 0 [Note] [MY-013667] [Server] Error-log destination "stderr" is not a file. Can not restore error log messages from previous run.
2021-11-14T03:34:36.653194Z 0 [Note] [MY-011240] [Server] Plugin mysqlx reported: 'Using SSL configuration from MySQL Server'
2021-11-14T03:34:36.653540Z 0 [Note] [MY-011243] [Server] Plugin mysqlx reported: 'Using OpenSSL for TLS connections'
2021-11-14T03:34:36.653640Z 0 [System] [MY-011323] [Server] X Plugin ready for connections. Bind-address: '::' port: 13020, socket: /tmp/4CEIPGCueI/mysqlx.1.sock
2021-11-14T03:34:36.653737Z 0 [System] [MY-010931] [Server] /var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/runtime_output_directory/mysqld: ready for connections. Version: '8.0.26-16' socket: '/tmp/4CEIPGCueI/mysqld.1.sock' port: 13000 Gentoo Linux percona-server-8.0.26.16.
----------SERVER LOG END-------------
- the logfile can be found in '/var/tmp/portage/dev-db/percona-server-8.0.26.16/work/percona-server-8.0.26.16_build/mysql-test/var/log/main.mtr_unit_tests/mtr_unit_tests.log'
[100%] shutdown_report [ pass ]
------------------------------------------------------------------------------
The servers were restarted 0 times
The servers were reinitialized 0 times
Spent 0.000 of 8 seconds executing testcases
Completed: Failed 1/2 tests, 50.00% were successful.
Failing test(s): main.mtr_unit_tests
Bug
Medium
