[MDEV-28100] reiserfs O_DIRECT unsupported: Assertion `p == pages.end() || p->first > page_id' failed in recv_sys_t::apply Created: 2022-03-16  Updated: 2023-09-19

Status: Stalled
Project: MariaDB Server
Component/s: None
Affects Version/s: 10.6, 10.7, 10.8
Fix Version/s: 10.6

Type: Bug Priority: Major
Reporter: Alice Sherepa Assignee: Unassigned
Resolution: Unresolved Votes: 0
Labels: not-10.5
Environment:

reiserfs, ubuntu 20.04 + fc35 (5.17.0-0.rc6.109.fc37.x86_64)

Attachments: File MDEV-28100.c    
Issue Links:
Relates
relates to MDEV-28751 mariadb-install-db fails writing ibda... Open

 Description   

innodb.log_file_name test fails on reiserfs file system

10.8 8575d2fb396d576f4e54d58

 
/10.8/mysql-test$ ./mtr innodb.log_file_name
 
 
 
innodb.log_file_name 'innodb'            [ fail ]
 
        Test ended at 2022-03-16 11:09:35
 
 
 
CURRENT_TEST: innodb.log_file_name
 
 
 
 
 
Server [mysqld.1 - pid: 183172, winpid: 183172, exit: 256] failed during test run
 
Server log from this test:
 
----------SERVER LOG START-----------
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:10 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 182968 ...
 
2022-03-16 11:09:10 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:10 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:10 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:10 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:10 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:10 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:10 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:10 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:10 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:10 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:10 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:10 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:10 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:10 0 [Note] InnoDB: 128 rollback segments are active.
 
2022-03-16 11:09:10 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
 
2022-03-16 11:09:14 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
 
2022-03-16 11:09:14 0 [Note] InnoDB: log sequence number 48591; transaction id 17
 
2022-03-16 11:09:14 0 [Note] InnoDB: Loading buffer pool(s) from /10.8/mysql-test/var/mysqld.1/data/ib_buffer_pool
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:14 0 [Note] InnoDB: Buffer pool(s) load completed at 220316 11:09:14
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:14 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:14 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:14 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:14 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:14 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:14 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:14 0 [Note] InnoDB: FTS optimize thread exiting.
 
2022-03-16 11:09:14 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:14 0 [Note] InnoDB: Dumping buffer pool(s) to /10.8/mysql-test/var/mysqld.1/data/ib_buffer_pool
 
2022-03-16 11:09:14 0 [Note] InnoDB: Restricted to 124 pages due to innodb_buf_pool_dump_pct=25
 
2022-03-16 11:09:14 0 [Note] InnoDB: Buffer pool(s) dump completed at 220316 11:09:14
 
2022-03-16 11:09:15 0 [Note] InnoDB: Removed temporary tablespace data file: "./ibtmp1"
 
2022-03-16 11:09:15 0 [Note] InnoDB: Shutdown completed; log sequence number 50367; transaction id 21
 
2022-03-16 11:09:15 0 [Note] Debug sync points hit:                   1164
 
2022-03-16 11:09:15 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:15 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:15 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:15 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 182992 ...
 
2022-03-16 11:09:15 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:15 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:15 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:15 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:15 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:15 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:15 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:15 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:15 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:15 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:15 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:15 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:15 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:16 0 [Note] InnoDB: 128 rollback segments are active.
 
2022-03-16 11:09:16 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
 
2022-03-16 11:09:19 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
 
2022-03-16 11:09:19 0 [Note] InnoDB: log sequence number 50367; transaction id 22
 
2022-03-16 11:09:19 0 [Note] InnoDB: Loading buffer pool(s) from /10.8/mysql-test/var/mysqld.1/data/ib_buffer_pool
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:19 0 [Note] InnoDB: Buffer pool(s) load completed at 220316 11:09:19
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:19 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:19 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:19 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:19 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:19 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183012 ...
 
2022-03-16 11:09:19 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:19 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:19 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:19 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:19 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:19 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:19 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:19 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:19 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:20 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:20 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:20 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:20 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:20 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:20 0 [Note] InnoDB: Ignoring data file './test/t2.ibd' with space ID 5. Another data file called ./test/t1.ibd exists with the same space ID.
 
2022-03-16 11:09:20 0 [ERROR] InnoDB: Cannot replay rename of tablespace 5 from './test/t1.ibd' to './test/t2.ibd' because the target file exists
 
2022-03-16 11:09:20 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Tablespace already exists
 
2022-03-16 11:09:20 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:20 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:20 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:20 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:20 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:20 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:20 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:20 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:20 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:20 0 [Note] Debug sync points hit:                   309
 
2022-03-16 11:09:20 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:20 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:20 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:21 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183026 ...
 
2022-03-16 11:09:21 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:21 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:21 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:21 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:21 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:21 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:21 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:21 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:21 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:21 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:21 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:21 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:21 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:21 0 [ERROR] InnoDB: Tablespace 6 was not found at ./test/t3.ibd.
 
2022-03-16 11:09:21 0 [Note] InnoDB: Set innodb_force_recovery=1 to ignore this and to permanently lose all changes to the tablespace.
 
2022-03-16 11:09:21 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Tablespace not found
 
2022-03-16 11:09:21 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:21 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:21 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:21 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:21 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:21 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:21 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:21 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:21 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:21 0 [Note] Debug sync points hit:                   308
 
2022-03-16 11:09:21 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:21 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:21 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:23 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183040 ...
 
2022-03-16 11:09:23 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:23 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:23 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:23 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:23 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:23 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:23 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:23 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:23 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:23 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:23 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:23 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:23 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:23 0 [ERROR] InnoDB: Tablespace 6 was not found at ./test/t3.ibd.
 
2022-03-16 11:09:23 0 [Note] InnoDB: Set innodb_force_recovery=1 to ignore this and to permanently lose all changes to the tablespace.
 
2022-03-16 11:09:23 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Tablespace not found
 
2022-03-16 11:09:23 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:23 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:23 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:23 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:23 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:23 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:23 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:23 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:23 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:23 0 [Note] Debug sync points hit:                   308
 
2022-03-16 11:09:23 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:23 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:23 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:24 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183062 ...
 
2022-03-16 11:09:24 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:24 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:24 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:24 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:24 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:24 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:24 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:24 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:24 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:24 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:24 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:24 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:24 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:24 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:24 0 [ERROR] InnoDB: Tablespace 5 was not found at ./test/t1.ibd.
 
2022-03-16 11:09:24 0 [Note] InnoDB: Set innodb_force_recovery=1 to ignore this and to permanently lose all changes to the tablespace.
 
2022-03-16 11:09:24 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Tablespace not found
 
2022-03-16 11:09:24 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:25 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:25 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:25 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:25 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:25 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:25 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:25 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:25 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:25 0 [Note] Debug sync points hit:                   308
 
2022-03-16 11:09:25 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:25 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:25 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:26 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183078 ...
 
2022-03-16 11:09:26 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:26 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:26 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:26 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:26 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:26 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:26 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:26 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:26 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:26 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:26 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:26 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:26 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:26 0 [ERROR] InnoDB: Operating system error number 21 in a file operation.
 
2022-03-16 11:09:26 0 [ERROR] InnoDB: Error number 21 means 'Is a directory'
 
2022-03-16 11:09:26 0 [Note] InnoDB: Some operating system error numbers are described at https://mariadb.com/kb/en/library/operating-system-error-codes/
 
2022-03-16 11:09:26 0 [ERROR] InnoDB: Cannot open datafile for read-write: './test/t2.ibd'
 
2022-03-16 11:09:26 0 [ERROR] InnoDB: Tablespace 5 was not found at ./test/t1.ibd.
 
2022-03-16 11:09:26 0 [Note] InnoDB: Set innodb_force_recovery=1 to ignore this and to permanently lose all changes to the tablespace.
 
2022-03-16 11:09:26 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Tablespace not found
 
2022-03-16 11:09:26 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:26 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:26 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:26 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:26 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:26 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:26 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:26 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:26 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:26 0 [Note] Debug sync points hit:                   308
 
2022-03-16 11:09:26 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:26 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:26 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:28 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183090 ...
 
2022-03-16 11:09:28 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:28 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:28 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:28 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:28 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:28 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:28 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:28 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:28 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:28 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:28 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:28 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:28 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:28 0 [ERROR] InnoDB: Cannot apply log to [page id: space=5, page number=3] of corrupted file './test/t2.ibd'
 
2022-03-16 11:09:28 0 [ERROR] InnoDB: Plugin initialization aborted at srv0start.cc[1193] with error Data structure corruption
 
2022-03-16 11:09:28 0 [Note] InnoDB: Starting shutdown...
 
2022-03-16 11:09:28 0 [ERROR] Plugin 'InnoDB' init function returned error.
 
2022-03-16 11:09:28 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:28 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:28 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:28 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:28 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:28 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:28 0 [Note] /10.8/sql/mariadbd (initiated by: root[root] @ localhost []): Normal shutdown
 
2022-03-16 11:09:28 0 [Note] Debug sync points hit:                   308
 
2022-03-16 11:09:28 0 [Note] Debug sync points executed:              0
 
2022-03-16 11:09:28 0 [Note] Debug sync points max active per thread: 0
 
2022-03-16 11:09:28 0 [Note] /10.8/sql/mariadbd: Shutdown complete
 
 
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:29 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183149 ...
 
2022-03-16 11:09:29 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:29 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:29 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:29 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:29 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:29 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:29 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:29 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:29 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:29 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:29 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:29 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:29 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:29 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50351
 
2022-03-16 11:09:29 0 [Note] InnoDB: Starting final batch to recover 9 pages from redo log.
 
2022-03-16 11:09:29 0 [Note] InnoDB: 128 rollback segments are active.
 
2022-03-16 11:09:29 0 [Note] InnoDB: Removed temporary tablespace data file: "./ibtmp1"
 
2022-03-16 11:09:29 0 [Note] InnoDB: Setting file './ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
 
2022-03-16 11:09:33 0 [Note] InnoDB: File './ibtmp1' size is now 12.000MiB.
 
2022-03-16 11:09:33 0 [Note] InnoDB: log sequence number 55857; transaction id 40
 
2022-03-16 11:09:33 0 [Note] InnoDB: Loading buffer pool(s) from /10.8/mysql-test/var/mysqld.1/data/ib_buffer_pool
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_CMP' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'THREAD_POOL_WAITS' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'THREAD_POOL_QUEUES' is disabled.
 
2022-03-16 11:09:33 0 [Note] InnoDB: Buffer pool(s) load completed at 220316 11:09:33
 
2022-03-16 11:09:33 0 [Note] Plugin 'FEEDBACK' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'THREAD_POOL_GROUPS' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'user_variables' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'THREAD_POOL_STATS' is disabled.
 
2022-03-16 11:09:33 0 [Note] Plugin 'unix_socket' is disabled.
 
2022-03-16 11:09:33 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-pam-debug'
 
2022-03-16 11:09:33 0 [Warning] /10.8/sql/mariadbd: unknown option '--loose-aria'
 
2022-03-16 11:09:33 0 [Note] Server socket created on IP: '127.0.0.1'.
 
2022-03-16 11:09:33 0 [Note] /10.8/sql/mariadbd: ready for connections.
 
Version: '10.8.3-MariaDB-debug-log'  socket: '/10.8/mysql-test/var/tmp/mysqld.1.sock'  port: 16000  Source distribution
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Operating system error number 17 in a file operation.
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Error number 17 means 'File exists'
 
2022-03-16 11:09:33 3 [Note] InnoDB: Some operating system error numbers are described at https://mariadb.com/kb/en/library/operating-system-error-codes/
 
2022-03-16 11:09:33 3 [Note] InnoDB: The file './test/t0.ibd' already exists though the corresponding table did not exist in the InnoDB data dictionary. You can resolve the problem by removing the file.
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Cannot create file './test/t0.ibd'
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Operating system error number 17 in a file operation.
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Error number 17 means 'File exists'
 
2022-03-16 11:09:33 3 [Note] InnoDB: Some operating system error numbers are described at https://mariadb.com/kb/en/library/operating-system-error-codes/
 
2022-03-16 11:09:33 3 [Note] InnoDB: The file './test/t0.ibd' already exists though the corresponding table did not exist in the InnoDB data dictionary. You can resolve the problem by removing the file.
 
2022-03-16 11:09:33 3 [ERROR] InnoDB: Cannot create file './test/t0.ibd'
 
$ /10.8/sql/mariadbd --defaults-group-suffix=.1 --defaults-file=/10.8/mysql-test/var/my.cnf --log-output=file --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 --innodb-force-recovery=1 --core-file --loose-debug-sync-timeout=300
 
2022-03-16 11:09:34 0 [Note] /10.8/sql/mariadbd (server 10.8.3-MariaDB-debug-log) starting as process 183173 ...
 
2022-03-16 11:09:34 0 [Warning] Could not increase number of max_open_files to more than 1024 (request: 32190)
 
2022-03-16 11:09:34 0 [Warning] Changed limits: max_open_files: 1024  max_connections: 151 (was 151)  table_cache: 421 (was 2000)
 
2022-03-16 11:09:34 0 [Note] Plugin 'partition' is disabled.
 
2022-03-16 11:09:34 0 [Note] Plugin 'SEQUENCE' is disabled.
 
2022-03-16 11:09:34 0 [Note] InnoDB: !!! innodb_force_recovery is set to 1 !!!
 
2022-03-16 11:09:34 0 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
 
2022-03-16 11:09:34 0 [Note] InnoDB: Compressed tables use zlib 1.2.11
 
2022-03-16 11:09:34 0 [Note] InnoDB: Number of transaction pools: 1
 
2022-03-16 11:09:34 0 [Note] InnoDB: Using crc32 + pclmulqdq instructions
 
2022-03-16 11:09:34 0 [Note] mariadbd: O_TMPFILE is not supported on /10.8/mysql-test/var/tmp/mysqld.1 (disabling future attempts)
 
2022-03-16 11:09:34 0 [Note] InnoDB: Using Linux native AIO
 
2022-03-16 11:09:34 0 [Note] InnoDB: Initializing buffer pool, total size = 8.000MiB, chunk size = 1.000MiB
 
2022-03-16 11:09:34 0 [Note] InnoDB: Completed initialization of buffer pool
 
2022-03-16 11:09:34 0 [Note] InnoDB: Buffered log writes (block size=512 bytes)
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 56076: unable to open file ./test/t2.ibd for tablespace 5
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 56076: unable to open file ./test/t3.ibd for tablespace 6
 
2022-03-16 11:09:34 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=50367
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 52376: unable to open file ./test/t1.ibd for tablespace 5
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 53635: unable to open file ./test/t1.ibd for tablespace 5
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 58598: unable to open file ./test/t0.ibd for tablespace 8
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 59099: unable to open file ./test/t0.ibd for tablespace 9
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 59602: unable to open file ./test/t0.ibd for tablespace 10
 
2022-03-16 11:09:34 0 [Note] InnoDB: Cannot read first page of './test/u2.ibd': I/O error
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 65854: unable to open file ./test/u3.ibd for tablespace 13
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 69820: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-16 11:09:34 0 [Note] InnoDB: At LSN: 71107: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-16 11:09:34 0 [Warning] InnoDB: Tablespace 13 was not found at ./test/u3.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-16 11:09:34 0 [Warning] InnoDB: Tablespace 8 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-16 11:09:34 0 [Warning] InnoDB: Tablespace 9 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-16 11:09:34 0 [Warning] InnoDB: Retry attempts for writing partial data failed.
 
2022-03-16 11:09:34 0 [ERROR] InnoDB: Write to file ./test/u2.ibd failed at offset 0, 98304 bytes should have been written, only 0 were written. Operating system error number 22. Check that your OS and file system support files of this size. Check also that the disk is not full or a disk quota exceeded.
 
2022-03-16 11:09:34 0 [ERROR] InnoDB: Error number 22 means 'Invalid argument'
 
2022-03-16 11:09:34 0 [Note] InnoDB: Some operating system error numbers are described at https://mariadb.com/kb/en/library/operating-system-error-codes/
 
2022-03-16 11:09:34 0 [ERROR] InnoDB: Cannot apply log to [page id: space=12, page number=0] of corrupted file './test/u2.ibd'
 
2022-03-16 11:09:34 0 [Note] InnoDB: Starting final batch to recover 16 pages from redo log.
 
mariadbd: /10.8/storage/innobase/log/log0recv.cc:3399: void recv_sys_t::apply(bool): Assertion `p == pages.end() || p->first > page_id' failed.
 
