[MDEV-15282] innodb.autoinc_persist failed in buildbot, Assertion failed: recv_sys->mlog_checkpoint_lsn <= recv_sys->recovered_lsn Created: 2018-02-11  Updated: 2020-07-30  Resolved: 2020-07-30

Status: Closed
Project: MariaDB Server
Component/s: Storage Engine - InnoDB, Tests
Affects Version/s: 10.2
Fix Version/s: 10.2.33, 10.3.24, 10.4.14, 10.5.5

Type: Bug Priority: Major
Reporter: Elena Stepanova Assignee: Thirunarayanan Balathandayuthapani
Resolution: Fixed Votes: 0
Labels: None

Issue Links:
Duplicate
is duplicated by MDEV-13830 Assertion failed: recv_sys->mlog_chec... Closed
Relates
relates to MDEV-13080 [ERROR] InnoDB: Missing MLOG_CHECKPOI... Closed
relates to MDEV-13830 Assertion failed: recv_sys->mlog_chec... Closed

 Description   

http://buildbot.askmonty.org/buildbot/builders/winx64-debug/builds/7306/steps/test/logs/stdio

innodb.autoinc_persist 'innodb'          w1 [ fail ]
        Test ended at 2018-02-10 13:21:14
 
CURRENT_TEST: innodb.autoinc_persist
 
 
Server [mysqld.1 - pid: 3708, winpid: 3708, exit: 65280] failed during test run
Server log from this test:
----------SERVER LOG START-----------
2018-02-10 13:20:20 3160 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (root[root] @ localhost [::1]): Normal shutdown
2018-02-10 13:20:20 3160 [Note] Event Scheduler: Purging the queue. 0 events
2018-02-10 13:20:20 508 [Note] InnoDB: FTS optimize thread exiting.
2018-02-10 13:20:20 3160 [Note] InnoDB: Starting shutdown...
2018-02-10 13:20:20 4792 [Note] InnoDB: Dumping buffer pool(s) to D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:20 4792 [Note] InnoDB: Instance 0, restricted to 128 pages due to innodb_buf_pool_dump_pct=25
2018-02-10 13:20:20 4792 [Note] InnoDB: Buffer pool(s) dump completed at 180210 13:20:20
2018-02-10 13:20:22 3160 [Note] InnoDB: Shutdown completed; log sequence number 3755423
2018-02-10 13:20:22 3160 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2018-02-10 13:20:22 3160 [Note] Debug sync points hit:                   6274
2018-02-10 13:20:22 3160 [Note] Debug sync points executed:              10
2018-02-10 13:20:22 3160 [Note] Debug sync points max active per thread: 1
2018-02-10 13:20:22 3160 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: Shutdown complete
 
2018-02-10 13:20:23 7040 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.13-MariaDB-debug-log) starting as process 5664 ...
2018-02-10 13:20:23 7040 [Note] Plugin 'partition' is disabled.
2018-02-10 13:20:23 7040 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2018-02-10 13:20:23 7040 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2018-02-10 13:20:23 7040 [Note] InnoDB: Uses event mutexes
2018-02-10 13:20:23 7040 [Note] InnoDB: Compressed tables use zlib 1.2.3
2018-02-10 13:20:23 7040 [Note] InnoDB: Number of pools: 1
2018-02-10 13:20:23 7040 [Note] InnoDB: Using SSE2 crc32 instructions
2018-02-10 13:20:23 7040 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
2018-02-10 13:20:23 7040 [Note] InnoDB: Completed initialization of buffer pool
2018-02-10 13:20:23 7040 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-10 13:20:26 7040 [Note] InnoDB: 128 out of 128 rollback segments are active.
2018-02-10 13:20:27 7040 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-10 13:20:27 7040 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-10 13:20:27 7040 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
2018-02-10 13:20:28 7040 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:28 7040 [Note] InnoDB: 5.7.21 started; log sequence number 3755423
2018-02-10 13:20:28 7040 [Note] Plugin 'SEQUENCE' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_MUTEXES' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_CMP' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'user_variables' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
2018-02-10 13:20:28 7040 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
2018-02-10 13:20:28 7040 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
2018-02-10 13:20:28 7040 [Note] Server socket created on IP: '::'.
2018-02-10 13:20:29 4652 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:29 7040 [Note] Reading of all Master_info entries succeded
2018-02-10 13:20:29 7040 [Note] Added new Master_info '' to hash table
2018-02-10 13:20:29 7040 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
Version: '10.2.13-MariaDB-debug-log'  socket: ''  port: 16000  Source distribution
2018-02-10 13:20:29 4652 [Note] InnoDB: Buffer pool(s) load completed at 180210 13:20:29
2018-02-10 13:20:31 5424 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (root[root] @ localhost [127.0.0.1]): Normal shutdown
2018-02-10 13:20:31 5424 [Note] Event Scheduler: Purging the queue. 0 events
2018-02-10 13:20:31 7332 [Note] InnoDB: FTS optimize thread exiting.
2018-02-10 13:20:31 5424 [Note] InnoDB: Starting shutdown...
2018-02-10 13:20:31 4652 [Note] InnoDB: Dumping buffer pool(s) to D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:31 4652 [Note] InnoDB: Instance 0, restricted to 128 pages due to innodb_buf_pool_dump_pct=25
2018-02-10 13:20:31 4652 [Note] InnoDB: Buffer pool(s) dump completed at 180210 13:20:31
2018-02-10 13:20:33 5424 [Note] InnoDB: Shutdown completed; log sequence number 3777080
2018-02-10 13:20:33 5424 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2018-02-10 13:20:33 5424 [Note] Debug sync points hit:                   3495
2018-02-10 13:20:33 5424 [Note] Debug sync points executed:              0
2018-02-10 13:20:33 5424 [Note] Debug sync points max active per thread: 0
2018-02-10 13:20:33 5424 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: Shutdown complete
 
