[MDEV-24821] Encryption loading fails while running the suite - Jira

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Critical
Resolution: Cannot Reproduce
Affects Version/s: 10.3(EOL), 10.4(EOL), 10.5, 10.6, 10.8(EOL), 10.9(EOL), 10.10(EOL), 10.11, 11.0(EOL), 11.1(EOL), 11.2(EOL)
Fix Version/s: N/A
Component/s: Platform FreeBSD, Tests
Labels:
- affects-tests

Description

In 10.6, encryption suite test case fails with Test requires plugin

encryption.innodb-compressed-blob '16k,cbc,crc32,innodb' [ skipped ]  Test requires file_key_management plugin

line

2021-02-09 18:35:21 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available

2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' init function returned error.

2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.

^ Found warnings in /home/thiru/mariarepo/10.6/10.6-test/bld_sample-debug/mysql-test/var/log/mysqld.1.err

ok

encryption.innodb-checksum-algorithm '4k,cbc,innodb' w2 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '8k,cbc,innodb' w4 [ skipped ]  Test requires file_key_management plugin

encryption.innochecksum '8k,cbc,innodb,strict_crc32' w6 [ skipped ]  Test requires file_key_management plugin

encryption.innochecksum '16k,cbc,innodb,strict_full_crc32' w4 [ skipped ]  Test requires file_key_management plugin

encryption.innochecksum '4k,cbc,innodb,strict_full_crc32' w6 [ skipped ]  Test requires file_key_management plugin

It fails only in 10.6. I debugged further to understand what went wrong.

mtr tool doesn't start the server with file_key_management.so. I used the verbose command to find out what went wrong.

> Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/have_innodb.combinations

> Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/suite/encryption/include/have_file_key_management_plugin.combinations

> Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/innodb_page_size_small.combinations

> Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/innodb_checksum_algorithm.combinations

worker[1] > mysqld_start:  [' --innodb-checksum-algorithm=crc32', '--innodb-page-size=16K', '--skip-innodb-read-only-compressed', '--innodb', '--innodb-cmpmem', '--innodb-cmp-per-index', '--innodb-trx', '--innodb-locks', '--innodb-lock-waits', '--innodb-metrics', '--innodb-buffer-pool-stats', '--innodb-buffer-page', '--innodb-buffer-page-lru', '--innodb-sys-columns', '--innodb-sys-fields', '--innodb-sys-foreign', '--innodb-sys-foreign-cols', '--innodb-sys-indexes', '--innodb-sys-tables', '--innodb-sys-virtual ']

Attachments

Activity

Ascending order - Click to sort in descending order

Thirunarayanan Balathandayuthapani created issue - 2021-02-09 13:08

Thirunarayanan Balathandayuthapani made changes - 2021-02-09 14:18

Field	Original Value	New Value
Description	In 10.6, encryption suite test case fails with {{Test requires plugin}} {code} encryption.innodb-compressed-blob '16k,cbc,crc32,innodb' [ skipped ] Test requires file_key_management plugin line 2021-02-09 18:35:21 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' init function returned error. 2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /home/thiru/mariarepo/10.6/10.6-test/bld_sample-debug/mysql-test/var/log/mysqld.1.err ok encryption.innodb-checksum-algorithm '4k,cbc,innodb' w2 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '8k,cbc,innodb' w4 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '8k,cbc,innodb,strict_crc32' w6 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '16k,cbc,innodb,strict_full_crc32' w4 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '4k,cbc,innodb,strict_full_crc32' w6 [ skipped ] Test requires file_key_management plugin {code} It fails only in 10.6	In 10.6, encryption suite test case fails with {{Test requires plugin}} {code} encryption.innodb-compressed-blob '16k,cbc,crc32,innodb' [ skipped ] Test requires file_key_management plugin line 2021-02-09 18:35:21 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' init function returned error. 2021-02-09 18:35:21 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /home/thiru/mariarepo/10.6/10.6-test/bld_sample-debug/mysql-test/var/log/mysqld.1.err ok encryption.innodb-checksum-algorithm '4k,cbc,innodb' w2 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '8k,cbc,innodb' w4 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '8k,cbc,innodb,strict_crc32' w6 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '16k,cbc,innodb,strict_full_crc32' w4 [ skipped ] Test requires file_key_management plugin encryption.innochecksum '4k,cbc,innodb,strict_full_crc32' w6 [ skipped ] Test requires file_key_management plugin {code} It fails only in 10.6. I debugged further to understand what went wrong. mtr tool doesn't start the server with {{file_key_management.so}}. I used the verbose command to find out what went wrong. {code} > Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/have_innodb.combinations > Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/suite/encryption/include/have_file_key_management_plugin.combinations > Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/innodb_page_size_small.combinations > Read combinations file /home/thiru/mariarepo/10.6/10.6-test/mysql-test/include/innodb_checksum_algorithm.combinations worker[1] > mysqld_start: [' --innodb-checksum-algorithm=crc32', '--innodb-page-size=16K', '--skip-innodb-read-only-compressed', '--innodb', '--innodb-cmpmem', '--innodb-cmp-per-index', '--innodb-trx', '--innodb-locks', '--innodb-lock-waits', '--innodb-metrics', '--innodb-buffer-pool-stats', '--innodb-buffer-page', '--innodb-buffer-page-lru', '--innodb-sys-columns', '--innodb-sys-fields', '--innodb-sys-foreign', '--innodb-sys-foreign-cols', '--innodb-sys-indexes', '--innodb-sys-tables', '--innodb-sys-virtual '] {code}