220316 11:09:34 [ERROR] mysqld got signal 6 ;
 
This could be because you hit a bug. It is also possible that this binary
 
or one of the libraries it was linked against is corrupt, improperly built,
 
or misconfigured. This error can also be caused by malfunctioning hardware.
 
 
 
To report this bug, see https://mariadb.com/kb/en/reporting-bugs
 
 
 
We will try our best to scrape up some info that will hopefully help
 
diagnose the problem, but since we have already crashed, 
something is definitely wrong and this may fail.
 
 
 
Server version: 10.8.3-MariaDB-debug-log
 
key_buffer_size=1048576
 
read_buffer_size=131072
 
max_used_connections=0
 
max_threads=153
 
thread_count=0
 
It is possible that mysqld could use up to 
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 63896 K  bytes of memory
 
Hope that's ok; if not, decrease some variables in the equation.
 
 
 
Thread pointer: 0x0
 
Attempting backtrace. You can use the following information to find out
 
where mysqld died. If you see no messages after this, something went
 
terribly wrong...
 
stack_bottom = 0x0 thread_stack 0x49000
 
mysys/stacktrace.c:212(my_print_stacktrace)[0x55c95074f5ee]
 
sql/signal_handler.cc:226(handle_fatal_signal)[0x55c94fe5d7be]
 