2018-02-10 13:20:34 6696 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.13-MariaDB-debug-log) starting as process 4056 ...
2018-02-10 13:20:34 6696 [Note] Plugin 'partition' is disabled.
2018-02-10 13:20:35 6696 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2018-02-10 13:20:35 6696 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2018-02-10 13:20:35 6696 [Note] InnoDB: Uses event mutexes
2018-02-10 13:20:35 6696 [Note] InnoDB: Compressed tables use zlib 1.2.3
2018-02-10 13:20:35 6696 [Note] InnoDB: Number of pools: 1
2018-02-10 13:20:35 6696 [Note] InnoDB: Using SSE2 crc32 instructions
2018-02-10 13:20:35 6696 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
2018-02-10 13:20:35 6696 [Note] InnoDB: Completed initialization of buffer pool
2018-02-10 13:20:35 6696 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-10 13:20:38 6696 [Note] InnoDB: 128 out of 128 rollback segments are active.
2018-02-10 13:20:38 6696 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-10 13:20:38 6696 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-10 13:20:38 6696 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
2018-02-10 13:20:39 6696 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:39 6696 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:39 6696 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:39 6696 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:39 6696 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:39 6696 [Note] InnoDB: 5.7.21 started; log sequence number 3777080
2018-02-10 13:20:40 3544 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:40 3544 [Note] InnoDB: Buffer pool(s) load completed at 180210 13:20:40
2018-02-10 13:20:40 6696 [Note] Plugin 'SEQUENCE' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_MUTEXES' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_CMP' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'user_variables' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
2018-02-10 13:20:40 6696 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
2018-02-10 13:20:40 6696 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
2018-02-10 13:20:40 6696 [Note] Server socket created on IP: '::'.
2018-02-10 13:20:40 6696 [Note] Reading of all Master_info entries succeded
2018-02-10 13:20:40 6696 [Note] Added new Master_info '' to hash table
2018-02-10 13:20:40 6696 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
Version: '10.2.13-MariaDB-debug-log'  socket: ''  port: 16000  Source distribution
2018-02-10 13:20:42 4396 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.13-MariaDB-debug-log) starting as process 3964 ...
2018-02-10 13:20:42 4396 [Note] Plugin 'partition' is disabled.
2018-02-10 13:20:42 4396 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2018-02-10 13:20:42 4396 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2018-02-10 13:20:42 4396 [Note] InnoDB: Uses event mutexes
2018-02-10 13:20:42 4396 [Note] InnoDB: Compressed tables use zlib 1.2.3
2018-02-10 13:20:42 4396 [Note] InnoDB: Number of pools: 1
2018-02-10 13:20:42 4396 [Note] InnoDB: Using SSE2 crc32 instructions
2018-02-10 13:20:42 4396 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
2018-02-10 13:20:42 4396 [Note] InnoDB: Completed initialization of buffer pool
2018-02-10 13:20:42 4396 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-10 13:20:42 4396 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3777080
2018-02-10 13:20:47 4396 [Note] InnoDB: Starting final batch to recover 7 pages from redo log.
2018-02-10 13:20:48 4396 [Note] InnoDB: 128 out of 128 rollback segments are active.
2018-02-10 13:20:48 4396 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2018-02-10 13:20:48 4396 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-10 13:20:48 4396 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-10 13:20:48 4396 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
2018-02-10 13:20:50 4396 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:50 4396 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:50 4396 [Note] InnoDB: 5.7.21 started; log sequence number 3780219
2018-02-10 13:20:50 4396 [Note] Plugin 'SEQUENCE' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_MUTEXES' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_CMP' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'user_variables' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
2018-02-10 13:20:50 4396 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
2018-02-10 13:20:50 4396 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
2018-02-10 13:20:50 4396 [Note] Server socket created on IP: '::'.
2018-02-10 13:20:50 2096 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:50 4396 [Note] Reading of all Master_info entries succeded
2018-02-10 13:20:50 4396 [Note] Added new Master_info '' to hash table
2018-02-10 13:20:50 2096 [Note] InnoDB: Buffer pool(s) load completed at 180210 13:20:50
2018-02-10 13:20:50 4396 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
Version: '10.2.13-MariaDB-debug-log'  socket: ''  port: 16000  Source distribution
2018-02-10 13:20:53 5200 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.13-MariaDB-debug-log) starting as process 656 ...
2018-02-10 13:20:53 5200 [Note] Plugin 'partition' is disabled.
2018-02-10 13:20:54 5200 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2018-02-10 13:20:54 5200 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2018-02-10 13:20:54 5200 [Note] InnoDB: Uses event mutexes
2018-02-10 13:20:54 5200 [Note] InnoDB: Compressed tables use zlib 1.2.3
2018-02-10 13:20:54 5200 [Note] InnoDB: Number of pools: 1
2018-02-10 13:20:54 5200 [Note] InnoDB: Using SSE2 crc32 instructions
2018-02-10 13:20:54 5200 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
2018-02-10 13:20:54 5200 [Note] InnoDB: Completed initialization of buffer pool
2018-02-10 13:20:54 5200 [Note] InnoDB: Highest supported file format is Barracuda.
2018-02-10 13:20:54 5200 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3777080
2018-02-10 13:20:55 5200 [Note] InnoDB: Starting final batch to recover 27 pages from redo log.
2018-02-10 13:20:56 5200 [Note] InnoDB: 128 out of 128 rollback segments are active.
2018-02-10 13:20:56 5200 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
2018-02-10 13:20:56 5200 [Note] InnoDB: Creating shared tablespace for temporary tables
2018-02-10 13:20:56 5200 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
2018-02-10 13:20:56 5200 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
2018-02-10 13:20:57 5200 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:57 5200 [Note] InnoDB: Waiting for purge to start
2018-02-10 13:20:57 5200 [Note] InnoDB: 5.7.21 started; log sequence number 3816146
2018-02-10 13:20:57 7376 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\ib_buffer_pool
2018-02-10 13:20:58 5200 [Note] Plugin 'SEQUENCE' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_MUTEXES' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_CMP' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'FEEDBACK' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'user_variables' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
2018-02-10 13:20:58 7376 [Note] InnoDB: Buffer pool(s) load completed at 180210 13:20:58
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
2018-02-10 13:20:58 5200 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
2018-02-10 13:20:58 5200 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
2018-02-10 13:20:58 5200 [Note] Server socket created on IP: '::'.
2018-02-10 13:20:58 5200 [Note] Reading of all Master_info entries succeded
2018-02-10 13:20:58 5200 [Note] Added new Master_info '' to hash table
2018-02-10 13:20:58 5200 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
Version: '10.2.13-MariaDB-debug-log'  socket: ''  port: 16000  Source distribution
2018-02-10 13:21:03 6620 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.13-MariaDB-debug-log) starting as process 7596 ...
2018-02-10 13:21:03 6620 [Note] Plugin 'partition' is disabled.
2018-02-10 13:21:03 6620 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
2018-02-10 13:21:03 6620 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
2018-02-10 13:21:03 6620 [Note] InnoDB: Uses event mutexes
2018-02-10 13:21:03 6620 [Note] InnoDB: Compressed tables use zlib 1.2.3
2018-02-10 13:21:03 6620 [Note] InnoDB: Number of pools: 1
2018-02-10 13:21:03 6620 [Note] InnoDB: Using SSE2 crc32 instructions
2018-02-10 13:21:03 6620 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
2018-02-10 13:21:03 6620 [Note] InnoDB: Completed initialization of buffer pool
2018-02-10 13:21:03 6620 [Note] InnoDB: Highest supported file format is Barracuda.