Thirunarayanan Balathandayuthapani made changes - 2021-02-09 14:19

Component/s

Storage Engine - InnoDB [ 10129 ]

Thirunarayanan Balathandayuthapani made changes - 2021-02-09 14:19

Labels

affects-tests

Thirunarayanan Balathandayuthapani made changes - 2021-02-09 14:19

Component/s		Tests [ 10800 ]
Component/s	Encryption [ 11200 ]

Thirunarayanan Balathandayuthapani made changes - 2021-02-09 16:53

Assignee

Sergei Golubchik [ serg ]

Marko Mäkelä added a comment - 2021-03-11 07:45

With the ~~MDEV-25105~~ changes, I am observing the following for

./mtr --parallel=14 encryption.innodb-checksum-algorithm

encryption.innodb-checksum-algorithm '16k,cbc,innodb,strict_full_crc32' w8 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '8k,cbc,innodb,strict_full_crc32' w14 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '4k,cbc,innodb,strict_crc32' w9 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '4k,cbc,innodb,strict_full_crc32' w13 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '16k,cbc,innodb,strict_crc32' w10 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '8k,cbc,innodb,strict_crc32' w7 [ skipped ]  Test requires file_key_management plugin

encryption.innodb-checksum-algorithm '32k,ctr,innodb,strict_full_crc32' w3 [ pass ]   1580

encryption.innodb-checksum-algorithm '64k,ctr,innodb,strict_full_crc32' w2 [ pass ]   1585

encryption.innodb-checksum-algorithm '32k,cbc,innodb,strict_full_crc32' w12 [ pass ]   1580

encryption.innodb-checksum-algorithm '64k,cbc,innodb,strict_full_crc32' w6 [ pass ]   1582

encryption.innodb-checksum-algorithm '64k,ctr,innodb,strict_crc32' w5 [ pass ]   4712

encryption.innodb-checksum-algorithm '32k,ctr,innodb,strict_crc32' w11 [ pass ]   5694

encryption.innodb-checksum-algorithm '32k,cbc,innodb,strict_crc32' w1 [ pass ]   5681

encryption.innodb-checksum-algorithm '64k,cbc,innodb,strict_crc32' w4 [ pass ]   5738

It appears that with larger page sizes, the problem is less likely to occur. We started to see this in 10.6, possibly after something was cleaned up in InnoDB so that it would start up or shut down faster.

Marko Mäkelä added a comment - 2021-03-11 07:45 With the MDEV-25105 changes, I am observing the following for ./mtr --parallel=14 encryption.innodb-checksum-algorithm encryption.innodb-checksum-algorithm '16k,cbc,innodb,strict_full_crc32' w8 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '8k,cbc,innodb,strict_full_crc32' w14 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '4k,cbc,innodb,strict_crc32' w9 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '4k,cbc,innodb,strict_full_crc32' w13 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '16k,cbc,innodb,strict_crc32' w10 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '8k,cbc,innodb,strict_crc32' w7 [ skipped ] Test requires file_key_management plugin encryption.innodb-checksum-algorithm '32k,ctr,innodb,strict_full_crc32' w3 [ pass ] 1580 encryption.innodb-checksum-algorithm '64k,ctr,innodb,strict_full_crc32' w2 [ pass ] 1585 encryption.innodb-checksum-algorithm '32k,cbc,innodb,strict_full_crc32' w12 [ pass ] 1580 encryption.innodb-checksum-algorithm '64k,cbc,innodb,strict_full_crc32' w6 [ pass ] 1582 encryption.innodb-checksum-algorithm '64k,ctr,innodb,strict_crc32' w5 [ pass ] 4712 encryption.innodb-checksum-algorithm '32k,ctr,innodb,strict_crc32' w11 [ pass ] 5694 encryption.innodb-checksum-algorithm '32k,cbc,innodb,strict_crc32' w1 [ pass ] 5681 encryption.innodb-checksum-algorithm '64k,cbc,innodb,strict_crc32' w4 [ pass ] 5738 It appears that with larger page sizes, the problem is less likely to occur. We started to see this in 10.6, possibly after something was cleaned up in InnoDB so that it would start up or shut down faster.

