[MDEV-12898] encryption.innodb-redo-badkey failed in buildbot, server hung on startup Created: 2017-05-24  Updated: 2019-09-30  Resolved: 2019-09-30

Status: Closed
Project: MariaDB Server
Component/s: Storage Engine - InnoDB, Tests
Affects Version/s: 10.1
Fix Version/s: 10.2.24, 10.3.15, 10.4.5

Type: Bug Priority: Major
Reporter: Elena Stepanova Assignee: Marko Mäkelä
Resolution: Duplicate Votes: 0
Labels: None
Environment:

kvm-deb-wheezy-amd64


Issue Links:
Duplicate
duplicates MDEV-12699 Improve crash recovery of corrupted d... Closed
Relates
relates to MDEV-12602 InnoDB: Failing assertion: space->n_p... Closed
relates to MDEV-13485 MTR tests fail massively with --innod... Closed

 Description   

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-wheezy-amd64/builds/8785/steps/mtr/logs/stdio

encryption.innodb-redo-badkey 'cbc,xtradb' w3 [ fail ]
 
        Test ended at 2017-05-09 11:12:38
 
 
 
CURRENT_TEST: encryption.innodb-redo-badkey
 
 
 
 
 
Failed to start mysqld.1
 
mysqltest failed but provided no output

From the error log it appears that the server just froze on startup:

CURRENT_TEST: encryption.innodb-redo-badkey
 
2017-05-09 11:09:37 140444503680864 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB-1~wheezy) starting as process 1257 ...
 
2017-05-09 11:09:37 140444503680864 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
 
2017-05-09 11:09:37 140444503680864 [Note] Plugin 'partition' is disabled.
 
innodb_open_files should not be greater than the open_files_limit.
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
 
 
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Using mutexes to ref count buffer pool pages
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: The InnoDB memory heap is disabled
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Compressed tables use zlib 1.2.7
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Using Linux native AIO
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Using generic crc32 instructions
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Completed initialization of buffer pool
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Highest supported file format is Barracuda.
 
CURRENT_TEST: encryption.innodb-redo-badkey
 
2017-05-09 11:12:38 140619261765472 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB-1~wheezy) starting as process 5616 ...
 
2017-05-09 11:12:38 140619261765472 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'partition' is disabled.
 
innodb_open_files should not be greater than the open_files_limit.
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
 
 
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Using mutexes to ref count buffer pool pages
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: The InnoDB memory heap is disabled
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Compressed tables use zlib 1.2.7
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Using Linux native AIO
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Using generic crc32 instructions
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Completed initialization of buffer pool
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Highest supported file format is Barracuda.
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: 128 rollback segment(s) are active.
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Waiting for purge to start
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.35-80.0 started; log sequence number 1629796
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Creating #1 thread id 140618923747072 total threads 2.
 
2017-05-09 11:12:38 140619261765472 [Note] InnoDB: Creating #2 thread id 140618906990336 total threads 2.
 
2017-05-09 11:12:38 140618948925184 [Note] InnoDB: Dumping buffer pool(s) not yet started
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'SEQUENCE' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'XTRADB_RSEG' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'FEEDBACK' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_CMP' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_MUTEXES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_CHANGED_PAGES' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
 
2017-05-09 11:12:38 140619261765472 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-startup-interval=20'
 
2017-05-09 11:12:38 140619261765472 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-first-interval=60'
 
2017-05-09 11:12:38 140619261765472 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-interval=60'
 
2017-05-09 11:12:38 140619261765472 [Warning] /usr/sbin/mysqld: unknown option '--loose-pam-debug'
 
2017-05-09 11:12:38 140619261765472 [Warning] /usr/sbin/mysqld: unknown variable 'loose-debug-sync-timeout=300'
 
2017-05-09 11:12:38 140619261765472 [Note] Server socket created on IP: '127.0.0.1'.
 
2017-05-09 11:12:38 140619261765472 [Note] /usr/sbin/mysqld: ready for connections.
 
Version: '10.1.24-MariaDB-1~wheezy'  socket: '/run/shm/var/tmp/3/mysqld.1.sock'  port: 16020  mariadb.org binary distribution



 Comments   