2018-02-10 13:21:03 6620 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3816262
2018-02-10 13:21:03 6620 [Note] InnoDB: Ignoring data file '.\test\t19.ibd' with space ID 80. Another data file called .\test\t9.ibd exists with the same space ID.
Assertion failed: recv_sys->mlog_checkpoint_lsn <= recv_sys->recovered_lsn, file D:\winx64-debug\build\src\storage\innobase\log\log0recv.cc, line 2430
180210 13:21:03 [ERROR] mysqld got exception 0x80000003 ;
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.2.13-MariaDB-debug-log
key_buffer_size=1048576
read_buffer_size=131072
max_used_connections=0
max_threads=65537
thread_count=0
It is possible that mysqld could use up to 
key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 4227 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...
mysqld.exe!my_sigabrt_handler()[my_thr_init.c:487]
mysqld.exe!raise()[signal.cpp:516]
mysqld.exe!abort()[abort.cpp:71]
mysqld.exe!common_assert_to_stderr<wchar_t>()[assert.cpp:149]
mysqld.exe!_wassert()[assert.cpp:404]
mysqld.exe!recv_parse_log_recs()[log0recv.cc:2429]
mysqld.exe!recv_scan_log_recs()[log0recv.cc:2907]
mysqld.exe!recv_group_scan_log_recs()[log0recv.cc:2996]
mysqld.exe!recv_recovery_from_checkpoint_start()[log0recv.cc:3285]
mysqld.exe!innobase_start_or_create_for_mysql()[srv0start.cc:2246]
mysqld.exe!innobase_init()[ha_innodb.cc:4367]
mysqld.exe!ha_initialize_handlerton()[handler.cc:520]
mysqld.exe!plugin_initialize()[sql_plugin.cc:1416]
mysqld.exe!plugin_init()[sql_plugin.cc:1697]
mysqld.exe!init_server_components()[mysqld.cc:5266]
mysqld.exe!win_main()[mysqld.cc:5858]
mysqld.exe!mysql_service()[mysqld.cc:6114]
mysqld.exe!mysqld_main()[mysqld.cc:6307]
mysqld.exe!main()[main.cc:26]
mysqld.exe!__scrt_common_main_seh()[exe_common.inl:283]
KERNEL32.DLL!BaseThreadInitThunk()
ntdll.dll!RtlUserThreadStart()
The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
information that should help you find out what is causing the crash.
Writing a core file at D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\
Minidump written to D:\winx64-debug\build\mysql-test\var\1\mysqld.1\data\mysqld.dmp
----------SERVER LOG END-------------
mysqltest failed but provided no output
The result from queries just before the failure was:
< snip >
# MDEV-6076: Test adding an AUTO_INCREMENT COLUMN
CREATE TABLE mdev6076a (b INT) ENGINE=InnoDB;
INSERT INTO mdev6076a VALUES(2),(1);
CREATE TABLE mdev6076b (b INT) ENGINE=InnoDB;
INSERT INTO mdev6076b VALUES(2),(1);
ALTER TABLE mdev6076a ADD COLUMN a SERIAL FIRST, LOCK=NONE;
ERROR 0A000: LOCK=NONE is not supported. Reason: Adding an auto-increment column requires a lock. Try LOCK=SHARED
ALTER TABLE mdev6076a ADD COLUMN a SERIAL FIRST, ALGORITHM=INPLACE;
ALTER TABLE mdev6076b ADD COLUMN a SERIAL FIRST, AUTO_INCREMENT=100,
ALGORITHM=INPLACE;
# MDEV-6076: Test root page split and page_create_empty()
CREATE TABLE mdev6076empty (b SERIAL, pad CHAR(255) NOT NULL DEFAULT '')
ENGINE=InnoDB;
BEGIN;
# Insert records in descending order of AUTO_INCREMENT,
# causing a page split on the very last insert.
# Without the fix in btr_page_empty() this would lose the counter value.
# Without the fix in page_create_empty() the counter value would be lost
# when ROLLBACK deletes the last row.
ROLLBACK;
 
 
 
 - saving 'D:/winx64-debug/build/mysql-test/var/1/log/innodb.autoinc_persist-innodb/' to 'D:/winx64-debug/build/mysql-test/var/log/innodb.autoinc_persist-innodb/'
 - found 'mysqld.dmp' (0/5)
 