Marko Mäkelä added a comment - 2021-03-17 10:21

I am seeing this also on 10.5 on a remote system that I am using to debug ~~MDEV-25121~~. The mysql-test/var directory is on a SSD with 4KiB block size.

mariadb-10.5.9 with some InnoDB changes
encryption.innodb-compressed-blob '16k,cbc,innodb' w8 [ skipped ] Test requires file_key_management plugin
line
2021-03-17 3:18:02 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available
2021-03-17 3:18:02 0 [ERROR] Plugin 'InnoDB' init function returned error.
2021-03-17 3:18:02 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
^ Found warnings in /home/mariadb/mariadb-10.5.9/build/mysql-test/var/8/log/mysqld.1.err

Marko Mäkelä added a comment - 2021-03-17 10:21 I am seeing this also on 10.5 on a remote system that I am using to debug MDEV-25121 . The mysql-test/var directory is on a SSD with 4KiB block size. mariadb-10.5.9 with some InnoDB changes encryption.innodb-compressed-blob '16k,cbc,innodb' w8 [ skipped ] Test requires file_key_management plugin line 2021-03-17 3:18:02 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2021-03-17 3:18:02 0 [ERROR] Plugin 'InnoDB' init function returned error. 2021-03-17 3:18:02 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /home/mariadb/mariadb-10.5.9/build/mysql-test/var/8/log/mysqld.1.err

Marko Mäkelä made changes - 2021-03-17 10:21

Fix Version/s		10.5 [ 23123 ]
Affects Version/s		10.5 [ 23123 ]

Sergei Golubchik made changes - 2021-05-25 12:51

Fix Version/s

10.6 [ 24028 ]

Marko Mäkelä made changes - 2021-07-02 14:25

Fix Version/s

10.6 [ 24028 ]

Julien Fritsch made changes - 2021-11-02 09:19

Priority

Major [ 3 ]

Critical [ 2 ]

Marko Mäkelä added a comment - 2021-11-18 16:11

I got this on 10.6. It is as if the wrong parameters are being passed to the server for some reason:

10.6 aaef2e1d8c843d1e40b1ce0c5199c3abb3c5da28
worker[14] encryption.encryption_key_corruption
worker[14] [ fail ]
Test ended at 2021-11-18 17:50:27
worker[14]

Server log from this test:
----------SERVER LOG START-----------
$ /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/14/my.cnf --log-output=file --innodb-checksum-algorithm=innodb --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300
2021-11-18 17:50:27 0 [Note] /dev/shm/10.6m/sql/mariadbd (server 10.6.6-MariaDB-debug-log) starting as process 82074 ...
2021-11-18 17:50:27 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32222)
2021-11-18 17:50:27 0 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000)
2021-11-18 17:50:27 0 [Warning] Plugin 'example_key_management' is of maturity level experimental while the server is stable
2021-11-18 17:50:27 0 [Note] Plugin 'partition' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'SEQUENCE' is disabled.
2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: Error while setting value 'innodb' to 'innodb-checksum-algorithm'
2021-11-18 17:50:27 0 [ERROR] Parsing options for plugin 'InnoDB' failed.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'FEEDBACK' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_VIRTUAL' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'user_variables' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'unix_socket' is disabled.
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-pam-debug'
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria'
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria'
2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: unknown option '----file-marker----'
2021-11-18 17:50:27 0 [ERROR] Aborting
----------SERVER LOG END-------------