sigaction.c:0(__restore_rt)[0x7f879853a3c0]
 
??:0(gsignal)[0x7f879802303b]
 
??:0(abort)[0x7f8798002859]
 
/lib/x86_64-linux-gnu/libc.so.6(+0x22729)[0x7f8798002729]
 
??:0(__assert_fail)[0x7f8798014006]
 
log/log0recv.cc:3402(recv_sys_t::apply(bool))[0x55c950379984]
 
srv/srv0start.cc:1235(srv_start(bool))[0x55c9504d060d]
 
handler/ha_innodb.cc:4178(innodb_init(void*))[0x55c950295c19]
 
sql/handler.cc:649(ha_initialize_handlerton(st_plugin_int*))[0x55c94fe61591]
 
sql/sql_plugin.cc:1463(plugin_initialize(st_mem_root*, st_plugin_int*, int*, char**, bool))[0x55c94fad0632]
 
sql/sql_plugin.cc:1756(plugin_init(int*, char**, int))[0x55c94fad1497]
 
sql/mysqld.cc:5091(init_server_components())[0x55c94f90bdfd]
 
sql/mysqld.cc:5705(mysqld_main(int, char**))[0x55c94f90d176]
 
sql/main.cc:34(main)[0x55c94f90178d]
 
??:0(__libc_start_main)[0x7f87980040b3]
 