Trying 'cdb' to get a backtrace
Output from cdb follows. Faulting thread is printed twice,with and without function parameters
Search for STACK_TEXT to see the stack trace of 
the faulting thread. Callstacks of other threads are printed after it.
 
Microsoft (R) Windows Debugger Version 10.0.16299.15 AMD64
Copyright (c) Microsoft Corporation. All rights reserved.

Loading Dump File [D:\winx64-debug\build\mysql-test\var\log\innodb.autoinc_persist-innodb\mysqld.1\data\mysqld.dmp]
User Mini Dump File: Only registers, stack and portions of memory are available
 
 
Response                         Time (ms)     Location
OK                                             C:\Windows\System32
OK                                             D:\winx64-debug\build\sql\Debug
OK                                             .
 
Response                         Time (ms)     Location
OK                                             C:\Windows\System32
OK                                             D:\winx64-debug\build\sql\Debug
OK                                             .
Deferred                                       cache*C:\cdb_symbols
Symbol search path is: C:\Windows\System32;D:\winx64-debug\build\sql\Debug;.;cache*C:\cdb_symbols
Executable search path is: C:\Windows\System32;D:\winx64-debug\build\sql\Debug;.
Windows 8.1 Version 9600 MP (4 procs) Free x64
Product: Server, suite: TerminalServer DataCenter SingleUserTS
6.3.9600.18217 (winblue_ltsb.160124-0053)
Machine Name:
Debug session time: Sat Feb 10 13:21:10.000 2018 (UTC + 0:00)
System Uptime: not available
Process Uptime: 0 days 0:00:07.000
........................
This dump file has a breakpoint exception stored in it.
The stored exception information can be accessed via .ecxr.
ntdll!NtGetContextThread:
             ret
0:000> cdb: Reading initial command '!sym prompts off; !analyze -v; .ecxr; !for_each_frame dv /t;!uniqstack -p;q'
quiet mode - symbol prompts off
 
 
 
DUMP_CLASS: 2
 
DUMP_QUALIFIER: 400
 
CONTEXT:  (.ecxr)
rax=00007ff721b9d5dc rbx=00007ff723f958a0 rcx=0000000000000016
rdx=00008b7162a70bdf rsi=00007ff722301d01 rdi=0000000000000016
rip=00007ff721a10385 rsp=0000001123566090 rbp=0000001123566250
 r8=0000001123566120  r9=0000001123566128 r10=0000000000000000
r11=0000001123566118 r12=0000000000000910 r13=0000000000000000
r14=000000000000097e r15=00007ff721a10380
iopl=0         nv up ei pl nz ac pe nc
cs=0033  ss=002b  ds=002b  es=002b  fs=0053  gs=002b             efl=00000212
mysqld!my_sigabrt_handler:
             int     3
Resetting default scope
 
FAULTING_IP: 
mysqld!my_sigabrt_handler+5 [d:\winx64-debug\build\src\mysys\my_thr_init.c @ 487]
             int     3
 
EXCEPTION_RECORD:  (.exr -1)
ExceptionAddress: 00007ff721a10385 (mysqld!my_sigabrt_handler)
   ExceptionCode: 80000003 (Break instruction exception)
  ExceptionFlags: 00000000
NumberParameters: 1
   Parameter[0]: 0000000000000000
 
BUGCHECK_STR:  5A4B1B67
 
WRONG_SYMBOLS_TIMESTAMP: 5a4b1b67
 
WRONG_SYMBOLS_SIZE: 1ad000
 
FAULTING_MODULE: 00007ffa6fba0000 ntdll
 
DEBUG_FLR_IMAGE_TIMESTAMP:  5a4b1b67
 