Comment by Marko Mäkelä [ 2017-08-18 ]

I was hoping that MDEV-12602 and other fixes in MariaDB 10.1.23 would have fixed most encryption-related hangs, but it clearly is not the case.

There is a known possible source of a hang, in fil_space_crypt_close_tablespace() which I pointed out during one of the reviews for encryption bugs in 10.1.22 or 10.1.23. I am not sure if this function could have been invoked this early at startup. The data dictionary might not be available at this point yet:

2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Completed initialization of buffer pool
 
2017-05-09 11:09:37 140444503680864 [Note] InnoDB: Highest supported file format is Barracuda.
 
CURRENT_TEST: encryption.innodb-redo-badkey
 
2017-05-09 11:12:38 140619261765472 [Note] /usr/sbin/mysqld (mysqld 10.1.24-MariaDB-1~wheezy) starting as process 5616 ...

Comment by Marko Mäkelä [ 2017-08-22 ]

Possibly related hang, apparently during IMPORT: http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/9296/steps/test_4/logs/stdio

CURRENT_TEST: encryption.innodb-checksum-algorithm
 
Warning: /mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-ca=/mnt/buildbot/build/mariadb-10.2.9/mysql-test/std_data/cacert.pem'
 
Warning: /mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-cert=/mnt/buildbot/build/mariadb-10.2.9/mysql-test/std_data/client-cert.pem'
 
Warning: /mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded: unknown variable 'loose-ssl-key=/mnt/buildbot/build/mariadb-10.2.9/mysql-test/std_data/client-key.pem'
 
Warning: /mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded: unknown option '--loose-skip-ssl'
 
########################################
 
DEADLOCK of threads detected!
 
rw-lock 0xb325740 --Thread 2823805760 has waited at fsp0fsp.cc line 2786 for 0.00 seconds the semaphore:
 
X-lock (wait_ex) on RW-latch at 0xb325740 created in file fil0fil.cc line 1674
 
a writer (thread id 2823805760) has reserved it in mode  wait exclusive
 
number of readers 2, waiters flag 0, lock_word: fffffffe
 
Last time read locked in file fil0crypt.cc line 1777
 
Last time write locked in file fsp0fsp.cc line 2041
 
Locked: thread 2806713152 file fil0crypt.cc line 1777  S-LOCK
 
Locked: thread 2806713152 file fil0crypt.cc line 1777  S-LOCK
 
rw-lock 0xb5306cfc --Thread 2806713152 has waited at fil0crypt.cc line 1791 for 0.00 seconds the semaphore:
 
X-lock on RW-latch at 0xb5306cfc created in file buf0buf.cc line 1478
 
a writer (thread id 2823805760) has reserved it in mode  exclusive
 
number of readers 0, waiters flag 1, lock_word: 0
 
Last time read locked in file not yet reserved line 0
 
Last time write locked in file trx0rseg.ic line 50
 
Locked: thread 2823805760 file trx0rseg.ic line 50  X-LOCK
 
Locked: thread 2823805760 file trx0rseg.ic line 50  X-LOCK
 
mysqltest got signal 6
 
read_command_buf (0x94f9280): eval ALTER TABLE tc_$checksum IMPORT TABLESPACEcrc32'                end`
 
conn->name (0xb18e3d0): 
conn->cur_query (0xb466858): ALTER TABLE tc_crc32 IMPORT TABLESPACE
 
Attempting backtrace...
 
stack_bottom = 0x0 thread_stack 0x49000
 
/mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded(my_print_stacktrace+0x32)[0x8272ff6]
 
/mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded[0x825581b]
 
/mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded[0x8255859]
 
[0xb76f5400]
 
[0xb76f5424]
 
/lib/i386-linux-gnu/libc.so.6(gsignal+0x4f)[0xb71d11ef]
 
/lib/i386-linux-gnu/libc.so.6(abort+0x175)[0xb71d4835]
 
mysys/stacktrace.c:267(my_print_stacktrace)[0x892b7e1]
 
