[MDEV-24821] Encryption loading fails while running the suite Created: 2021-02-09  Updated: 2023-11-16

Status: Stalled
Project: MariaDB Server
Component/s: Platform FreeBSD, Tests
Affects Version/s: 10.3, 10.4, 10.5, 10.6, 10.8, 10.9, 10.10, 10.11, 11.0, 11.1, 11.2
Fix Version/s: 10.4, 10.5, 10.6, 10.11, 11.0, 11.1, 11.2

Type: Bug Priority: Critical
Reporter: Thirunarayanan Balathandayuthapani Assignee: Sergei Golubchik
Resolution: Unresolved Votes: 0
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 ']



 Comments   
Comment by Marko Mäkelä [ 2021-03-11 ]

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.

Comment by Marko Mäkelä [ 2021-03-17 ]

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

Comment by Marko Mäkelä [ 2021-11-18 ]

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)

Comment by Sergei Golubchik [ 2021-12-29 ]

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?

Comment by Marko Mäkelä [ 2022-01-03 ]

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

Comment by Thirunarayanan Balathandayuthapani [ 2022-01-03 ]

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

Comment by Marko Mäkelä [ 2022-07-01 ]

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

Comment by Marko Mäkelä [ 2022-07-01 ]

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

Comment by Elena Stepanova [ 2022-07-01 ]

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.

Comment by Sergei Golubchik [ 2022-10-21 ]

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

Comment by Marko Mäkelä [ 2023-11-16 ]

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;

Comment by Marko Mäkelä [ 2023-11-16 ]

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

Generated at Thu Feb 08 09:32:55 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.