ADDITIONAL_DEBUG_TEXT:  
You can run '.symfix; .reload' to try to fix the symbol path and load symbols. ; Followup set based on attribute [Is_ChosenCrashFollowupThread] from Frame:[0] on thread:[PSEUDO_THREAD]
 
ANALYSIS_SESSION_HOST:  MARIADB-02
 
ANALYSIS_SESSION_TIME:  02-10-2018 13:21:16.0559
 
ANALYSIS_VERSION: 10.0.16299.15 amd64fre
 
STACK_TEXT:  
WRONG_SYMBOLS!WRONG_SYMBOLS
 
 
THREAD_SHA1_HASH_MOD_FUNC:  2a06fe893fc51638e55bcc8ee02bcdf6f10cbc26
 
THREAD_SHA1_HASH_MOD_FUNC_OFFSET:  214d5e958d92c59434e5414a89d1e95c2f82d12a
 
THREAD_SHA1_HASH_MOD:  79d1e41e8e0e291e73ec18352c568efa4ef4b5ab
 
SYMBOL_STACK_INDEX:  0
 
FOLLOWUP_NAME:  MachineOwner
 
STACK_COMMAND:  .ecxr ; kb ; ** Pseudo Context ** ManagedPseudo ** Value: a9b88da320 ** ; kb
 
EXCEPTION_CODE: (NTSTATUS) 0x5a4b1b67 - <Unable to get error code text>
 
EXCEPTION_CODE_STR:  5A4B1B67
 
EXCEPTION_STR:  PRIVATE_SYMBOLS
 
PROCESS_NAME:  ntdll.wrong.symbols.dll
 
IMAGE_NAME:  ntdll.wrong.symbols.dll
 
MODULE_NAME: ntdll_wrong_symbols
 
SYMBOL_NAME:  ntdll_wrong_symbols!5A4B1B671AD000
 
BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_180102-054055
 
DEFAULT_BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_180102-054055
 
PRIMARY_PROBLEM_CLASS:  PRIVATE_SYMBOLS
 
FAILURE_EXCEPTION_CODE:  5A4B1B67
 
FAILURE_IMAGE_NAME:  ntdll.wrong.symbols.dll
 
BUCKET_ID_IMAGE_STR:  ntdll.wrong.symbols.dll
 
FAILURE_MODULE_NAME:  ntdll_wrong_symbols
 
BUCKET_ID_MODULE_STR:  ntdll_wrong_symbols
 
FAILURE_FUNCTION_NAME:  5A4B1B671AD000
 
BUCKET_ID_FUNCTION_STR:  5A4B1B671AD000
 
BUCKET_ID_OFFSET:  0
 
BUCKET_ID_MODTIMEDATESTAMP:  0
 
BUCKET_ID_MODCHECKSUM:  0
 
BUCKET_ID_MODVER_STR:  0.0.0.0
 
BUCKET_ID_PREFIX_STR:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_180102-054055
 
FAILURE_PROBLEM_CLASS:  PRIVATE_SYMBOLS
 
FAILURE_SYMBOL_NAME:  ntdll.wrong.symbols.dll!5A4B1B671AD000
 
FAILURE_BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_180102-054055_5A4B1B67_ntdll.wrong.symbols.dll!5A4B1B671AD000
 
TARGET_TIME:  2018-02-10T13:21:10.000Z
 
OSBUILD:  9600
 
OSSERVICEPACK:  17415
 
SERVICEPACK_NUMBER: 0
 
OS_REVISION: 0
 
SUITE_MASK:  400
 
PRODUCT_TYPE:  3
 
OSPLATFORM_TYPE:  x64
 
OSNAME:  Windows 8.1
 
OSEDITION:  Windows 8.1 Server TerminalServer DataCenter SingleUserTS
 
OS_LOCALE:  
 
USER_LCID:  0
 
OSBUILD_TIMESTAMP:  2014-10-29 02:45:30
 
BUILDDATESTAMP_STR:  141028-1500
 
BUILDLAB_STR:  winblue_r4
 
BUILDOSVER_STR:  6.3.9600.17415
 
ANALYSIS_SESSION_ELAPSED_TIME:  10e8
 
ANALYSIS_SOURCE:  UM
 
FAILURE_ID_HASH_STRING:  um:private_symbols_x64_6.3.9600.17415_(winblue_r4.141028-1500)_timestamp_180102-054055_5a4b1b67_ntdll.wrong.symbols.dll!5a4b1b671ad000
 
FAILURE_ID_HASH:  {9a834c3a-3c1a-97bb-5342-e960e8a6752a}
 
Followup:     MachineOwner
---------
 
rax=00007ff721b9d5dc rbx=00007ff723f958a0 rcx=0000000000000016
rdx=00008b7162a70bdf rsi=00007ff722301d01 rdi=0000000000000016
rip=00007ff721a10385 rsp=0000001123566090 rbp=0000001123566250
 r8=0000001123566120  r9=0000001123566128 r10=0000000000000000