/mnt/buildbot/build/mariadb-10.2.9/libmysqld/examples/mysqltest_embedded[0x88f9d4e]
 
ut/ut0ut.cc:840(~fatal)[0x8c448bf]
 
sync/sync0rw.cc:756(rw_lock_x_lock_func(rw_lock_t*, unsigned int, char const*, unsigned int))[0x88ff118]
 
include/sync0rw.ic:547(pfs_rw_lock_x_lock_func)[0x890ea62]
 
fil/fil0crypt.cc:1644(fil_crypt_get_page_throttle_func)[0x8b04f25]
 
fil/fil0crypt.cc:1789(fil_crypt_rotate_page)[0x8b0548a]
 
fil/fil0crypt.cc:1955(fil_crypt_rotate_pages)[0x8b05be7]
 
fil/fil0crypt.cc:2189(fil_crypt_thread)[0x8b06561]
 
/lib/i386-linux-gnu/libpthread.so.0(+0x6d4c)[0xb76d8d4c]
 
/lib/i386-linux-gnu/libc.so.6(clone+0x5e)[0xb728dace]
 
Writing a core file...

I think that we should remove SYNC_NO_ORDER_CHECK and replace any occurrences with SYNC_ANY_LATCH or similar, so that --innodb-debug-sync can be used for catching potential deadlocks in the encryption code.

Comment by Marko Mäkelä [ 2017-09-07 ]

I believe that I have an explanation for this failure now.
In MDEV-12699, I reproduced a hang of the InnoDB recovery if there exists redo log for a page that cannot be decrypted.

Comment by Elena Stepanova [ 2017-12-20 ]

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-stretch-x86/builds/1551/steps/mtr/logs/mysqld.1.err.2

10.1 7e1326cfcf5aea38f40fb787f1b987cb325c785b

 
2017-11-14 18:56:17 3067297600 [Note] /usr/sbin/mysqld: Shutdown complete
 
 
 
CURRENT_TEST: binlog.binlog_row_mix_innodb_myisam
 
2017-11-14 18:56:17 3067837184 [Note] /usr/sbin/mysqld (mysqld 10.1.30-MariaDB-1~stretch) starting as process 14382 ...
 
2017-11-14 18:56:17 3067837184 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
 
2017-11-14 18:56:17 3067837184 [Note] Plugin 'partition' is disabled.
 
2017-11-14 18:56:17 3067837184 [Note] Plugin 'SEQUENCE' is disabled.
 
innodb_open_files should not be greater than the open_files_limit.
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
 
 
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Using mutexes to ref count buffer pool pages
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: The InnoDB memory heap is disabled
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Compressed tables use zlib 1.2.8
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Using Linux native AIO
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Using generic crc32 instructions
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Completed initialization of buffer pool
 
2017-11-14 18:56:17 3067837184 [Note] InnoDB: Highest supported file format is Barracuda.
 
CURRENT_TEST: binlog.binlog_row_mix_innodb_myisam

Comment by Elena Stepanova [ 2018-02-03 ]

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-trusty-x86/builds/5173/steps/mtr/logs/stdio

worker[3] mysql-test-run: WARNING: Timeout after mysql-test-run waited 180 seconds for the process [mysqld.2 - pid: 23824, winpid: 23824] to create a pid file.
 
rpl.rpl_row_annotate_do 'row'            w3 [ fail ]
 
        Test ended at 2017-12-28 14:41:14
 
 
 
CURRENT_TEST: rpl.rpl_row_annotate_do
 
 
 
 
 
Failed to start mysqld.2
 
mysqltest failed but provided no output
 
 
 
 
 
 - saving '/run/shm/var/3/log/rpl.rpl_row_annotate_do-row/' to '/run/shm/var/log/rpl.rpl_row_annotate_do-row/'
 
 
 
Retrying test rpl.rpl_row_annotate_do, attempt(2/3)...
 
 
 
worker[3] > Restart  - not started
 
worker[3] > Restart  - not started
 
rpl.rpl_row_annotate_do 'row'            w3 [ retry-pass ]    557