??:0(_start)[0x55c94f9016ae]
 
The manual page at https://mariadb.com/kb/en/how-to-produce-a-full-stack-trace-for-mysqld/ contains
 
information that should help you find out what is causing the crash.
 
Writing a core file...
 
Working directory at /10.8/mysql-test/var/mysqld.1/data
 
Resource Limits:
 
Limit                     Soft Limit           Hard Limit           Units     
Max cpu time              unlimited            unlimited            seconds   
Max file size             unlimited            unlimited            bytes     
Max data size             unlimited            unlimited            bytes     
Max stack size            8388608              unlimited            bytes     
Max core file size        unlimited            unlimited            bytes     
Max resident set          unlimited            unlimited            bytes     
Max processes             126857               126857               processes 
Max open files            1024                 1024                 files     
Max locked memory         67108864             67108864             bytes     
Max address space         unlimited            unlimited            bytes     
Max file locks            unlimited            unlimited            locks     
Max pending signals       126857               126857               signals   
Max msgqueue size         819200               819200               bytes     
Max nice priority         0                    0                    
Max realtime priority     0                    0                    
Max realtime timeout      unlimited            unlimited            us        
Core pattern: /tmp/cores/core.%e.%p.%t
 
 
 
----------SERVER LOG END-------------
 
mysqltest failed but provided no output
 