r11=0000001123566118 r12=0000000000000910 r13=0000000000000000
r14=000000000000097e r15=00007ff721a10380
iopl=0         nv up ei pl nz ac pe nc
cs=0033  ss=002b  ds=002b  es=002b  fs=0053  gs=002b             efl=00000212
mysqld!my_sigabrt_handler:
             int     3
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!my_sigabrt_handler [d:\winx64-debug\build\src\mysys\my_thr_init.c @ 487]
int sig = 0n22
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!raise [d:\th\minkernel\crts\ucrt\src\appcrt\misc\signal.cpp @ 516]
int signum = 0n22
int old_fpecode = 0n0
struct _EXCEPTION_POINTERS * old_pxcptinfoptrs = 0x00000000`00000000
bool return0 = false
bool action_is_global = true
<function> * action = 0x00007ff7`21a10380
struct __acrt_ptd * ptd = 0x00000000`00000000
<function> ** action_pointer = 0x00007ff7`23f958a0
struct __crt_signal_action_t * local_action = <value unavailable>
int _Expr_val = <value unavailable>
struct __crt_signal_action_t * last = <value unavailable>
struct __crt_signal_action_t * first = <value unavailable>
struct __crt_signal_action_t * p = 0x00000011`23566100
unsigned int64 __acrt_signal_action_table_count = <value unavailable>
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!abort [d:\th\minkernel\crts\ucrt\src\appcrt\startup\abort.cpp @ 71]
<function> * sigabrt_action = 0x00007ff7`21b9d5dc
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!common_assert_to_stderr<wchar_t> [d:\th\minkernel\crts\ucrt\src\appcrt\startup\assert.cpp @ 149]
wchar_t * expression = 0x00007ff7`22301e50 "recv_sys->mlog_checkpoint_lsn <= recv_sys->recovered_lsn"
wchar_t * file_name = 0x00007ff7`22301d20 "D:\winx64-debug\build\src\storage\innobase\log\log0recv.cc"
unsigned int line_number = 0x97e
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
(Inline Function) --------`-------- mysqld!common_assert [d:\th\minkernel\crts\ucrt\src\appcrt\startup\assert.cpp @ 383]
void * return_address = 0x00007ff7`214d12ef
int current_error_mode = <value unavailable>
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!_wassert [d:\th\minkernel\crts\ucrt\src\appcrt\startup\assert.cpp @ 404]
wchar_t * expression = 0x00007ff7`22301e50 "recv_sys->mlog_checkpoint_lsn <= recv_sys->recovered_lsn"
wchar_t * file_name = 0x00007ff7`22301d20 "D:\winx64-debug\build\src\storage\innobase\log\log0recv.cc"
unsigned int line_number = 0x97e
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!recv_parse_log_recs [d:\winx64-debug\build\src\storage\innobase\log\log0recv.cc @ 2429]
unsigned int64 lsn = 0x39aacc
unsigned int64 checkpoint_lsn = 0x39aacc
store_t store = STORE_YES (0n1)
bool apply = true
unsigned int64 old_lsn = 0x3a3ac9
unsigned char * body = 0x00000000`00000000 ""
unsigned char * ptr = 0x00000011`295b8bbd "--- memory read error at address 0x00000011`295b8bbd ---"
unsigned int64 new_recovered_lsn = 0x3a3ad2
unsigned int64 page_no = 0
unsigned char * end_ptr = 0x00000011`295bf780 "--- memory read error at address 0x00000011`295bf780 ---"
bool single_rec = true
unsigned int64 len = 9
mlog_id_t type = MLOG_CHECKPOINT (0n56)
unsigned int64 space = 0x50
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!recv_scan_log_recs [d:\winx64-debug\build\src\storage\innobase\log\log0recv.cc @ 2907]
unsigned int64 available_memory = 0x400000
store_t * store_to_hash = 0x00000011`23566d24
unsigned char * log_block = 0x00000011`293bc200 "--- memory read error at address 0x00000011`293bc200 ---"
unsigned int64 checkpoint_lsn = 0x39aacc
unsigned int64 start_lsn = 0x39aa00
unsigned int64 end_lsn = 0x3aaa00
unsigned int64 * contiguous_lsn = 0x00000011`23566df8
unsigned int64 * group_scanned_lsn = 0x00000011`28266d88
unsigned char * log_end = 0x00000011`293bc200 "--- memory read error at address 0x00000011`293bc200 ---"
bool more_data = true
unsigned int64 data_len = 0x200
unsigned int64 scanned_lsn = 0x3aaa00
bool finished = false
bool apply = true
unsigned int64 recv_parsing_buf_size = 0x200000
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!recv_group_scan_log_recs [d:\winx64-debug\build\src\storage\innobase\log\log0recv.cc @ 2996]
struct log_group_t * group = 0x00000011`28266d48
unsigned int64 checkpoint_lsn = 0x39aacc
unsigned int64 * contiguous_lsn = 0x00000011`23566df8
bool last_phase = false
store_t store_to_hash = STORE_YES (0n1)
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
unsigned int64 end_lsn = 0x3aaa00
unsigned int64 start_lsn = 0x39aa00
unsigned int64 available_mem = 0x400000
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!recv_recovery_from_checkpoint_start [d:\winx64-debug\build\src\storage\innobase\log\log0recv.cc @ 3285]
unsigned int64 flush_lsn = 0x39a238
unsigned int64 max_cp_field = 0x600
unsigned int64 checkpoint_lsn = 0x39aacc
dberr_t err = DB_SUCCESS (0n10)
bool rescan = true
unsigned int64 checkpoint_no = 0x15
unsigned int64 end_lsn = 0x3a3b46
unsigned int64 contiguous_lsn = 0x3aa200
unsigned char * buf = 0x00000011`28267000 "--- memory read error at address 0x00000011`28267000 ---"
struct log_group_t * group = 0x00000011`28266d48
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!innobase_start_or_create_for_mysql [d:\winx64-debug\build\src\storage\innobase\srv\srv0start.cc @ 2246]
unsigned int64 sum_of_data_file_sizes = 0xcccccccc`cccccccc
unsigned int64 tablespace_size_in_header = 0xcccccccc`cccccccc
char unit = 0n77 'M'
double chunk_size = 8
dberr_t err = DB_SUCCESS (0n10)
double size = 8
unsigned int64 srv_n_log_files_found = 2
unsigned int64 dirnamelen = 2
unsigned int i = 2
unsigned int64 flushed_lsn = 0x39a238
unsigned int64 sum_of_new_sizes = 0
char * logfile0 = 0x00000000`00000000 ""
bool create_new_db = false
char [10000] logfilename = char [10000] ".\ib_logfile1"
char chunk_unit = 0n77 'M'
struct mtr_t mtr = struct mtr_t
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!innobase_init [d:\winx64-debug\build\src\storage\innobase\handler\ha_innodb.cc @ 4367]
void * p = 0x00000011`27f2f658
unsigned int64 srv_buf_pool_size_org = 0
char * default_path = 0x00007ff7`23727a40 "--- memory read error at address 0x00007ff7`23727a40 ---"
char [3] test_filename = char [3] "-@"
int err = 0n-858993460
char [12] test_tablename = char [12] "#mysql50#-@"
unsigned int format_id = 1
int count = 0n1
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
struct handlerton * innobase_hton = 0x00000011`27f2f658
unsigned long num_pll_degree = 2
char [3] current_dir = char [3] "--- memory read error at address 0x00007ff7`23727a40 ---"
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!ha_initialize_handlerton [d:\winx64-debug\build\src\sql\handler.cc @ 520]
struct st_plugin_int * plugin = 0x00000011`28032a18
struct handlerton * hton = 0x00000011`27f2f658
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
char *[1] no_exts = char *[1]
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!plugin_initialize [d:\winx64-debug\build\src\sql\sql_plugin.cc @ 1416]
struct st_mem_root * tmp_root = 0x00000011`2356ea68
struct st_plugin_int * plugin = 0x00000011`28032a18
int * argc = 0x00007ff7`23701704
char ** argv = 0x00000011`23682588
bool options_only = false
int ret = 0n1
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
unsigned int state = 4
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!plugin_init [d:\winx64-debug\build\src\sql\sql_plugin.cc @ 1697]
unsigned int idx = 7
struct st_hash * hash = 0x00007ff7`23702120
int * argc = 0x00007ff7`23701704
char ** argv = 0x00000011`23682588
int flags = 0n2
struct st_plugin_int ** reap = 0x00000011`2356e7c8
struct st_mem_root tmp_root = struct st_mem_root
unsigned int i = 2
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
struct st_plugin_int * plugin_ptr = 0x00000011`28032a18
struct st_maria_plugin * plugin = 0x00007ff7`234fd848
struct st_plugin_int tmp = struct st_plugin_int
bool mandatory = false
struct st_maria_plugin ** builtins = 0x00007ff7`234844c8
bool reaped_mandatory_plugin = false
struct st_mysql_lex_string MyISAM = struct st_mysql_lex_string
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!init_server_components [d:\winx64-debug\build\src\sql\mysqld.cc @ 5266]
struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!win_main [d:\winx64-debug\build\src\sql\mysqld.cc @ 5858]
int argc = 0n145
char ** argv = 0x00000011`23682588
int ho_error = 0n0
unsigned int64 new_thread_stack_size = 0x49000
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!mysql_service [d:\winx64-debug\build\src\sql\mysqld.cc @ 6114]
void * p = 0x00000000`00000000
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!mysqld_main [d:\winx64-debug\build\src\sql\mysqld.cc @ 6307]
int argc = 0n22
char ** argv = 0x00000011`236721b0
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!main [d:\winx64-debug\build\src\sql\main.cc @ 26]
int argc = 0n22
char ** argv = 0x00000011`236721b0
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
(Inline Function) --------`-------- mysqld!invoke_main [f:\dd\vctools\crt\vcstartup\src\startup\exe_common.inl @ 78]
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!__scrt_common_main_seh [f:\dd\vctools\crt\vcstartup\src\startup\exe_common.inl @ 283]
bool has_cctor = false
int main_result = <value unavailable>
<function> ** tls_init_callback = <value unavailable>
bool is_nested = <value unavailable>
<function> ** tls_dtor_callback = <value unavailable>
int main_result = <value unavailable>
__scrt_native_startup_state __scrt_current_native_startup_state = <value unavailable>
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
kernel32!BaseThreadInitThunk
Unable to enumerate locals, Win32 error 0n87
Private symbols (symbols.pri) are required for locals.
Type ".hh dbgerr005" for details.
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
ntdll!RtlUserThreadStart
Unable to enumerate locals, Win32 error 0n318
Private symbols (symbols.pri) are required for locals.
Type ".hh dbgerr005" for details.
_ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
mysqld!my_sigabrt_handler [d:\winx64-debug\build\src\mysys\my_thr_init.c @ 487]
Processing 9 threads, please wait
 
