[MDEV-24101] innodb_random_read_ahead=ON causes hang on DDL or shutdown - Jira

XML

Word

Printable

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Critical
Resolution: Fixed
Affects Version/s: 10.5
Fix Version/s: 10.5.7
Component/s: Storage Engine - InnoDB
Labels:
- hang
- regression-10.5

Description

10.5 440d4b282d
Version: '10.5.7-MariaDB-debug-log' socket: '/home/mariadb/MDEV-24101/10.5/mysql-test/var/tmp/mysqld.1.sock' port: 16000 Source distribution
2020-11-03 1:43:49 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:43:59 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:09 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:19 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:29 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:39 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:49 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:44:59 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:45:10 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:45:20 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:45:30 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:45:40 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:45:50 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:00 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:10 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:20 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:30 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:41 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:46:51 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:01 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:11 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:21 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:31 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:41 9 [Warning] InnoDB: Trying to delete tablespace 'test/#sql-backup-8cbd-9' but there are 3 pending operations on it.
2020-11-03 1:47:49 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 241.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:47:49 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 241.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:47:49 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 241.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314

<...>

2020-11-03 1:48:56 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 299.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 308.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 308.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 308.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 308.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 307.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:56 0 [Note] InnoDB: A semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 299.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
InnoDB: Pending reads 0, writes 0
InnoDB: ###### Diagnostic info printed to the standard error stream
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 308.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 300.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 309.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 308.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:57 0 [Note] InnoDB: A semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 300.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
InnoDB: Pending reads 0, writes 0
InnoDB: ###### Diagnostic info printed to the standard error stream
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 309.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 301.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 310.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 309.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:58 0 [Note] InnoDB: A semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 301.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
InnoDB: Pending reads 0, writes 0
InnoDB: ###### Diagnostic info printed to the standard error stream
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 310.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Warning] InnoDB: A long semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 302.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635575420672 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635056432896 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635091044096 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635080554240 has waited at dict0dict.cc line 943 for 311.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635583813376 has waited at srv0srv.cc line 1603 for 310.00 seconds the semaphore:
X-lock on RW-latch at 0x5642ff699478 created in file dict0dict.cc line 1041
a writer (thread id 139635024963328) has reserved it in mode exclusive
number of readers 0, waiters flag 1, lock_word: 0
Last time write locked in file row0mysql.cc line 3314
2020-11-03 1:48:59 0 [Note] InnoDB: A semaphore wait:
--Thread 139635595351808 has waited at dict0stats_bg.cc line 348 for 302.00 seconds the semaphore:
Mutex at 0x5642ff6993e0, Mutex DICT_SYS created /home/mariadb/MDEV-24101/10.5/storage/innobase/dict/dict0dict.cc:1032, lock var 2
Last time reserved in file /home/mariadb/MDEV-24101/10.5/storage/innobase/row/row0mysql.cc line 3314
InnoDB: ###### Starts InnoDB Monitor for 30 secs to print diagnostic info:
InnoDB: Pending reads 0, writes 0
InnoDB: ###### Diagnostic info printed to the standard error stream
2020-11-03 1:48:59 0 [ERROR] [FATAL] InnoDB: Semaphore wait has lasted > 300 seconds. We intentionally crash the server because it appears to be hung.
201103 1:48:59 [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.5.7-MariaDB-debug-log
key_buffer_size=134217728
read_buffer_size=131072
max_used_connections=3
max_threads=153
thread_count=4
It is possible that mysqld could use up to
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 193789 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
/home/mariadb/MDEV-24101/10.5/sql/mariadbd(my_print_stacktrace+0x40)[0x5642fe86b833]
mysys/stacktrace.c:212(my_print_stacktrace)[0x5642fdf2979b]
??:0(__restore_rt)[0x7eff8205c8a0]
linux/raise.c:51(__GI_raise)[0x7eff81154f47]
stdlib/abort.c:81(__GI_abort)[0x7eff811568b1]
/home/mariadb/MDEV-24101/10.5/sql/mariadbd(+0x1376741)[0x5642fe627741]
/home/mariadb/MDEV-24101/10.5/sql/mariadbd(+0x1306b93)[0x5642fe5b7b93]
ut/ut0ut.cc:580(ib::fatal::~fatal())[0x5642fe7c858a]
srv/srv0srv.cc:1431(srv_error_monitor_task)[0x5642fe7c8678]
tpool/tpool_generic.cc:300(tpool::thread_pool_generic::timer_generic::run())[0x5642fe7cd74d]
tpool/task.cc:53(tpool::task::execute())[0x5642fe7c6e2e]
tpool/tpool_generic.cc:516(tpool::thread_pool_generic::worker_main(tpool::worker_data*))[0x5642fe7ca1d5]
bits/invoke.h:73(void std::__invoke_impl<void, void (tpool::thread_pool_generic::)(tpool::worker_data), tpool::thread_pool_generic, tpool::worker_data>(std::__invoke_memfun_deref, void (tpool::thread_pool_generic::&&)(tpool::worker_data), tpool::thread_pool_generic&&, tpool::worker_data&&))[0x5642fe7c94c1]
bits/invoke.h:95(std::__invoke_result<void (tpool::thread_pool_generic::)(tpool::worker_data), tpool::thread_pool_generic, tpool::worker_data>::type std::__invoke<void (tpool::thread_pool_generic::)(tpool::worker_data), tpool::thread_pool_generic, tpool::worker_data>(void (tpool::thread_pool_generic::&&)(tpool::worker_data), tpool::thread_pool_generic&&, tpool::worker_data&&))[0x5642fe7cd055]
7/thread:234(decltype (__invoke((_S_declval<0ul>)(), (_S_declval<1ul>)(), (_S_declval<2ul>)())) std::thread::_Invoker<std::tuple<void (tpool::thread_pool_generic::)(tpool::worker_data), tpool::thread_pool_generic, tpool::worker_data> >::_M_invoke<0ul, 1ul, 2ul>(std::_Index_tuple<0ul, 1ul, 2ul>))[0x5642fe7ccfef]
7/thread:243(std::thread::_Invoker<std::tuple<void (tpool::thread_pool_generic::)(tpool::worker_data), tpool::thread_pool_generic, tpool::worker_data> >::operator()())[0x5642fe7ccfce]
??:0(std::error_code::default_error_condition() const)[0x7eff81b7a6df]
nptl/pthread_create.c:463(start_thread)[0x7eff820516db]
x86_64/clone.S:97(clone)[0x7eff81237a3f]
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 /dev/shm/var_auto_gcO5/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 0 unlimited bytes
Max resident set unlimited unlimited bytes
Max processes 128241 128241 processes
Max open files 1024 1024 files
Max locked memory 16777216 16777216 bytes
Max address space unlimited unlimited bytes
Max file locks unlimited unlimited locks
Max pending signals 128241 128241 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: \|/usr/share/apport/apport %p %s %c %d %P %E

----------SERVER LOG END-------------

Attachments

Issue Links

is caused by

MDEV-23855 InnoDB log checkpointing causes regression for write-heavy OLTP

Closed

Activity

People

Assignee:: Marko Mäkelä

Reporter:: Elena Stepanova

Votes:: 0 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Created:: 2020-11-03 00:49

Updated:: 2020-11-05 16:52

Resolved:: 2020-11-03 14:29

Git Integration

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