The result from queries just before the failure was:
 
< snip >
 
t2
 
t3
 
DROP TABLE t2,t3;
 
CREATE TABLE t0(a INT PRIMARY KEY) ENGINE=InnoDB;
 
ERROR HY000: Can't create table `test`.`t0` (errno: 184 "Tablespace already exists")
 
CREATE TABLE t0(a INT PRIMARY KEY) ENGINE=InnoDB;
 
ERROR HY000: Can't create table `test`.`t0` (errno: 184 "Tablespace already exists")
 
CREATE TABLE t0(a INT PRIMARY KEY) ENGINE=InnoDB;
 
DROP TABLE t0;
 
CREATE TABLE u1(a INT PRIMARY KEY) ENGINE=InnoDB;
 
CREATE TABLE u2(a INT PRIMARY KEY) ENGINE=InnoDB;
 
CREATE TABLE u3(a INT PRIMARY KEY) ENGINE=InnoDB;
 
CREATE TABLE u4(a INT PRIMARY KEY) ENGINE=InnoDB;
 
INSERT INTO u4 VALUES(1);
 
RENAME TABLE u4 TO u5;
 
RENAME TABLE u5 TO u6;
 
INSERT INTO u6 VALUES(2);
 
# Kill the server
 
# Fault 6: All-zero data file and innodb_force_recovery
 
# restart: --innodb-force-recovery=1



 Comments   