worker[14] mysql-test-run: *** ERROR: Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/14/my.cnf --log-output=file --innodb-checksum-algorithm=innodb --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300
Error happened at lib/mtr_report.pm line 695.
mtr_report::mtr_error("Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/s"...) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 2743
main::mysql_server_start(undef, My::Test=HASH(0x563f696343b8)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 5413
main::start_servers(My::Test=HASH(0x563f696343b8)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 3849
main::run_testcase(My::Test=HASH(0x563f696343b8), IO::Socket::INET=GLOB(0x563f69f5e150)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 962
main::run_worker(51091, 14) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 475
main::main() called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 347
worker[10] mysql-test-run: WARNING: Process [mysqld.1 - pid: 82192, winpid: 82192] died after mysql-test-run waited 0.13 seconds for /dev/shm/10.6m/mysql-test/var/10/run/mysqld.1.pid to be created.
worker[10] encryption.encryption_key_corruption
worker[10] [ fail ]
Test ended at 2021-11-18 17:50:27
worker[10]

Server log from this test:
----------SERVER LOG START-----------
$ /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/10/my.cnf --log-output=file --innodb-checksum-algorithm=none --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300
2021-11-18 17:50:27 0 [Note] /dev/shm/10.6m/sql/mariadbd (server 10.6.6-MariaDB-debug-log) starting as process 82196 ...
2021-11-18 17:50:27 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32222)
2021-11-18 17:50:27 0 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000)
2021-11-18 17:50:27 0 [Warning] Plugin 'example_key_management' is of maturity level experimental while the server is stable
2021-11-18 17:50:27 0 [Note] Plugin 'partition' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'SEQUENCE' is disabled.
2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: Error while setting value 'none' to 'innodb-checksum-algorithm'
2021-11-18 17:50:27 0 [ERROR] Parsing options for plugin 'InnoDB' failed.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'FEEDBACK' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_VIRTUAL' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'user_variables' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
2021-11-18 17:50:27 0 [Note] Plugin 'unix_socket' is disabled.
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-pam-debug'
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria'
2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria'
2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: unknown option '----file-marker----'
2021-11-18 17:50:27 0 [ERROR] Aborting
----------SERVER LOG END-------------


worker[10] mysql-test-run: *** ERROR: Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/10/my.cnf --log-output=file --innodb-checksum-algorithm=none --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300
Error happened at lib/mtr_report.pm line 695.
mtr_report::mtr_error("Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/s"...) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 2743
main::mysql_server_start(My::Config::Group=HASH(0x563f696d71a0), My::Test=HASH(0x563f69610310)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 5413
main::start_servers(My::Test=HASH(0x563f69610310)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 3849
main::run_testcase(My::Test=HASH(0x563f69610310), IO::Socket::INET=GLOB(0x563f69f5e150)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 962
main::run_worker(51091, 10) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 475
main::main() called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 347

At the end of the test run, these two test failures were reported as follows:

Only  5881  of 5883 completed.

--------------------------------------------------------------------------

The servers were restarted 2067 times

Spent 11573.873 of 533 seconds executing testcases

Completed: All 5622 tests were successful.

198 tests were skipped, 177 by the test itself.

mysql-test-run: *** ERROR: Not all tests completed (only 5881 of 5883)

Marko Mäkelä added a comment - 2021-11-18 16:11 I got this on 10.6. It is as if the wrong parameters are being passed to the server for some reason: 10.6 aaef2e1d8c843d1e40b1ce0c5199c3abb3c5da28 worker[14] encryption.encryption_key_corruption worker[14] [ fail ] Test ended at 2021-11-18 17:50:27 worker[14] Server log from this test: ----------SERVER LOG START----------- $ /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/14/my.cnf --log-output=file --innodb-checksum-algorithm=innodb --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300 2021-11-18 17:50:27 0 [Note] /dev/shm/10.6m/sql/mariadbd (server 10.6.6-MariaDB-debug-log) starting as process 82074 ... 2021-11-18 17:50:27 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32222) 2021-11-18 17:50:27 0 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000) 2021-11-18 17:50:27 0 [Warning] Plugin 'example_key_management' is of maturity level experimental while the server is stable 2021-11-18 17:50:27 0 [Note] Plugin 'partition' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'SEQUENCE' is disabled. 2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: Error while setting value 'innodb' to 'innodb-checksum-algorithm' 2021-11-18 17:50:27 0 [ERROR] Parsing options for plugin 'InnoDB' failed. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'FEEDBACK' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_VIRTUAL' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'user_variables' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'unix_socket' is disabled. 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-pam-debug' 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria' 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria' 2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: unknown option '----file-marker----' 2021-11-18 17:50:27 0 [ERROR] Aborting ----------SERVER LOG END------------- worker[14] mysql-test-run: *** ERROR: Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/14/my.cnf --log-output=file --innodb-checksum-algorithm=innodb --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300 Error happened at lib/mtr_report.pm line 695. mtr_report::mtr_error("Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/s"...) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 2743 main::mysql_server_start(undef, My::Test=HASH(0x563f696343b8)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 5413 main::start_servers(My::Test=HASH(0x563f696343b8)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 3849 main::run_testcase(My::Test=HASH(0x563f696343b8), IO::Socket::INET=GLOB(0x563f69f5e150)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 962 main::run_worker(51091, 14) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 475 main::main() called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 347 worker[10] mysql-test-run: WARNING: Process [mysqld.1 - pid: 82192, winpid: 82192] died after mysql-test-run waited 0.13 seconds for /dev/shm/10.6m/mysql-test/var/10/run/mysqld.1.pid to be created. worker[10] encryption.encryption_key_corruption worker[10] [ fail ] Test ended at 2021-11-18 17:50:27 worker[10] Server log from this test: ----------SERVER LOG START----------- $ /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/10/my.cnf --log-output=file --innodb-checksum-algorithm=none --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300 2021-11-18 17:50:27 0 [Note] /dev/shm/10.6m/sql/mariadbd (server 10.6.6-MariaDB-debug-log) starting as process 82196 ... 2021-11-18 17:50:27 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32222) 2021-11-18 17:50:27 0 [Warning] Changed limits: max_open_files: 1024 max_connections: 151 (was 151) table_cache: 421 (was 2000) 2021-11-18 17:50:27 0 [Warning] Plugin 'example_key_management' is of maturity level experimental while the server is stable 2021-11-18 17:50:27 0 [Note] Plugin 'partition' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'SEQUENCE' is disabled. 2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: Error while setting value 'none' to 'innodb-checksum-algorithm' 2021-11-18 17:50:27 0 [ERROR] Parsing options for plugin 'InnoDB' failed. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'FEEDBACK' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_SYS_VIRTUAL' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'user_variables' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled. 2021-11-18 17:50:27 0 [Note] Plugin 'unix_socket' is disabled. 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-pam-debug' 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria' 2021-11-18 17:50:27 0 [Warning] /dev/shm/10.6m/sql/mariadbd: unknown option '--loose-aria' 2021-11-18 17:50:27 0 [ERROR] /dev/shm/10.6m/sql/mariadbd: unknown option '----file-marker----' 2021-11-18 17:50:27 0 [ERROR] Aborting ----------SERVER LOG END------------- worker[10] mysql-test-run: *** ERROR: Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/dev/shm/10.6m/mysql-test/var/10/my.cnf --log-output=file --innodb-checksum-algorithm=none --innodb --innodb-cmpmem --innodb-cmp-per-index --innodb-trx --innodb-locks --innodb-lock-waits --innodb-metrics --innodb-buffer-pool-stats --innodb-buffer-page --innodb-buffer-page-lru --innodb-sys-columns --innodb-sys-fields --innodb-sys-foreign --innodb-sys-foreign-cols --innodb-sys-indexes --innodb-sys-tables --innodb-sys-virtual --plugin-load-add=example_key_management.so --loose-example-key-management --skip-innodb-read-only-compressed --innodb-encrypt-tables=1 --core-file --loose-debug-sync-timeout=300 Error happened at lib/mtr_report.pm line 695. mtr_report::mtr_error("Failed to start mysqld mysqld.1 with command /dev/shm/10.6m/s"...) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 2743 main::mysql_server_start(My::Config::Group=HASH(0x563f696d71a0), My::Test=HASH(0x563f69610310)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 5413 main::start_servers(My::Test=HASH(0x563f69610310)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 3849 main::run_testcase(My::Test=HASH(0x563f69610310), IO::Socket::INET=GLOB(0x563f69f5e150)) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 962 main::run_worker(51091, 10) called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 475 main::main() called at /mariadb/10.6m/mysql-test/mariadb-test-run.pl line 347 At the end of the test run, these two test failures were reported as follows: Only 5881 of 5883 completed. -------------------------------------------------------------------------- The servers were restarted 2067 times Spent 11573.873 of 533 seconds executing testcases Completed: All 5622 tests were successful. 198 tests were skipped, 177 by the test itself. mysql-test-run: *** ERROR: Not all tests completed (only 5881 of 5883)

Sergei Golubchik made changes - 2021-12-06 21:34

Workflow

MariaDB v3 [ 118966 ]

MariaDB v4 [ 142579 ]

Sergei Golubchik added a comment - 2021-12-29 10:57

Seems to be a mix of different symptoms and I cannot repeat any of that anyway.

Could you please explain, how I can repeat these effects?

Sergei Golubchik added a comment - 2021-12-29 10:57 Seems to be a mix of different symptoms and I cannot repeat any of that anyway. Could you please explain, how I can repeat these effects?

Sergei Golubchik made changes - 2021-12-29 10:57

Status

Open [ 1 ]

Needs Feedback [ 10501 ]

Marko Mäkelä added a comment - 2022-01-03 15:40

serg, I can repeat this on almost every run of any 10.6 or later branch, optimized debug build in /dev/shm. I am simply invoking

nice ./mtr --parallel=60 --big-test --force

on a system that is equipped with 40 CPU cores.

However, I just tried the following a few times on a 10.6 build, and I was unable to repeat the skipped tests:

./mtr --parallel=14 encryption.innodb-checksum-algorithm

Marko Mäkelä added a comment - 2022-01-03 15:40 serg , I can repeat this on almost every run of any 10.6 or later branch, optimized debug build in /dev/shm . I am simply invoking nice ./mtr --parallel=60 --big-test --force on a system that is equipped with 40 CPU cores. However, I just tried the following a few times on a 10.6 build, and I was unable to repeat the skipped tests: ./mtr --parallel=14 encryption.innodb-checksum-algorithm

Thirunarayanan Balathandayuthapani added a comment - 2022-01-03 17:36

I can repeat the issue till 10.6.4. Something could've fixed in 10.6.5 (or merge from lower version)

Thirunarayanan Balathandayuthapani added a comment - 2022-01-03 17:36 I can repeat the issue till 10.6.4. Something could've fixed in 10.6.5 (or merge from lower version)

Sergei Golubchik made changes - 2022-02-07 13:58

Status

Needs Feedback [ 10501 ]

Open [ 1 ]

Sergei Golubchik made changes - 2022-04-12 10:15

Fix Version/s		10.6.5 [ 26034 ]
Fix Version/s	10.5 [ 23123 ]
Fix Version/s	10.6 [ 24028 ]
Resolution		Fixed [ 1 ]
Status	Open [ 1 ]	Closed [ 6 ]

Marko Mäkelä added a comment - 2022-07-01 07:28

This is occurring again at least on FreeBSD 13.0:

10.6 90792e4a437e68be86627797229d54e82648cc25
encryption.innodb_encryption 'innodb,undo0' w1 [ skipped ] Needs example_key_management
line
2022-06-30 17:44:40 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available
2022-06-30 17:44:40 0 [ERROR] Plugin 'InnoDB' init function returned error.
2022-06-30 17:44:40 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
^ Found warnings in /tmp/var/1/log/mysqld.1.err

Marko Mäkelä added a comment - 2022-07-01 07:28 This is occurring again at least on FreeBSD 13.0 : 10.6 90792e4a437e68be86627797229d54e82648cc25 encryption.innodb_encryption 'innodb,undo0' w1 [ skipped ] Needs example_key_management line 2022-06-30 17:44:40 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2022-06-30 17:44:40 0 [ERROR] Plugin 'InnoDB' init function returned error. 2022-06-30 17:44:40 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /tmp/var/1/log/mysqld.1.err

Marko Mäkelä made changes - 2022-07-01 07:28

Resolution	Fixed [ 1 ]
Status	Closed [ 6 ]	Stalled [ 10000 ]

Marko Mäkelä made changes - 2022-07-01 07:29

Fix Version/s		10.5 [ 23123 ]
Fix Version/s		10.6 [ 24028 ]
Fix Version/s		10.7 [ 24805 ]
Fix Version/s		10.8 [ 26121 ]
Fix Version/s		10.9 [ 26905 ]
Fix Version/s		10.10 [ 27530 ]
Fix Version/s	10.6.5 [ 26034 ]

Marko Mäkelä added a comment - 2022-07-01 07:50

On FreeBSD 12.3 or 13.0, this occurs also on 10.3, 10.4, 10.5. I did not check newer branches. Here is an excerpt from the test log of the currently latest builds of 10.3, 10.4, 10.5 on FreeBSD 13.0:

10.3 e34f8781397ed451a84c1a3fdc5766c5b1e28d41
encryption.innodb_encryption 'innodb,undo0' w2 [ skipped ] Needs example_key_management
line
2022-06-30 13:16:54 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available
2022-06-30 13:16:54 0 [ERROR] Plugin 'InnoDB' init function returned error.
2022-06-30 13:16:54 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
^ Found warnings in /tmp/var/2/log/mysqld.1.err

http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1763

10.4 d89cac0884a5fa0c1c66ebd03757a007b8003a20
encryption.innodb_encryption 'innodb,undo0' w2 [ skipped ] Needs example_key_management
line
2022-06-29 8:36:51 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available
2022-06-29 8:36:51 0 [ERROR] Plugin 'InnoDB' init function returned error.
2022-06-29 8:36:51 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
^ Found warnings in /tmp/var/2/log/mysqld.1.err

http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1715

10.5 a1267724cb1d1837026a3a5f49e55931038e43e7
encryption.innodb_encryption 'innodb,undo0' w1 [ skipped ] Needs example_key_management
line
2022-06-30 17:06:16 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available
2022-06-30 17:06:16 0 [ERROR] Plugin 'InnoDB' init function returned error.
2022-06-30 17:06:16 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
^ Found warnings in /tmp/var/1/log/mysqld.1.err

http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1769

Marko Mäkelä added a comment - 2022-07-01 07:50 On FreeBSD 12.3 or 13.0, this occurs also on 10.3, 10.4, 10.5. I did not check newer branches. Here is an excerpt from the test log of the currently latest builds of 10.3, 10.4, 10.5 on FreeBSD 13.0: 10.3 e34f8781397ed451a84c1a3fdc5766c5b1e28d41 encryption.innodb_encryption 'innodb,undo0' w2 [ skipped ] Needs example_key_management line 2022-06-30 13:16:54 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2022-06-30 13:16:54 0 [ERROR] Plugin 'InnoDB' init function returned error. 2022-06-30 13:16:54 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /tmp/var/2/log/mysqld.1.err http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1763 10.4 d89cac0884a5fa0c1c66ebd03757a007b8003a20 encryption.innodb_encryption 'innodb,undo0' w2 [ skipped ] Needs example_key_management line 2022-06-29 8:36:51 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2022-06-29 8:36:51 0 [ERROR] Plugin 'InnoDB' init function returned error. 2022-06-29 8:36:51 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /tmp/var/2/log/mysqld.1.err http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1715 10.5 a1267724cb1d1837026a3a5f49e55931038e43e7 encryption.innodb_encryption 'innodb,undo0' w1 [ skipped ] Needs example_key_management line 2022-06-30 17:06:16 0 [ERROR] InnoDB: cannot enable encryption, encryption plugin is not available 2022-06-30 17:06:16 0 [ERROR] Plugin 'InnoDB' init function returned error. 2022-06-30 17:06:16 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. ^ Found warnings in /tmp/var/1/log/mysqld.1.err http://buildbot.askmonty.org/buildbot/builders/kvm-ports-freebsd130-amd64/builds/1769

Marko Mäkelä made changes - 2022-07-01 07:50

Component/s		Platform FreeBSD [ 10139 ]
Affects Version/s		10.3 [ 22126 ]
Affects Version/s		10.4 [ 22408 ]

Elena Stepanova added a comment - 2022-07-01 08:34

It is not the same as the original problem(s). example_key_management plugin is a test plugin. It has been disabled in the FreeBSD port builds, so it's not there. Disabled not by us, by FreeBSD.

Elena Stepanova added a comment - 2022-07-01 08:34 It is not the same as the original problem(s). example_key_management plugin is a test plugin. It has been disabled in the FreeBSD port builds, so it's not there. Disabled not by us, by FreeBSD.

Sergei Golubchik added a comment - 2022-10-21 15:38 - edited

doesn't seem to happen anymore. Or, at least, it's totally masked by

encryption.innodb-compressed-blob '8k,ctr,innodb' w1 [ fail ]  Found warnings/errors in server log file!

        Test ended at 2022-10-21 15:02:59

line

2022-10-21 15:02:55 0 [ERROR] [FATAL] InnoDB: IO Error: 125 during async write of 1277952 bytes, for file 12, returned 0

Please, reopen, if it'll start showing up again

Sergei Golubchik added a comment - 2022-10-21 15:38 - edited doesn't seem to happen anymore. Or, at least, it's totally masked by encryption.innodb-compressed-blob '8k,ctr,innodb' w1 [ fail ] Found warnings/errors in server log file! Test ended at 2022-10-21 15:02:59 line 2022-10-21 15:02:55 0 [ERROR] [FATAL] InnoDB: IO Error: 125 during async write of 1277952 bytes, for file 12, returned 0 Please, reopen, if it'll start showing up again

Sergei Golubchik made changes - 2022-10-21 15:38

Fix Version/s		N/A [ 14700 ]
Fix Version/s	10.5 [ 23123 ]
Fix Version/s	10.6 [ 24028 ]
Fix Version/s	10.7 [ 24805 ]
Fix Version/s	10.8 [ 26121 ]
Fix Version/s	10.9 [ 26905 ]
Fix Version/s	10.10 [ 27530 ]
Resolution		Cannot Reproduce [ 5 ]
Status	Stalled [ 10000 ]	Closed [ 6 ]

Marko Mäkelä added a comment - 2023-11-16 16:04

11.0 6cf776b68d2671bd78c012b8dd9f55623144652e
mariabackup.xb_compressed_encrypted '8k,innodb' w14 [ fail ]
Test ended at 2023-11-13 22:59:11

CURRENT_TEST: mariabackup.xb_compressed_encrypted
mysqltest: At line 4: query 'CREATE TABLE t1(c1 INT, b VARCHAR(2400), index(b(100),c1))
ENGINE=INNODB ROW_FORMAT=compressed ENCRYPTED=YES' failed: ER_UNKNOWN_STORAGE_ENGINE (1286): Unknown storage engine 'INNODB'

https://buildbot.mariadb.org/#/builders/318/builds/18055

This is the very first SQL statement in the test file:

--source include/innodb_page_size_small.inc

--source include/have_file_key_management.inc

CREATE TABLE t1(c1 INT, b VARCHAR(2400), index(b(100),c1))

ENGINE=INNODB ROW_FORMAT=compressed ENCRYPTED=YES;

Marko Mäkelä added a comment - 2023-11-16 16:04 11.0 6cf776b68d2671bd78c012b8dd9f55623144652e mariabackup.xb_compressed_encrypted '8k,innodb' w14 [ fail ] Test ended at 2023-11-13 22:59:11 CURRENT_TEST: mariabackup.xb_compressed_encrypted mysqltest: At line 4: query 'CREATE TABLE t1(c1 INT, b VARCHAR(2400), index(b(100),c1)) ENGINE=INNODB ROW_FORMAT=compressed ENCRYPTED=YES' failed: ER_UNKNOWN_STORAGE_ENGINE (1286): Unknown storage engine 'INNODB' https://buildbot.mariadb.org/#/builders/318/builds/18055 This is the very first SQL statement in the test file: --source include/innodb_page_size_small.inc --source include/have_file_key_management.inc CREATE TABLE t1(c1 INT , b VARCHAR (2400), index (b(100),c1)) ENGINE=INNODB ROW_FORMAT=compressed ENCRYPTED=YES;

Marko Mäkelä made changes - 2023-11-16 16:04

Resolution	Cannot Reproduce [ 5 ]
Status	Closed [ 6 ]	Stalled [ 10000 ]

Marko Mäkelä made changes - 2023-11-16 16:04

Fix Version/s		10.4 [ 22408 ]
Fix Version/s		10.5 [ 23123 ]
Fix Version/s		10.6 [ 24028 ]
Fix Version/s		10.11 [ 27614 ]
Fix Version/s		11.0 [ 28320 ]
Fix Version/s		11.1 [ 28549 ]
Fix Version/s		11.2 [ 28603 ]
Fix Version/s	N/A [ 14700 ]

Marko Mäkelä made changes - 2023-11-16 16:04

Affects Version/s		10.8 [ 26121 ]
Affects Version/s		10.9 [ 26905 ]
Affects Version/s		10.10 [ 27530 ]
Affects Version/s		10.11 [ 27614 ]

Marko Mäkelä made changes - 2023-11-16 16:04

Affects Version/s		11.0 [ 28320 ]
Affects Version/s		11.1 [ 28549 ]
Affects Version/s		11.2 [ 28603 ]

Marko Mäkelä added a comment - 2023-11-16 16:05

The "Error: 125" (MDEV-29610) should not cause test failures anymore, after ~~MDEV-27593~~ was fixed.

Marko Mäkelä added a comment - 2023-11-16 16:05 The "Error: 125" ( MDEV-29610 ) should not cause test failures anymore, after MDEV-27593 was fixed.

Julien Fritsch made changes - 2024-05-03 14:39

Fix Version/s

11.0 [ 28320 ]

Sergei Golubchik added a comment - 2024-07-24 16:45

doesn't seem to happen anymore. Please, reopen, if it'll start showing up again

Sergei Golubchik added a comment - 2024-07-24 16:45 doesn't seem to happen anymore. Please, reopen, if it'll start showing up again

Sergei Golubchik made changes - 2024-07-24 16:45

Fix Version/s		N/A [ 14700 ]
Fix Version/s	10.4 [ 22408 ]
Fix Version/s	10.5 [ 23123 ]
Fix Version/s	10.6 [ 24028 ]
Fix Version/s	10.11 [ 27614 ]
Fix Version/s	11.1 [ 28549 ]
Fix Version/s	11.2 [ 28603 ]
Resolution		Cannot Reproduce [ 5 ]
Status	Stalled [ 10000 ]	Closed [ 6 ]

People

Assignee:: Sergei Golubchik

Reporter:: Thirunarayanan Balathandayuthapani

Votes:: 0 Vote for this issue

Watchers:: 5 Start watching this issue

Dates

Created:: 2021-02-09 13:08

Updated:: 2024-07-24 16:45

Resolved:: 2024-07-24 16:45

Git Integration

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

MariaDB Server

Details

Description

Attachments

Activity

People

Dates

Git Integration