.  0  Id: 1dac.19dc Suspend: 0 Teb: 00007ff7`203de000 Unfrozen
      Priority: 0  Priority class: 32
 
ntdll!NtGetContextThread
ntdll!RtlAllocateHeap
ntdll!RtlAllocateHeap
kernel32!MakeSortNode
 
.  1  Id: 1dac.cb4 Suspend: 0 Teb: 00007ff7`203dc000 Unfrozen
      Priority: 0  Priority class: 32
 
ntdll!ZwWaitForAlertByThreadId
ntdll!RtlSleepConditionVariableCS
KERNELBASE!SleepConditionVariableCS
mysqld!pthread_cond_timedwait(struct _RTL_CONDITION_VARIABLE * cond = 0x00007ff7`23f88ed8, struct _RTL_CRITICAL_SECTION * mutex = 0x00007ff7`23f88ea8, struct timespec * abstime = 0x00000011`2870f948) [d:\winx64-debug\build\src\mysys\my_wincond.c @ 82]
mysqld!inline_mysql_cond_timedwait(struct st_mysql_cond * that = 0x00007ff7`23f88ed8, struct st_mysql_mutex * mutex = 0x00007ff7`23f88ea8, struct timespec * abstime = 0x00000011`2870f948, char * src_file = 0x00007ff7`23511098 "--- memory read error at address 0x00007ff7`23511098 ---", unsigned int src_line = 0x124) [d:\winx64-debug\build\src\include\mysql\psi\mysql_thread.h @ 1175]
mysqld!timer_handler(void * arg = 0x00000000`00000000) [d:\winx64-debug\build\src\mysys\thr_timer.c @ 293]
mysqld!pfs_spawn_thread(void * arg = 0x00000011`2375e598) [d:\winx64-debug\build\src\storage\perfschema\pfs.cc @ 1864]
mysqld!pthread_start(void * p = 0x00000011`2375b430) [d:\winx64-debug\build\src\mysys\my_winthread.c @ 61]
(Inline Function) --------`-------- mysqld!invoke_thread_procedure [d:\th\minkernel\crts\ucrt\src\appcrt\startup\thread.cpp @ 91]
mysqld!thread_start<unsigned int (void * parameter = 0x00007ff7`21bd72a0) [d:\th\minkernel\crts\ucrt\src\appcrt\startup\thread.cpp @ 115]
kernel32!BaseThreadInitThunk
ntdll!RtlUserThreadStart
 
.  2  Id: 1dac.c80 Suspend: 0 Teb: 00007ff7`203da000 Unfrozen
      Priority: 0  Priority class: 32
 
ntdll!ZwRemoveIoCompletion
KERNELBASE!GetQueuedCompletionStatus
mysqld!os_aio_windows_handler(unsigned int64 segment = 0, unsigned int64 pos = 0, struct fil_node_t ** m1 = 0x00000011`298bf568, void ** m2 = 0x00000011`298bf5b8, class IORequest * type = 0x00000011`298bf588) [d:\winx64-debug\build\src\storage\innobase\os\os0file.cc @ 6521]
mysqld!os_aio_handler(unsigned int64 segment = 0, struct fil_node_t ** m1 = 0x00000011`298bf568, void ** m2 = 0x00000011`298bf5b8, class IORequest * request = 0x00000011`298bf588) [d:\winx64-debug\build\src\storage\innobase\os\os0file.cc @ 5716]
mysqld!fil_aio_wait(unsigned int64 segment = 0) [d:\winx64-debug\build\src\storage\innobase\fil\fil0fil.cc @ 5325]
mysqld!io_handler_thread(void * arg = 0x00007ff7`23775fb0) [d:\winx64-debug\build\src\storage\innobase\srv\srv0start.cc @ 344]
kernel32!BaseThreadInitThunk
ntdll!RtlUserThreadStart
 
.  8  Id: 1dac.1654 Suspend: 0 Teb: 00007ff7`202a8000 Unfrozen
      Priority: 0  Priority class: 32
 
ntdll!ZwWaitForAlertByThreadId
ntdll!RtlSleepConditionVariableCS
KERNELBASE!SleepConditionVariableCS
mysqld!os_event::wait(void) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 159]
mysqld!os_event::wait_low(int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 340]
mysqld!os_event_wait_low(struct os_event * event = 0x00000011`28210c60, int64 reset_sig_count = 0n0) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 536]
mysqld!buf_flush_page_cleaner_coordinator(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\buf\buf0flu.cc @ 3150]
kernel32!BaseThreadInitThunk
ntdll!RtlUserThreadStart
 
Total threads: 9
Duplicate callstacks: 5 (windbg thread #s follow):
3, 4, 5, 6, 7
quit:



 Comments   
Comment by Marko Mäkelä [ 2019-09-03 ]

I think that this report duplicates MDEV-13830. Basically, crash recovery occasionally fails.

Comment by Marko Mäkelä [ 2020-06-01 ]

Still occurring: http://buildbot.askmonty.org/buildbot/builders/winx64-debug/builds/19235

10.2 18d8f06f31cbe3913e1c80c0c5120f23

innodb.blob-crash '8k,debug,innodb'      w1 [ fail ]
2020-05-27  0:08:46 5992 [Note] InnoDB: Starting crash recovery from checkpoint LSN=8906591
Assertion failed: recv_sys->mlog_checkpoint_lsn <= recv_sys->recovered_lsn, file D:\winx64-debug\build\src\storage\innobase\log\log0recv.cc, line 2919
200527  0:08:47 [ERROR] mysqld got exception 0x80000003 ;

I suspected that this shares the root cause with MDEV-22027, but that bug was a 10.5-specific regression.

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