Comment by Marko Mäkelä [ 2022-03-16 ]

Among other things, I see the following:

10.8 8575d2fb396d576f4e54d58c05b570bc7f26ba42

 
2022-03-16 11:09:34 0 [Note] InnoDB: Cannot read first page of './test/u2.ibd': I/O error

Is this repeatable if you set innodb_flush_method=O_DIRECT or innodb_use_native_aio=OFF? Based on the log, I see that libaio is being used.

Comment by Alice Sherepa [ 2022-03-16 ]

with --innodb_use_native_aio=OFF test fails the same way
on 10.6 - 

./mtr  --mysqld=--innodb_flush_method=O_DIRECT  innodb.log_file_name - test fails
 
./mtr  --mysqld=--innodb-flush-method=fsync  innodb.log_file_name - test passes

10.5 ./mtr test passes even with --innodb_flush_method=O_DIRECT

Comment by Daniel Black [ 2022-03-17 ]

10.6-ee80c19633dd5d4e781b3e675d2ece52a5b0f9ff (non-debug), 2G loopback mounted reiserfs disk 5.17.0-0.rc6.109.fc37.x86_64

ext3 that succeeded - last server run in the test

 
2022-03-17 11:02:02 0 [Note] InnoDB: At LSN: 63233: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-17 11:02:02 0 [Note] InnoDB: At LSN: 64453: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-17 11:02:02 0 [Warning] InnoDB: Tablespace 13 was not found at ./test/u3.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 11:02:02 0 [Warning] InnoDB: Tablespace 8 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 11:02:02 0 [Warning] InnoDB: Tablespace 9 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 11:02:02 0 [Note] InnoDB: Starting final batch to recover 15 pages from redo log.
 
2022-03-17 11:02:02 0 [Note] InnoDB: 128 rollback segments are active.
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: Operating system error number 2 in a file operation.
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: The error means the system cannot find the path specified.
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: Cannot open datafile for read-only: './test/u3.ibd' OS error: 71
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: Operating system error number 2 in a file operation.
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: The error means the system cannot find the path specified.
 
2022-03-17 11:02:02 0 [ERROR] InnoDB: Could not find a valid tablespace file for test/u3. Please refer to https://mariadb.com/kb/en/innodb-data-dictionary-troubleshooting/ for how to resolve the issue.
 
2022-03-17 11:02:02 0 [Warning] InnoDB: Ignoring tablespace for test/u3 because it could not be opened.
 
2022-03-17 11:02:02 0 [Note] InnoDB: Removed temporary tablespace data file: "./ibtmp1"
 
2022-03-17 11:02:02 0 [Note] InnoDB: Creating shared tablespace for temporary tables
 
2022-03-17 11:02:02 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
 
2022-03-17 11:02:02 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
 
2022-03-17 11:02:02 0 [Note] InnoDB: 10.6.8 started; log sequence number 65580; transaction id 83

reiserfs failing at same point

 
022-03-17 10:40:46 0 [Note] InnoDB: At LSN: 63477: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-17 10:40:46 0 [Note] InnoDB: At LSN: 64713: unable to open file ./test/u5.ibd for tablespace 14
 
2022-03-17 10:40:46 0 [Warning] InnoDB: Tablespace 13 was not found at ./test/u3.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 10:40:46 0 [Warning] InnoDB: Tablespace 8 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 10:40:46 0 [Warning] InnoDB: Tablespace 9 was not found at ./test/t0.ibd, and innodb_force_recovery was set. All redo log for this tablespace will be ignored!
 
2022-03-17 10:40:46 0 [Warning] InnoDB: Retry attempts for writing partial data failed.
 
2022-03-17 10:40:46 0 [ERROR] InnoDB: Write to file ./test/u2.ibd failed at offset 0, 98304 bytes should have been written, only 0 were written. Operating system error number 22. Check that your OS and file system support files of this size. Check also that the disk is not full or a disk quota exceeded.
 
2022-03-17 10:40:46 0 [ERROR] InnoDB: Error number 22 means 'Invalid argument'
 
2022-03-17 10:40:46 0 [Note] InnoDB: Some operating system error numbers are described at https://mariadb.com/kb/en/library/operating-system-error-codes/
 
2022-03-17 10:40:46 0 [ERROR] InnoDB: Cannot apply log to [page id: space=12, page number=0] of corrupted file './test/u2.ibd'
 
2022-03-17 10:40:46 0 [Note] InnoDB: Starting final batch to recover 16 pages from redo log.