http://buildbot.askmonty.org/buildbot/builders/kvm-deb-trusty-x86/builds/5173/steps/mtr/logs/mysqld.2.err.3

10.1 461cf3e5a3c2d3

 
2017-12-28 14:38:11 3054382912 [Note] /usr/sbin/mysqld: Shutdown complete
 
 
 
CURRENT_TEST: rpl.rpl_row_annotate_do
 
2017-12-28 14:38:12 3056564032 [Note] /usr/sbin/mysqld (mysqld 10.1.30-MariaDB-1~trusty) starting as process 23825 ...
 
2017-12-28 14:38:12 3056564032 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
 
2017-12-28 14:38:12 3056564032 [Note] Plugin 'partition' is disabled.
 
2017-12-28 14:38:12 3056564032 [Note] Plugin 'SEQUENCE' is disabled.
 
innodb_open_files should not be greater than the open_files_limit.
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
 
 
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Using mutexes to ref count buffer pool pages
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: The InnoDB memory heap is disabled
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Compressed tables use zlib 1.2.8
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Using Linux native AIO
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Using generic crc32 instructions
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Completed initialization of buffer pool
 
2017-12-28 14:38:12 3056564032 [Note] InnoDB: Highest supported file format is Barracuda.
 
CURRENT_TEST: rpl.rpl_row_annotate_do
 
2017-12-28 14:41:14 3056645952 [Note] /usr/sbin/mysqld (mysqld 10.1.30-MariaDB-1~trusty) starting as process 7451 ...
 
2017-12-28 14:41:14 3056645952 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'partition' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'SEQUENCE' is disabled.
 
innodb_open_files should not be greater than the open_files_limit.
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: innodb_empty_free_list_algorithm has been changed to legacy because of small buffer pool size. In order to use backoff, increase buffer pool at least up to 20MB.
 
 
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Using mutexes to ref count buffer pool pages
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: The InnoDB memory heap is disabled
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Compressed tables use zlib 1.2.8
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Using Linux native AIO
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Using generic crc32 instructions
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Initializing buffer pool, size = 8.0M
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Completed initialization of buffer pool
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Highest supported file format is Barracuda.
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: 128 rollback segment(s) are active.
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB: Waiting for purge to start
 
2017-12-28 14:41:14 3056645952 [Note] InnoDB:  Percona XtraDB (http://www.percona.com) 5.6.36-82.2 started; log sequence number 1629444
 
2017-12-28 14:41:14 2738879296 [Note] InnoDB: Dumping buffer pool(s) not yet started
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'XTRADB_RSEG' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMP_PER_INDEX' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_TRX' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_LOCKS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'FEEDBACK' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMP' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_MUTEXES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_BUFFER_PAGE_LRU' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_FOREIGN_COLS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_BUFFER_POOL_STATS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_FOREIGN' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_METRICS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMPMEM' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_TABLES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_BUFFER_PAGE' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_CHANGED_PAGES' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
 
2017-12-28 14:41:14 3056645952 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
 
2017-12-28 14:41:14 3056645952 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-startup-interval=20'
 
2017-12-28 14:41:14 3056645952 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-first-interval=60'
 
2017-12-28 14:41:14 3056645952 [Warning] /usr/sbin/mysqld: unknown variable 'loose-feedback-debug-interval=60'
 
2017-12-28 14:41:14 3056645952 [Warning] /usr/sbin/mysqld: unknown option '--loose-pam-debug'
 
2017-12-28 14:41:14 3056645952 [Warning] /usr/sbin/mysqld: unknown variable 'loose-debug-sync-timeout=300'
 
2017-12-28 14:41:14 3056645952 [Note] Server socket created on IP: '127.0.0.1'.
 
2017-12-28 14:41:14 3056645952 [Note] /usr/sbin/mysqld: ready for connections.

Comment by Marko Mäkelä [ 2019-09-27 ]

There have been no reports of this since MDEV-12699 was closed. So, I would claim that this is a duplicate of MDEV-12699.

Generated at Thu Feb 08 08:01:21 UTC 2024 using Jira 8.20.16#820016-sha1:9d11dbea5f4be3d4cc21f03a88dd11d8c8687422.