Comment by Daniel Black [ 2022-03-17 ]

minimal test case

 
--source include/have_innodb.inc
 
 
 
 
 
--source include/no_checkpoint_start.inc
 
 
 
CREATE TABLE u2(a INT PRIMARY KEY) ENGINE=InnoDB;
 
 
 
--let CLEANUP_IF_CHECKPOINT=DROP TABLE u2;
 
--source include/no_checkpoint_end.inc
 
 
 
--echo # Fault 6: All-zero data file and innodb_force_recovery
 
 
 
--remove_file $MYSQLD_DATADIR/test/u2.ibd
 
 
 
--exec echo "" > $MYSQLD_DATADIR/test/u2.ibd
 
 
 
--let $restart_parameters= --innodb-force-recovery=1
 
--source include/start_mysqld.inc
 
 
 
--echo # List of files:
 
--list_files $MYSQLD_DATADIR/test
 
 
 
SHOW TABLES;


(gdb) bt
 
#0  os_file_io (in_type=@0x1289330: {bpage = 0x0, slot = 0x0, node = 0x0, type = IORequest::WRITE_SYNC}, file=file@entry=13, buf=buf@entry=0x24a0000, n=n@entry=98304, offset=offset@entry=0, err=err@entry=0x7fffffff8adc) at /home/dan/repos/mariadb-server-10.6/storage/innobase/os/os0file.cc:2814
 
#1  0x0000000000d0d7bc in os_file_pwrite (n=98304, offset=0, err=0x7fffffff8adc, type=<optimized out>, file=<optimized out>, buf=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/os/os0file.cc:2895
 
#2  os_file_write_func (type=@0x1289330: {bpage = 0x0, slot = 0x0, node = 0x0, type = IORequest::WRITE_SYNC}, name=name@entry=0x22bfde8 "./test/u2.ibd", file=file@entry=13, buf=buf@entry=0x24a0000, offset=offset@entry=0, n=n@entry=98304) at /home/dan/repos/mariadb-server-10.6/storage/innobase/os/os0file.cc:2926
 
#3  0x0000000000d0e06c in pfs_os_file_write_func (name=0x22bfde8 "./test/u2.ibd", file={m_file = 13, m_psi = 0x0}, buf=0x24a0000, offset=0, n=98304, src_line=3356, type=<optimized out>, src_file=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/include/os0file.inl:317
 
#4  os_file_set_size (name=<optimized out>, name@entry=0x22bfde8 "./test/u2.ibd", file=file@entry=13, size=98304, is_sparse=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/os/os0file.cc:3355
 
#5  0x0000000000cf5c8a in recv_sys_t::recover_deferred (this=<optimized out>, p=<optimized out>, name=@0x22befe8: {_M_dataplus = {<std::allocator<char>> = {<__gnu_cxx::new_allocator<char>> = {<No data fields>}, <No data fields>}, _M_p = 0x22beff8 "./test/u2.ibd"}, _M_string_length = 13, {_M_local_buf = "./test/u2.ibd\000\000", _M_allocated_capacity = 8444095865315143470}}, free_block=@0x7fffffff9438: 0x0) at /home/dan/repos/mariadb-server-10.6/storage/innobase/log/log0recv.cc:919
 
#6  0x0000000000d006f3 in $_0::reinit_all (this=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/log/log0recv.cc:757
 
#7  0x0000000000cfe28b in recv_recovery_from_checkpoint_start (flush_lsn=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/log/log0recv.cc:4234
 
#8  0x0000000000d8ae9d in srv_start (create_new_db=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/srv/srv0start.cc:1439
 
#9  0x0000000000cb1236 in innodb_init (p=<optimized out>) at /home/dan/repos/mariadb-server-10.6/storage/innobase/handler/ha_innodb.cc:4251
 
#10 0x000000000092f52c in ha_initialize_handlerton (plugin=0x2106590) at /home/dan/repos/mariadb-server-10.6/sql/handler.cc:659
 
#11 0x0000000000721b66 in plugin_initialize (tmp_root=<optimized out>, tmp_root@entry=0x7fffffffb318, plugin=0x2106590, argc=argc@entry=0x17d0fe0 <remaining_argc>, argv=argv@entry=0x20c97d8, options_only=false) at /home/dan/repos/mariadb-server-10.6/sql/sql_plugin.cc:1464
 
#12 0x00000000007212bd in plugin_init (argc=0x17d0fe0 <remaining_argc>, argv=0x20c97d8, flags=0) at /home/dan/repos/mariadb-server-10.6/sql/sql_plugin.cc:1757
 
#13 0x000000000064a2ab in init_server_components () at /home/dan/repos/mariadb-server-10.6/sql/mysqld.cc:5047
 
#14 0x0000000000647021 in mysqld_main (argc=<optimized out>, argv=<optimized out>) at /home/dan/repos/mariadb-server-10.6/sql/mysqld.cc:5662
 
#15 0x00007ffff7373560 in __libc_start_call_main () from /lib64/libc.so.6
 
#16 0x00007ffff737360c in __libc_start_main_impl () from /lib64/libc.so.6

strace

 
1561029 openat(AT_FDCWD, "./test/u2.ibd", O_RDWR|O_CLOEXEC) = 13
 
1561029 newfstatat(13, "", {st_mode=S_IFREG|0644, st_size=1, ...}, AT_EMPTY_PATH) = 0
 
1561029 newfstatat(13, "", {st_mode=S_IFREG|0644, st_size=1, ...}, AT_EMPTY_PATH) = 0
 
1561029 close(13)                       = 0
 
1561029 openat(AT_FDCWD, "./test/u2.ibd", O_RDWR|O_CLOEXEC) = 13
 
1561029 fcntl(13, F_SETFL, O_RDONLY|O_DIRECT) = 0
 
1561029 newfstatat(13, "", {st_mode=S_IFREG|0644, st_size=1, ...}, AT_EMPTY_PATH) = 0
 
1561029 fallocate(13, 0, 0, 98304)      = -1 EOPNOTSUPP (Operation not supported)
 
1561029 fcntl(13, F_GETFL)              = 0xc002 (flags O_RDWR|O_DIRECT|O_LARGEFILE)
 
1561029 newfstatat(13, "", {st_mode=S_IFREG|0644, st_size=1, ...}, AT_EMPTY_PATH) = 0
 
1561029 fstatfs(13, {f_type=REISERFS_SUPER_MAGIC, f_bsize=4096, f_blocks=524271, f_bfree=476909, f_bavail=476909, f_files=0, f_ffree=0, f_fsid={val=[0xa2cebe5b, 0x4cfee612]}, f_namelen=255, f_frsize=4096, f_flags=ST_VALID|ST_RELATIME}) = 0
 
1561029 pwrite64(13, "\0", 1, 4095)     = -1 EINVAL (Invalid argument)
 
1561029 newfstatat(13, "", {st_mode=S_IFREG|0644, st_size=1, ...}, AT_EMPTY_PATH) = 0
 
1561029 pwrite64(13, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 98304, 0) = -1 EINVAL (Invalid argument)

so 98304 is 96KiB exactly. Block size from stat is 4k, so easy multiple. But still an error.

SyncFileIO::execute (this=0x7fffffff5f90, request=@0x7fffffff62e8: {bpage = 0x0, slot = 0x0, node = 0x0, type = IORequest::WRITE_SYNC}) at /home/dan/repos/mariadb-server-10.6/storage/innobase/os/os0file.cc:734
 
734		if (request.is_read()) {
 
(gdb) n
 
741			ut_ad(request.is_write());
 
(gdb) 
745			n_bytes = pwrite(m_fh, m_buf, m_n, m_offset);
 
(gdb) info locals
 
n_bytes = 49659904
 
(gdb) p *this
 
$2 = {m_fh = 15, m_buf = 0x2f5c000, m_n = 98304, m_offset = 0}
 
(gdb) n
 
749		return(n_bytes);
 
(gdb) p n_bytes
 
$3 = -1
 
(gdb) p (int) errno
 
$4 = 22

Comment by Daniel Black [ 2022-03-17 ]

10.5 is unaffected as it doesn't try to set the size of the ibd file back.

reiserfs simply cannot write/pwrite on an O_DIRECT file.

MDEV-28100.c

 
openat(AT_FDCWD, "/mnt/reiserfs/a", O_RDWR|O_CREAT|O_TRUNC, 0740) = 3
 
close(3)                                = 0
 
openat(AT_FDCWD, "/mnt/reiserfs/a", O_RDWR|O_DIRECT|O_CLOEXEC) = 3
 
pwrite64(3, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 4096, 0) = -1 EINVAL (Invalid argument)
 
close(3)                                = 0
 
exit_group(0)

Comment by Daniel Black [ 2023-09-19 ]

Last I saw it was on the way out even from the kernel.

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