Uploaded image for project: 'MariaDB Server'
  1. MariaDB Server
  2. MDEV-14205

Windows : race condition writing into error log and setvbuf, innodb.doublewrite fails in buildbot

Details

    • Bug
    • Status: Closed (View Workflow)
    • Major
    • Resolution: Fixed
    • 10.1(EOL), 10.2(EOL)
    • 10.1.29, 10.2.11
    • Tests
    • None

    Description

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

      innodb.doublewrite '4k,innodb'           w3 [ fail ]
              Test ended at 2017-10-27 08:33:00
       
      CURRENT_TEST: innodb.doublewrite
       
       
      Server [mysqld.1 - pid: 4520, winpid: 4520, exit: 65280] failed during test run
      Server log from this test:
      ----------SERVER LOG START-----------
      2017-10-27  8:32:33 4420 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.10-MariaDB-debug-log) starting as process 2096 ...
      2017-10-27  8:32:33 4420 [Note] Plugin 'partition' is disabled.
      2017-10-27  8:32:33 4420 [Note] InnoDB: innodb_page_size=4096
      2017-10-27  8:32:33 4420 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2017-10-27  8:32:33 4420 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2017-10-27  8:32:33 4420 [Note] InnoDB: Uses event mutexes
      2017-10-27  8:32:33 4420 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2017-10-27  8:32:33 4420 [Note] InnoDB: Number of pools: 1
      2017-10-27  8:32:33 4420 [Note] InnoDB: Using generic crc32 instructions
      2017-10-27  8:32:33 4420 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2017-10-27  8:32:33 4420 [Note] InnoDB: Completed initialization of buffer pool
      2017-10-27  8:32:33 4420 [Note] InnoDB: Highest supported file format is Barracuda.
      2017-10-27  8:32:34 4420 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2017-10-27  8:32:34 4420 [Note] InnoDB: Creating shared tablespace for temporary tables
      2017-10-27  8:32:34 4420 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2017-10-27  8:32:34 4420 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2017-10-27  8:32:35 4420 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:35 4420 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:35 4420 [Note] InnoDB: 5.7.20 started; log sequence number 730942
      2017-10-27  8:32:35 3860 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool
      2017-10-27  8:32:35 4420 [Note] Plugin 'SEQUENCE' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_CMP' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'FEEDBACK' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'user_variables' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2017-10-27  8:32:35 4420 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2017-10-27  8:32:35 4420 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
      2017-10-27  8:32:35 4420 [Note] Server socket created on IP: '::'.
      2017-10-27  8:32:35 4420 [Note] Reading of all Master_info entries succeded
      2017-10-27  8:32:35 4420 [Note] Added new Master_info '' to hash table
      2017-10-27  8:32:35 4420 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
      Version: '10.2.10-MariaDB-debug-log'  socket: ''  port: 16040  Source distribution
      2017-10-27  8:32:35 3860 [Note] InnoDB: Buffer pool(s) load completed at 171027  8:32:35
      2017-10-27  8:32:36 3084 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (root[root] @ localhost [::1]): Normal shutdown
       
      2017-10-27  8:32:36 3084 [Note] Event Scheduler: Purging the queue. 0 events
      2017-10-27  8:32:36 4136 [Note] InnoDB: FTS optimize thread exiting.
      2017-10-27  8:32:36 3084 [Note] InnoDB: Starting shutdown...
      2017-10-27  8:32:36 3860 [Note] InnoDB: Dumping buffer pool(s) to D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool
      2017-10-27  8:32:36 3860 [Note] InnoDB: Buffer pool(s) dump completed at 171027  8:32:36
      2017-10-27  8:32:37 3084 [Note] InnoDB: Shutdown completed; log sequence number 730961
      2017-10-27  8:32:37 3084 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2017-10-27  8:32:37 3084 [Note] Debug sync points hit:                   1266
      2017-10-27  8:32:37 3084 [Note] Debug sync points executed:              0
      2017-10-27  8:32:37 3084 [Note] Debug sync points max active per thread: 0
      2017-10-27  8:32:37 3084 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: Shutdown complete
       
      2017-10-27  8:32:38 3476 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.10-MariaDB-debug-log) starting as process 2868 ...
      2017-10-27  8:32:38 3476 [Note] Plugin 'partition' is disabled.
      2017-10-27  8:32:38 3476 [Note] InnoDB: innodb_page_size=4096
      2017-10-27  8:32:38 3476 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2017-10-27  8:32:38 3476 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2017-10-27  8:32:38 3476 [Note] InnoDB: Uses event mutexes
      2017-10-27  8:32:38 3476 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2017-10-27  8:32:38 3476 [Note] InnoDB: Number of pools: 1
      2017-10-27  8:32:38 3476 [Note] InnoDB: Using generic crc32 instructions
      2017-10-27  8:32:38 3476 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2017-10-27  8:32:38 3476 [Note] InnoDB: Completed initialization of buffer pool
      2017-10-27  8:32:38 3476 [Note] InnoDB: Highest supported file format is Barracuda.
      2017-10-27  8:32:39 3476 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2017-10-27  8:32:39 3476 [Note] InnoDB: Creating shared tablespace for temporary tables
      2017-10-27  8:32:39 3476 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2017-10-27  8:32:39 3476 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2017-10-27  8:32:40 3476 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:40 3476 [Note] InnoDB: 5.7.20 started; log sequence number 730961
      2017-10-27  8:32:40 576 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool
      2017-10-27  8:32:40 3476 [Note] Plugin 'SEQUENCE' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_CMP' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2017-10-27  8:32:40 576 [Note] InnoDB: Buffer pool(s) load completed at 171027  8:32:40
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'FEEDBACK' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'user_variables' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2017-10-27  8:32:40 3476 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2017-10-27  8:32:40 3476 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
      2017-10-27  8:32:40 3476 [Note] Server socket created on IP: '::'.
      2017-10-27  8:32:40 3476 [Note] Reading of all Master_info entries succeded
      2017-10-27  8:32:40 3476 [Note] Added new Master_info '' to hash table
      2017-10-27  8:32:40 3476 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
      Version: '10.2.10-MariaDB-debug-log'  socket: ''  port: 16040  Source distribution
      2017-10-27  8:32:40 1484 [Note] InnoDB: Sync to disk of `test`.`t1` started.
      2017-10-27  8:32:40 1484 [Note] InnoDB: Stopping purge
      2017-10-27  8:32:40 1484 [Note] InnoDB: Writing table metadata to '.\test\t1.cfg'
      2017-10-27  8:32:40 1484 [Note] InnoDB: Table `test`.`t1` flushed to disk
      2017-10-27  8:32:40 2476 [Note] InnoDB: Deleting the meta-data file '.\test\t1.cfg'
      2017-10-27  8:32:40 2476 [Note] InnoDB: Resuming purge
      2017-10-27  8:32:40 2476 [Note] InnoDB: Saving InnoDB page number: 0
      2017-10-27  8:32:40 2476 [Note] InnoDB: Dirtying page: [page id: space=4, page number=0]
      2017-10-27  8:32:42 4476 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.10-MariaDB-debug-log) starting as process 2668 ...
      2017-10-27  8:32:42 4476 [Note] Plugin 'partition' is disabled.
      2017-10-27  8:32:42 4476 [Note] InnoDB: innodb_page_size=4096
      2017-10-27  8:32:42 4476 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2017-10-27  8:32:42 4476 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2017-10-27  8:32:42 4476 [Note] InnoDB: Uses event mutexes
      2017-10-27  8:32:42 4476 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2017-10-27  8:32:42 4476 [Note] InnoDB: Number of pools: 1
      2017-10-27  8:32:42 4476 [Note] InnoDB: Using generic crc32 instructions
      2017-10-27  8:32:42 4476 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2017-10-27  8:32:42 4476 [Note] InnoDB: Completed initialization of buffer pool
      2017-10-27  8:32:42 4476 [Note] InnoDB: Highest supported file format is Barracuda.
      2017-10-27  8:32:42 4476 [Note] InnoDB: Starting crash recovery from checkpoint LSN=730961
      2017-10-27  8:32:42 4476 [ERROR] InnoDB: Header page consists of zero bytes in datafile: .\test\t1.ibd, Space ID:0, Flags: 0. Please refer to http://dev.mysql.com/doc/refman/5.7/en/innodb-troubleshooting-datadict.html for how to resolve the issue.
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size:1024. Pages to analyze:24
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size: 1024. Possible space_id count:0
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size:2048. Pages to analyze:12
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size: 2048. Possible space_id count:0
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size:4096. Pages to analyze:6
      2017-10-27  8:32:42 4476 [Note] InnoDB: VALID: space:4 page_no:1 page_size:4096
      2017-10-27  8:32:42 4476 [Note] InnoDB: VALID: space:4 page_no:2 page_size:4096
      2017-10-27  8:32:42 4476 [Note] InnoDB: VALID: space:4 page_no:3 page_size:4096
      2017-10-27  8:32:42 4476 [Note] InnoDB: Page size: 4096. Possible space_id count:1
      2017-10-27  8:32:42 4476 [Note] InnoDB: space_id:4, Number of pages matched: 3/3 (4096)
      2017-10-27  8:32:42 4476 [Note] InnoDB: Chosen space:4
      2017-10-27  8:32:42 4476 [Note] InnoDB: Restoring page [page id: space=4, page number=0] of datafile '.\test\t1.ibd' from the doublewrite buffer. Writing 4096 bytes into file '.\test\t1.ibd'
      2017-10-27  8:32:44 4476 [Warning] InnoDB: adjusting FSP_SPACE_FLAGS of tablespace 4 from 0x6021 to 0xe1
      2017-10-27  8:32:44 4476 [Note] InnoDB: 1 transaction(s) which must be rolled back or cleaned up in total 1 row operations to undo
      2017-10-27  8:32:44 4476 [Note] InnoDB: Trx id counter is 2304
      2017-10-27  8:32:44 4476 [Note] InnoDB: Starting final batch to recover 5 pages from redo log.
      2017-10-27  8:32:45 4476 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2017-10-27  8:32:45 4476 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2017-10-27  8:32:45 4476 [Note] InnoDB: Creating shared tablespace for temporary tables
      2017-10-27  8:32:45 4476 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2017-10-27  8:32:45 4476 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2017-10-27  8:32:45 4588 [Note] InnoDB: Starting in background the rollback of recovered transactions
      2017-10-27  8:32:45 4588 [Note] InnoDB: Rolling back trx with id 1798, 1 rows to undo
      2017-10-27  8:32:45 4588 [Note] InnoDB: Rollback of trx with id 1798 completed
      2017-10-27  8:32:46 4588 [Note] InnoDB: Rollback of non-prepared transactions completed
      2017-10-27  8:32:46 4476 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:46 4476 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:46 4476 [Note] InnoDB: 5.7.20 started; log sequence number 735949
      2017-10-27  8:32:46 4476 [Note] Plugin 'SEQUENCE' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_CMP' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'FEEDBACK' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'user_variables' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2017-10-27  8:32:46 4476 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2017-10-27  8:32:46 4476 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
      2017-10-27  8:32:46 4476 [Note] Server socket created on IP: '::'.
      2017-10-27  8:32:46 3260 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool
      2017-10-27  8:32:46 4476 [Note] Reading of all Master_info entries succeded
      2017-10-27  8:32:46 4476 [Note] Added new Master_info '' to hash table
      2017-10-27  8:32:46 4476 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe: ready for connections.
      Version: '10.2.10-MariaDB-debug-log'  socket: ''  port: 16040  Source distribution
      2017-10-27  8:32:46 3260 [Note] InnoDB: Buffer pool(s) load completed at 171027  8:32:46
      2017-10-27  8:32:47 1484 [Note] InnoDB: Sync to disk of `test`.`t1` started.
      2017-10-27  8:32:47 1484 [Note] InnoDB: Stopping purge
      2017-10-27  8:32:47 1484 [Note] InnoDB: Writing table metadata to '.\test\t1.cfg'
      2017-10-27  8:32:47 1484 [Note] InnoDB: Table `test`.`t1` flushed to disk
      2017-10-27  8:32:47 3540 [Note] InnoDB: Deleting the meta-data file '.\test\t1.cfg'
      2017-10-27  8:32:47 3540 [Note] InnoDB: Resuming purge
      2017-10-27  8:32:47 1484 [Note] InnoDB: Saving InnoDB page number: 0
      2017-10-27  8:32:47 1484 [Note] InnoDB: Dirtying page: [page id: space=4, page number=0]
      2017-10-27  8:32:48 1320 [Note] D:/winx64-debug/build/sql//Debug/mysqld.exe (mysqld 10.2.10-MariaDB-debug-log) starting as process 5024 ...
      2017-10-27  8:32:48 1320 [Note] Plugin 'partition' is disabled.
      2017-10-27  8:32:48 1320 [Note] InnoDB: innodb_page_size=4096
      2017-10-27  8:32:48 1320 [Note] InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
      2017-10-27  8:32:48 1320 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2017-10-27  8:32:48 1320 [Note] InnoDB: Uses event mutexes
      2017-10-27  8:32:48 1320 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2017-10-27  8:32:48 1320 [Note] InnoDB: Number of pools: 1
      2017-10-27  8:32:48 1320 [Note] InnoDB: Using generic crc32 instructions
      2017-10-27  8:32:48 1320 [Note] InnoDB: Initializing buffer pool, total size = 8M, instances = 1, chunk size = 8M
      2017-10-27  8:32:48 1320 [Note] InnoDB: Completed initialization of buffer pool
      2017-10-27  8:32:48 1320 [Note] InnoDB: Highest supported file format is Barracuda.
      2017-10-27  8:32:48 1320 [Note] InnoDB: Starting crash recovery from checkpoint LSN=736026
      2017-10-27  8:32:50 1320 [Note] InnoDB: 1 transaction(s) which must be rolled back or cleaned up in total 1 row operations to undo
      2017-10-27  8:32:50 1320 [Note] InnoDB: Trx id counter is 2816
      2017-10-27  8:32:50 1320 [Note] InnoDB: Starting final batch to recover 1 pages from redo log.
      2017-10-27  8:32:51 1320 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2017-10-27  8:32:51 1320 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2017-10-27  8:32:51 1320 [Note] InnoDB: Creating shared tablespace for temporary tables
      2017-10-27  8:32:51 1320 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2017-10-27  8:32:51 1320 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2017-10-27  8:32:51 4640 [Note] InnoDB: Starting in background the rollback of recovered transactions
      2017-10-27  8:32:51 4640 [Note] InnoDB: Rolling back trx with id 2307, 1 rows to undo
      2017-10-27  8:32:51 4640 [Note] InnoDB: Rollback of trx with id 2307 completed
      2017-10-27  8:32:51 4640 [Note] InnoDB: Rollback of non-prepared transactions completed
      2017-10-27  8:32:52 1320 [Note] InnoDB: Waiting for purge to start
      2017-10-27  8:32:52 1320 [Note] InnoDB: 5.7.20 started; log sequence number 736152
      2017-10-27  8:32:52 4532 [Note] InnoDB: Loading buffer pool(s) from D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool
      2017-10-27  8:32:52 1320 [Note] Plugin 'SEQUENCE' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_MUTEXES' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_CMP' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'FEEDBACK' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'user_variables' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
      2017-10-27  8:32:52 1320 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
      2017-10-27  8:32:52 1320 [Warning] D:/winx64-debug/build/sql//Debug/mysqld.exe: unknown option '--loose-pam-debug'
      2017-10-27  8:32:52 1320 [Note] Server socket created on IP: '::'.
      

      171027  8:32:52 [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.10-MariaDB-debug-log
      key_buffer_size=1048576
      read_buffer_size=131072
      max_used_connections=0
      max_threads=65537
      thread_count=5
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 4211 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_parameter_handler()[my_init.c:256]
      mysqld.exe!_invalid_parameter_noinfo()[invalid_parameter.cpp:98]
      mysqld.exe!_write()[write.cpp:48]
      mysqld.exe!write_buffer_nolock<char>()[_flsbuf.cpp:56]
      mysqld.exe!__acrt_stdio_flush_and_write_narrow_nolock()[_flsbuf.cpp:157]
      mysqld.exe!__crt_stdio_output::stream_output_adapter<char>::write_string()[corecrt_internal_stdio_output.h:163]
      mysqld.exe!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::write_stored_string_tchar()[corecrt_internal_stdio_output.h:2644]
      mysqld.exe!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::state_case_type()[corecrt_internal_stdio_output.h:2095]
      mysqld.exe!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::process()[corecrt_internal_stdio_output.h:1644]
      mysqld.exe!<lambda_0a64b6a4deda75c01876f29c44fc5eb3>::operator()()[output.cpp:46]
      mysqld.exe!__crt_seh_guarded_call<int>::operator()<<lambda_aa279a7bcb127014629d5b1d62c7325d>,<lambda_0a64b6a4deda75c01876f29c44fc5eb3> & __ptr64,<lambda_3323e69fbefe9d1a11713e670d47cdfa> >()[internal_shared.h:199]
      mysqld.exe!__stdio_common_vfprintf()[output.cpp:59]
      mysqld.exe!_vfprintf_l()[stdio.h:640]
      mysqld.exe!fprintf()[stdio.h:835]
      mysqld.exe!print_buffer_to_file()[log.cc:8465]
      mysqld.exe!vprint_msg_to_log()[log.cc:8497]
      mysqld.exe!Log_to_file_event_handler::log_error()[log.cc:1023]
      mysqld.exe!LOGGER::error_log_print()[log.cc:1131]
      mysqld.exe!error_log_print()[log.cc:6383]
      mysqld.exe!sql_print_information()[log.cc:8541]
      mysqld.exe!ib::info::~info()[ut0ut.cc:824]
      mysqld.exe!buf_load_status()[buf0dump.cc:173]
      mysqld.exe!buf_load()[buf0dump.cc:779]
      mysqld.exe!buf_dump_thread()[buf0dump.cc:822]
      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
      Minidump written to D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\mysqld.dmp
      ----------SERVER LOG END-------------
      mysqltest failed but provided no output
      The result from queries just before the failure was:
      < snip >
      3	////////////
      4	------------
      5	............
      # Test End
      # ---------------------------------------------------------------
      # Test Begin: Test if recovery works if first page of user
      # tablespace is corrupted.
      select space from information_schema.innodb_sys_tables
      where name = 'test/t1' into @space_id;
      # Ensure that dirty pages of table t1 is flushed.
      flush tables t1 for export;
      unlock tables;
      begin;
      insert into t1 values (6, repeat('%', 12));
      # Make the first page dirty for table t1
      set global innodb_saved_page_number_debug = 0;
      set global innodb_fil_make_page_dirty_debug = @space_id;
      # Ensure that dirty pages of table t1 are flushed.
      set global innodb_buf_flush_list_now = 1;
      # Kill the server
       
       
       
       - saving 'D:/winx64-debug/build/mysql-test/var/3/log/innodb.doublewrite-4k,innodb/' to 'D:/winx64-debug/build/mysql-test/var/log/innodb.doublewrite-4k,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.10586.567 AMD64
      Copyright (c) Microsoft Corporation. All rights reserved.
       
       
      Loading Dump File [D:\winx64-debug\build\mysql-test\var\log\innodb.doublewrite-4k,innodb\mysqld.1\data\mysqld.dmp]
      User Mini Dump File: Only registers, stack and portions of memory are available
       
       
      Response                         Time (ms)     Location
      OK                                             .
       
      Response                         Time (ms)     Location
      OK                                             .
      Deferred                                       cache*C:\cdb_symbols
      Symbol search path is: .;cache*C:\cdb_symbols
      Executable search path is: .
      Windows 8.1 Version 9600 MP (4 procs) Free x64
      Product: Server, suite: TerminalServer DataCenter SingleUserTS
      Built by: 6.3.9600.18217 (winblue_ltsb.160124-0053)
      Machine Name:
      Debug session time: Fri Oct 27 08:32:59.000 2017 (UTC + 0:00)
      System Uptime: not available
      Process Uptime: 0 days 0:00:11.000
      ........................
      This dump file has a breakpoint exception stored in it.
      The stored exception information can be accessed via .ecxr.
      ntdll!ZwGetContextThread:
                   ret
      0:019> 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=00007ff7b8c3ed00 rbx=0000000000000002 rcx=0000000000000000
      rdx=0000000000000000 rsi=0000000000000080 rdi=0000000000000032
      rip=00007ff7b8c3ed15 rsp=00000074f925da70 rbp=00000074f925df48
       r8=0000000000000000  r9=0000000000000000 r10=00004ebcbfa524f8
      r11=0000000000000000 r12=00000074f925db40 r13=00007ff7bb1c9360
      r14=0000000000000000 r15=0000000000000001
      iopl=0         nv up ei pl nz na pe nc
      cs=0033  ss=002b  ds=002b  es=002b  fs=0053  gs=002b             efl=00000202
      mysqld!my_parameter_handler:
                   int     3
      Resetting default scope
       
      FAULTING_IP: 
      mysqld!my_parameter_handler+15 [d:\winx64-debug\build\src\mysys\my_init.c @ 256]
                   int     3
       
      EXCEPTION_RECORD:  (.exr -1)
      ExceptionAddress: 00007ff7b8c3ed15 (mysqld!my_parameter_handler)
         ExceptionCode: 80000003 (Break instruction exception)
        ExceptionFlags: 00000000
      NumberParameters: 1
         Parameter[0]: 0000000000000000
       
      BUGCHECK_STR:  59BA86DB
       
      WRONG_SYMBOLS_TIMESTAMP: 59ba86db
       
      WRONG_SYMBOLS_SIZE: 1ad000
       
      FAULTING_MODULE: 00007ffa13150000 ntdll
       
      DEBUG_FLR_IMAGE_TIMESTAMP:  59ba86db
       
      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]
       
      LAST_CONTROL_TRANSFER:  from 0000000000000000 to 0000000000000000
       
      ANALYSIS_SESSION_HOST:  MARIADB-02
       
      ANALYSIS_SESSION_TIME:  10-27-2017 08:33:02.0271
       
      ANALYSIS_VERSION: 10.0.10586.567 amd64fre
       
      STACK_TEXT:  
      wrong_symbols!WRONG_SYMBOLS
       
       
      STACK_COMMAND:  .ecxr ; kb ; ** Pseudo Context ** ; kb
       
      THREAD_SHA1_HASH_MOD_FUNC:  8b50e467776d7660ec74f0fcf6059a449f38bc9a
       
      THREAD_SHA1_HASH_MOD_FUNC_OFFSET:  386be9b62a7535c96acfda43762924bd7c0bcb71
       
      THREAD_SHA1_HASH_MOD:  1c9266d4d33f329668e953be351439450e48d114
       
      SYMBOL_STACK_INDEX:  0
       
      FOLLOWUP_NAME:  MachineOwner
       
      EXCEPTION_CODE: (NTSTATUS) 0x59ba86db - <Unable to get error code text>
       
      EXCEPTION_CODE_STR:  59BA86DB
       
      FAILURE_EXCEPTION_CODE:  59BA86DB
       
      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!59BA86DB1AD000
       
      BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_170914-134043
       
      DEFAULT_BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_170914-134043
       
      PRIMARY_PROBLEM_CLASS:  PRIVATE_SYMBOLS
       
      BUCKET_ID_OFFSET:  0
       
      BUCKET_ID_MODULE_STR:  ntdll_wrong_symbols
       
      BUCKET_ID_MODVER_STR:  
       
      BUCKET_ID_PREFIX_STR:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_170914-134043
       
      FAILURE_PROBLEM_CLASS:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_170914-134043
       
      FAILURE_IMAGE_NAME:  ntdll.wrong.symbols.dll
       
      FAILURE_FUNCTION_NAME:  59BA86DB1AD000
       
      BUCKET_ID_FUNCTION_STR:  59BA86DB1AD000
       
      FAILURE_SYMBOL_NAME:  ntdll.wrong.symbols.dll!59BA86DB1AD000
       
      FAILURE_BUCKET_ID:  PRIVATE_SYMBOLS_X64_6.3.9600.17415_(winblue_r4.141028-1500)_TIMESTAMP_170914-134043_59BA86DB_ntdll.wrong.symbols.dll!59BA86DB1AD000
       
      TARGET_TIME:  2017-10-27T08:32:59.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: 10a9
       
      ANALYSIS_SOURCE:  UM
       
      FAILURE_ID_HASH_STRING:  um:private_symbols_x64_6.3.9600.17415_(winblue_r4.141028-1500)_timestamp_170914-134043_59ba86db_ntdll.wrong.symbols.dll!59ba86db1ad000
       
      FAILURE_ID_HASH:  {d62cd6d8-aa8f-46af-c234-ecd0fe2bb7ff}
       
      Followup:     MachineOwner
      ---------
       
      rax=00007ff7b8c3ed00 rbx=0000000000000002 rcx=0000000000000000
      rdx=0000000000000000 rsi=0000000000000080 rdi=0000000000000032
      rip=00007ff7b8c3ed15 rsp=00000074f925da70 rbp=00000074f925df48
       r8=0000000000000000  r9=0000000000000000 r10=00004ebcbfa524f8
      r11=0000000000000000 r12=00000074f925db40 r13=00007ff7bb1c9360
      r14=0000000000000000 r15=0000000000000001
      iopl=0         nv up ei pl nz na pe nc
      cs=0033  ss=002b  ds=002b  es=002b  fs=0053  gs=002b             efl=00000202
      mysqld!my_parameter_handler:
                   int     3
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!my_parameter_handler [d:\winx64-debug\build\src\mysys\my_init.c @ 256]
      wchar_t * expression = 0x00000000`00000000 ""
      wchar_t * function = 0x00000000`00000000 ""
      wchar_t * file = 0x00000000`00000000 ""
      unsigned int line = 0
      unsigned int64 pReserved = 0
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!_invalid_parameter_noinfo [d:\th\minkernel\crts\ucrt\src\appcrt\misc\invalid_parameter.cpp @ 98]
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!_write [d:\th\minkernel\crts\ucrt\src\appcrt\lowio\write.cpp @ 48]
      int fh = 0n2
      void * buffer = 0x00000074`f925db40
      unsigned int size = 1
      int result = <value unavailable>
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!write_buffer_nolock<char> [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\_flsbuf.cpp @ 56]
      char c = 0n50 '2'
      class __crt_stdio_stream stream = class __crt_stdio_stream
      int fh = <value unavailable>
      int chars_written = <value unavailable>
      int chars_to_write = <value unavailable>
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      (Inline Function) --------`-------- mysqld!common_flush_and_write_nolock [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\_flsbuf.cpp @ 141]
      int fh = 0n2
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__acrt_stdio_flush_and_write_narrow_nolock [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\_flsbuf.cpp @ 157]
      int c = 0n50
      struct _iobuf * stream = 0x00007ff7`ba91dda0
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      (Inline Function) --------`-------- mysqld!__crt_char_traits<char>::puttc_nolock [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_traits.h @ 106]
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      (Inline Function) --------`-------- mysqld!__crt_stdio_output::stream_output_adapter<char>::write_character [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio_output.h @ 135]
      char c = <value unavailable>
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__crt_stdio_output::stream_output_adapter<char>::write_string [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio_output.h @ 163]
      class __crt_stdio_output::stream_output_adapter<char> * this = 0x00000074`f925e158
      char * string = <value unavailable>
      int length = <value unavailable>
      int * count_written = 0x00000074`f925dd18
      int * status = 0x00000074`f6abbfd0
      char * string_last = 0x00000074`f925df48 "???"
      struct __crt_errno_guard reset_errno = struct __crt_errno_guard
      char * it = 0x00000074`f925df44 "2017???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::write_stored_string_tchar [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio_output.h @ 2644]
      class __crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > > * this = 0x00000074`f925dcf0
      char __formal = 0n0 ''
      wchar_t * p = 0x00000000`fffffffc "--- memory read error at address 0x00000000`fffffffc ---"
      int i = 0n0
      int status = 0n-1195119360
      char [6] local_buffer = char [6] "t"
      int mbc_length = 0n-114959120
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::state_case_type [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio_output.h @ 2095]
      class __crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > > * this = 0x00000074`f925dcf0
      unsigned int64 prefix_length = 0
      bool result = <value unavailable>
      int padding = <value unavailable>
      bool print_integer_0x = <value unavailable>
      bool print_floating_point_0x = <value unavailable>
      char [3] prefix = char [3] ""
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >::process [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio_output.h @ 1644]
      class __crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > > * this = 0x00000074`f925dcf0
      bool result = false
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!<lambda_0a64b6a4deda75c01876f29c44fc5eb3>::operator() [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\output.cpp @ 46]
      class common_vfprintf::__l11::<lambda_0a64b6a4deda75c01876f29c44fc5eb3> * this = 0x00000074`f925e1f8
      class _LocaleUpdate locale_update = class _LocaleUpdate
      class __acrt_stdio_temporary_buffering_guard buffering = class __acrt_stdio_temporary_buffering_guard
      class __crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > > processor = class __crt_stdio_output::output_processor<char,__crt_stdio_output::stream_output_adapter<char>,__crt_stdio_output::standard_base<char,__crt_stdio_output::stream_output_adapter<char> > >
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__crt_seh_guarded_call<int>::operator()<<lambda_aa279a7bcb127014629d5b1d62c7325d>,<lambda_0a64b6a4deda75c01876f29c44fc5eb3> & __ptr64,<lambda_3323e69fbefe9d1a11713e670d47cdfa> > [d:\th\minkernel\crts\ucrt\devdiv\vcruntime\inc\internal_shared.h @ 199]
      struct __crt_seh_guarded_call<int> * this = 0x00007ff7`b8896950
      class __acrt_lock_stream_and_call::__l3::<lambda_aa279a7bcb127014629d5b1d62c7325d> * setup = 0xcccccccc`cccccccc
      class common_vfprintf::__l11::<lambda_0a64b6a4deda75c01876f29c44fc5eb3> * action = 0x00000074`f925e1f8
      class __acrt_lock_stream_and_call::__l4::<lambda_3323e69fbefe9d1a11713e670d47cdfa> * cleanup = 0x00000074`f925e1e8
      int result = <value unavailable>
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      (Inline Function) --------`-------- mysqld!__acrt_lock_stream_and_call [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio.h @ 255]
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      (Inline Function) --------`-------- mysqld!common_vfprintf [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\output.cpp @ 35]
      unsigned int64 options = 4
      struct _iobuf * stream = 0x00007ff7`ba91dda0
      char * format = 0x00007ff7`b90acaf0 "%d-%02d-%02d %2d:%02d:%02d %lu [%s] %.*s%.*s."
      struct __crt_locale_pointers * locale = 0x00000000`00000000
      char * arglist = 0x00000074`f925e2e0 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!__stdio_common_vfprintf [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\output.cpp @ 59]
      unsigned int64 options = <value unavailable>
      struct _iobuf * stream = <value unavailable>
      char * format = <value unavailable>
      struct __crt_locale_pointers * locale = <value unavailable>
      char * arglist = 0x00000074`f925e2e0 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!_vfprintf_l [c:\program files (x86)\windows kits\10\include\10.0.10240.0\ucrt\stdio.h @ 640]
      struct _iobuf * _Stream = 0x00007ff7`ba91dda0
      char * _Format = 0x00007ff7`b90acaf0 "%d-%02d-%02d %2d:%02d:%02d %lu [%s] %.*s%.*s."
      struct __crt_locale_pointers * _Locale = 0x00000000`00000000
      char * _ArgList = 0x00000074`f925e2e0 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!fprintf [c:\program files (x86)\windows kits\10\include\10.0.10240.0\ucrt\stdio.h @ 835]
      struct _iobuf * _Stream = 0x00007ff7`ba91dda0
      char * _Format = 0x00007ff7`b90acaf0 "%d-%02d-%02d %2d:%02d:%02d %lu [%s] %.*s%.*s."
      char * _ArgList = 0x00000074`f925e2e0 "???"
      int _Result = 0n-858993460
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!print_buffer_to_file [d:\winx64-debug\build\src\sql\log.cc @ 8465]
      loglevel level = INFORMATION_LEVEL (0n2)
      char * buffer = 0x00000074`f925e540 "InnoDB: Buffer pool(s) load completed at 171027  8:32:52"
      unsigned int64 length = 0x38
      int64 skr = 0n1509093172
      int tag_length = 0n0
      char [192] tag = char [192] "???"
      struct tm tm_tmp = struct tm
      struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
      struct tm * start = 0x00000074`f925e368
      class THD * thd = 0x00000000`00000000
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!vprint_msg_to_log [d:\winx64-debug\build\src\sql\log.cc @ 8497]
      loglevel level = INFORMATION_LEVEL (0n2)
      char * format = 0x00007ff7`b92a5260 "InnoDB: %s"
      char * args = 0x00000074`f925eb08 "???"
      char [1024] buff = char [1024] "InnoDB: Buffer pool(s) load completed at 171027  8:32:52"
      unsigned int64 length = 0x38
      struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!Log_to_file_event_handler::log_error [d:\winx64-debug\build\src\sql\log.cc @ 1023]
      class Log_to_file_event_handler * this = 0x00000074`f453e120
      loglevel level = INFORMATION_LEVEL (0n2)
      char * format = 0x00007ff7`b92a5260 "InnoDB: %s"
      char * args = 0x00000074`f925eb08 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!LOGGER::error_log_print [d:\winx64-debug\build\src\sql\log.cc @ 1131]
      class LOGGER * this = 0x00007ff7`ba933480
      loglevel level = INFORMATION_LEVEL (0n2)
      char * format = 0x00007ff7`b92a5260 "InnoDB: %s"
      char * args = 0x00000074`f925eb08 "???"
      class Log_event_handler ** current_handler = 0x00007ff7`ba9334e8
      bool error = false
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!error_log_print [d:\winx64-debug\build\src\sql\log.cc @ 6383]
      loglevel level = INFORMATION_LEVEL (0n2)
      char * format = 0x00007ff7`b92a5260 "InnoDB: %s"
      char * args = 0x00000074`f925eb08 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!sql_print_information [d:\winx64-debug\build\src\sql\log.cc @ 8541]
      char * format = 0x00007ff7`b92a5260 "InnoDB: %s"
      char * args = 0x00000074`f925eb08 "???"
      struct _db_stack_frame_ _db_stack_frame_ = struct _db_stack_frame_
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!ib::info::~info [d:\winx64-debug\build\src\storage\innobase\ut\ut0ut.cc @ 824]
      class ib::info * this = 0x00000074`f925eba8
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!buf_load_status [d:\winx64-debug\build\src\storage\innobase\buf\buf0dump.cc @ 173]
      status_severity severity = STATUS_INFO (0n1)
      char * fmt = 0x00007ff7`b9a7c420 "Buffer pool(s) load completed at %s"
      char * ap = 0x00000074`f925edd0 "???"
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!buf_load [d:\winx64-debug\build\src\storage\innobase\buf\buf0dump.cc @ 779]
      unsigned int64 space_id = 0
      class page_size_t page_size = class page_size_t
      unsigned int64 i = 0x158
      char [4000] full_filename = char [4000] "D:\winx64-debug\build\mysql-test\var\3\mysqld.1\data\ib_buffer_pool"
      struct _iobuf * f = 0x00000074`f69448f0
      unsigned int64 * dump = 0x00000074`f6abc380
      unsigned int64 page_no = 7
      unsigned int64 last_check_time = 0x0000015f`5cf6648d
      unsigned int64 dump_n = 0x158
      int fscanf_ret = 0n2
      char [32] now = char [32] "171027  8:32:52"
      unsigned int64 total_buffer_pools_pages = 0x800
      unsigned int64 last_activity_cnt = 3
      unsigned int64 cur_space_id = 0xfffffffe
      struct fil_space_t * space = 0x00000074`f6a87070
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      mysqld!buf_dump_thread [d:\winx64-debug\build\src\storage\innobase\buf\buf0dump.cc @ 822]
      void * __formal = 0x00000000`00000000
      _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _
      kernel32!BaseThreadInitThunk
      Unable to enumerate locals, HRESULT 0x80004005
      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_parameter_handler [d:\winx64-debug\build\src\mysys\my_init.c @ 256]
      Processing 25 threads, please wait
       
      .  0  Id: 13a0.528 Suspend: 0 Teb: 00007ff7`b7eee000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!ZwWaitForSingleObject
      ntdll!RtlLockHeap
      ntdll!RtlEnterCriticalSection
      (Inline Function) --------`-------- mysqld!__acrt_lock_stream_and_call::__l3::<lambda_7a5ef57ff966f610505f1d28a3d40618>::operator() [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio.h @ 253]
      mysqld!__crt_seh_guarded_call<int>::operator()<<lambda_7a5ef57ff966f610505f1d28a3d40618>,<lambda_b721fa326cdb32351d23dfa67958c160> & __ptr64,<lambda_5e0fb27672408a07956fe59e48ccb2aa> >(class __acrt_lock_stream_and_call::__l3::<lambda_7a5ef57ff966f610505f1d28a3d40618> * setup = 0xcccccccc`cccccccc, class setvbuf::__l27::<lambda_b721fa326cdb32351d23dfa67958c160> * action = 0x00000074`f1aff968, class __acrt_lock_stream_and_call::__l4::<lambda_5e0fb27672408a07956fe59e48ccb2aa> * cleanup = 0x00000074`f1aff958) [d:\th\minkernel\crts\ucrt\devdiv\vcruntime\inc\internal_shared.h @ 196]
      (Inline Function) --------`-------- mysqld!__acrt_lock_stream_and_call [d:\th\minkernel\crts\ucrt\inc\corecrt_internal_stdio.h @ 255]
      mysqld!setvbuf(struct _iobuf * public_stream = <Value unavailable error>, char * buffer = 0x00000000`00000000 "", int type = 0n4, unsigned int64 buffer_size_in_bytes = 0) [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\setvbuf.cpp @ 105]
      mysqld!reopen_fstreams(char * filename = 0x00007ff7`ba9352c0 "--- memory read error at address 0x00007ff7`ba9352c0 ---", struct _iobuf * outstream = 0x00007ff7`ba91dd48, struct _iobuf * errstream = 0x00007ff7`ba91dda0) [d:\winx64-debug\build\src\sql\log.cc @ 8343]
      mysqld!win_main(int argc = 0n146, char ** argv = 0x00000074`f1d313b8) [d:\winx64-debug\build\src\sql\mysqld.cc @ 5878]
      mysqld!mysql_service(void * p = 0x00000000`00000000) [d:\winx64-debug\build\src\sql\mysqld.cc @ 6128]
      mysqld!mysqld_main(int argc = 0n23, char ** argv = 0x00000074`f1d22160) [d:\winx64-debug\build\src\sql\mysqld.cc @ 6321]
      mysqld!main(int argc = 0n23, char ** argv = 0x00000074`f1d22160) [d:\winx64-debug\build\src\sql\main.cc @ 26]
      (Inline Function) --------`-------- mysqld!invoke_main [f:\dd\vctools\crt\vcstartup\src\startup\exe_common.inl @ 78]
      mysqld!__scrt_common_main_seh(void) [f:\dd\vctools\crt\vcstartup\src\startup\exe_common.inl @ 283]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      .  1  Id: 13a0.9ec Suspend: 0 Teb: 00007ff7`b7eec000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!pthread_cond_timedwait(struct _RTL_CONDITION_VARIABLE * cond = 0x00007ff7`bb1bba88, struct _RTL_CRITICAL_SECTION * mutex = 0x00007ff7`bb1bba58, struct timespec * abstime = 0x00000074`f6d7f758) [d:\winx64-debug\build\src\mysys\my_wincond.c @ 82]
      mysqld!inline_mysql_cond_timedwait(struct st_mysql_cond * that = 0x00007ff7`bb1bba88, struct st_mysql_mutex * mutex = 0x00007ff7`bb1bba58, struct timespec * abstime = 0x00000074`f6d7f758, char * src_file = 0x00007ff7`ba744f78 "--- memory read error at address 0x00007ff7`ba744f78 ---", 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 = 0x00000074`f3ece518) [d:\winx64-debug\build\src\storage\perfschema\pfs.cc @ 1865]
      mysqld!pthread_start(void * p = 0x00000074`f1e0b400) [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`b8df88f0) [d:\th\minkernel\crts\ucrt\src\appcrt\startup\thread.cpp @ 115]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      .  2  Id: 13a0.c3c Suspend: 0 Teb: 00007ff7`b7eea000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtRemoveIoCompletion
      KERNELBASE!GetQueuedCompletionStatus
      mysqld!os_aio_windows_handler(unsigned int64 segment = 0, unsigned int64 pos = 0, struct fil_node_t ** m1 = 0x00000074`f803f8b8, void ** m2 = 0x00000074`f803f908, class IORequest * type = 0x00000074`f803f8d8) [d:\winx64-debug\build\src\storage\innobase\os\os0file.cc @ 6541]
      mysqld!os_aio_handler(unsigned int64 segment = 0, struct fil_node_t ** m1 = 0x00000074`f803f8b8, void ** m2 = 0x00000074`f803f908, class IORequest * request = 0x00000074`f803f8d8) [d:\winx64-debug\build\src\storage\innobase\os\os0file.cc @ 5736]
      mysqld!fil_aio_wait(unsigned int64 segment = 0) [d:\winx64-debug\build\src\storage\innobase\fil\fil0fil.cc @ 5296]
      mysqld!io_handler_thread(void * arg = 0x00007ff7`ba9a9d30) [d:\winx64-debug\build\src\storage\innobase\srv\srv0start.cc @ 344]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      .  8  Id: 13a0.b98 Suspend: 0 Teb: 00007ff7`b7dba000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!os_event::timed_wait(unsigned long time_in_ms = 0x3e8) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 262]
      mysqld!os_event::wait_time_low(unsigned int64 time_in_usec = 0xf4240, int64 reset_sig_count = 0n2) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 415]
      mysqld!os_event_wait_time_low(struct os_event * event = 0x00000074`f670ff30, unsigned int64 time_in_usec = 0xf4240, int64 reset_sig_count = 0n2) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 519]
      mysqld!pc_sleep_if_needed(unsigned int64 next_loop_time = 0x0000015f`5cf68023, int64 sig_count = 0n2) [d:\winx64-debug\build\src\storage\innobase\buf\buf0flu.cc @ 2716]
      mysqld!buf_flush_page_cleaner_coordinator(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\buf\buf0flu.cc @ 3211]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      .  9  Id: 13a0.138c Suspend: 0 Teb: 00007ff7`b7db6000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!os_event::timed_wait(unsigned long time_in_ms = 0x3e8) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 262]
      mysqld!os_event::wait_time_low(unsigned int64 time_in_usec = 0xf4240, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 415]
      mysqld!os_event_wait_time_low(struct os_event * event = 0x00000074`f693c550, unsigned int64 time_in_usec = 0xf4240, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 519]
      mysqld!lock_wait_timeout_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\lock\lock0wait.cc @ 530]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 10  Id: 13a0.82c Suspend: 0 Teb: 00007ff7`b7db4000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!ZwWaitForSingleObject
      ntdll!RtlLockHeap
      ntdll!RtlEnterCriticalSection
      mysqld!fflush(struct _iobuf * stream = 0x00007ff7`ba91dda0) [d:\th\minkernel\crts\ucrt\src\appcrt\stdio\fflush.cpp @ 91]
      mysqld!srv_error_monitor_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 1967]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 11  Id: 13a0.1248 Suspend: 0 Teb: 00007ff7`b7db2000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!os_event::timed_wait(unsigned long time_in_ms = 0x1388) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 262]
      mysqld!os_event::wait_time_low(unsigned int64 time_in_usec = 0x4c4b40, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 415]
      mysqld!os_event_wait_time_low(struct os_event * event = 0x00000074`f6710530, unsigned int64 time_in_usec = 0x4c4b40, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 519]
      mysqld!srv_monitor_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 1746]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 12  Id: 13a0.f14 Suspend: 0 Teb: 00007ff7`b7db8000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtDelayExecution
      KERNELBASE!SleepEx
      mysqld!os_thread_sleep(unsigned int64 tm = 0xf4240) [d:\winx64-debug\build\src\storage\innobase\os\os0thread.cc @ 239]
      mysqld!srv_master_sleep(void) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 2459]
      mysqld!srv_master_thread(void * arg = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 2505]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 13  Id: 13a0.5a4 Suspend: 0 Teb: 00007ff7`b7db0000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!os_event::timed_wait(unsigned long time_in_ms = 0x2710) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 262]
      mysqld!os_event::wait_time_low(unsigned int64 time_in_usec = 0x989680, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 415]
      mysqld!os_event_wait_time_low(struct os_event * event = 0x00000074`f693e0b0, unsigned int64 time_in_usec = 0x989680, int64 reset_sig_count = 0n0) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 519]
      mysqld!dict_stats_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\dict\dict0stats_bg.cc @ 454]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 14  Id: 13a0.13bc Suspend: 0 Teb: 00007ff7`b7dae000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!os_event::timed_wait(unsigned long time_in_ms = 0x1388) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 262]
      mysqld!os_event::wait_time_low(unsigned int64 time_in_usec = 0x4c4b40, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 415]
      mysqld!os_event_wait_time_low(struct os_event * event = 0x00000074`f6929290, unsigned int64 time_in_usec = 0x4c4b40, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 519]
      mysqld!ib_wqueue_timedwait(struct ib_wqueue_t * wq = 0x00000074`f66dbf70, int64 wait_in_usecs = 0n5000000) [d:\winx64-debug\build\src\storage\innobase\ut\ut0wqueue.cc @ 161]
      mysqld!fts_optimize_thread(void * arg = 0x00000074`f66dbf70) [d:\winx64-debug\build\src\storage\innobase\fts\fts0opt.cc @ 3030]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 15  Id: 13a0.cdc Suspend: 0 Teb: 00007ff7`b7dac000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      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 = 0x00000074`f67106b0, int64 reset_sig_count = 0n1) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 536]
      mysqld!srv_resume_thread(struct srv_slot_t * slot = 0x00007ff7`ba9933b8, int64 sig_count = 0n1, bool wait = true, unsigned int64 timeout_usec = 0) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 943]
      mysqld!srv_purge_coordinator_suspend(struct srv_slot_t * slot = 0x00007ff7`ba9933b8, unsigned int64 rseg_history_len = 6) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 2798]
      mysqld!srv_purge_coordinator_thread(void * arg = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 2890]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 16  Id: 13a0.1130 Suspend: 0 Teb: 00007ff7`b7daa000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      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 = 0n2) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 340]
      mysqld!os_event_wait_low(struct os_event * event = 0x00000074`f6710590, int64 reset_sig_count = 0n0) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 536]
      mysqld!srv_resume_thread(struct srv_slot_t * slot = 0x00007ff7`ba993428, int64 sig_count = 0n0, bool wait = true, unsigned int64 timeout_usec = 0) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 943]
      mysqld!srv_worker_thread(void * arg = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\srv\srv0srv.cc @ 2649]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 19  Id: 13a0.11b4 Suspend: 0 Teb: 00007ff7`b7da4000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!ZwGetContextThread
      ntdll!RtlAllocateHeap
      ntdll!RtlAllocateHeap
      kernel32!MakeSortNode
       
      . 20  Id: 13a0.9dc Suspend: 0 Teb: 00007ff7`b7da2000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtDelayExecution
      KERNELBASE!SleepEx
      mysqld!os_thread_sleep(unsigned int64 tm = 0xf4240) [d:\winx64-debug\build\src\storage\innobase\os\os0thread.cc @ 239]
      mysqld!btr_defragment_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\btr\btr0defragment.cc @ 761]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 21  Id: 13a0.aa8 Suspend: 0 Teb: 00007ff7`b7da0000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      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 = 0x00000074`f6710710, int64 reset_sig_count = 0n0) [d:\winx64-debug\build\src\storage\innobase\os\os0event.cc @ 536]
      mysqld!buf_resize_thread(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\buf\buf0buf.cc @ 3081]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 22  Id: 13a0.880 Suspend: 0 Teb: 00007ff7`b7d9e000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!pthread_cond_timedwait(struct _RTL_CONDITION_VARIABLE * cond = 0x00000074`f955f738, struct _RTL_CRITICAL_SECTION * mutex = 0x00000074`f955f6e8, struct timespec * abstime = 0x00000000`00000000) [d:\winx64-debug\build\src\mysys\my_wincond.c @ 82]
      mysqld!pthread_cond_wait(struct _RTL_CONDITION_VARIABLE * cond = 0x00000074`f955f738, struct _RTL_CRITICAL_SECTION * mutex = 0x00000074`f955f6e8) [d:\winx64-debug\build\src\mysys\my_wincond.c @ 91]
      mysqld!inline_mysql_cond_wait(struct st_mysql_cond * that = 0x00000074`f955f738, struct st_mysql_mutex * mutex = 0x00000074`f955f6e8, char * src_file = 0x00007ff7`b9269bd0 "D:\winx64-debug\build\src\storage\innobase\handler\ha_innodb.cc", unsigned int src_line = 0x155) [d:\winx64-debug\build\src\include\mysql\psi\mysql_thread.h @ 1149]
      mysqld!thd_destructor_proxy(void * __formal = 0x00000000`00000000) [d:\winx64-debug\build\src\storage\innobase\handler\ha_innodb.cc @ 341]
      mysqld!pfs_spawn_thread(void * arg = 0x00000074`f69987c8) [d:\winx64-debug\build\src\storage\perfschema\pfs.cc @ 1865]
      mysqld!pthread_start(void * p = 0x00000074`f69b42c0) [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`b8df88f0) [d:\th\minkernel\crts\ucrt\src\appcrt\startup\thread.cpp @ 115]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 23  Id: 13a0.13d4 Suspend: 0 Teb: 00007ff7`b7d9c000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!NtWaitForAlertByThreadId
      ntdll!RtlSleepConditionVariableCS
      KERNELBASE!SleepConditionVariableCS
      mysqld!pthread_cond_timedwait(struct _RTL_CONDITION_VARIABLE * cond = 0x00007ff7`bb1ac8f0, struct _RTL_CRITICAL_SECTION * mutex = 0x00007ff7`bb1ac8c0, struct timespec * abstime = 0x00000074`fafdf7b8) [d:\winx64-debug\build\src\mysys\my_wincond.c @ 82]
      mysqld!inline_mysql_cond_timedwait(struct st_mysql_cond * that = 0x00007ff7`bb1ac8f0, struct st_mysql_mutex * mutex = 0x00007ff7`bb1ac8c0, struct timespec * abstime = 0x00000074`fafdf7b8, char * src_file = 0x00007ff7`ba711540 "--- memory read error at address 0x00007ff7`ba711540 ---", unsigned int src_line = 0x74) [d:\winx64-debug\build\src\include\mysql\psi\mysql_thread.h @ 1175]
      mysqld!my_service_thread_sleep(struct st_ma_service_thread_control * control = 0x00007ff7`ba6e0780, unsigned int64 sleep_time = 0x00000006`c088e200) [d:\winx64-debug\build\src\storage\maria\ma_servicethread.c @ 119]
      mysqld!ma_checkpoint_background(void * arg = 0x00000000`0000001e) [d:\winx64-debug\build\src\storage\maria\ma_checkpoint.c @ 709]
      mysqld!pfs_spawn_thread(void * arg = 0x00000074`f69987c8) [d:\winx64-debug\build\src\storage\perfschema\pfs.cc @ 1865]
      mysqld!pthread_start(void * p = 0x00000074`f69b41e0) [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`b8df88f0) [d:\th\minkernel\crts\ucrt\src\appcrt\startup\thread.cpp @ 115]
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      . 24  Id: 13a0.554 Suspend: 0 Teb: 00007ff7`b7d9a000 Unfrozen
            Priority: 0  Priority class: 32
       
      ntdll!ZwWaitForWorkViaWorkerFactory
      ntdll!RtlFreeUnicodeString
      kernel32!BaseThreadInitThunk
      ntdll!RtlUserThreadStart
       
      Total threads: 25
      Duplicate callstacks: 7 (windbg thread #s follow):
      3, 4, 5, 6, 7, 17, 18
      quit:
      

      Attachments

        Activity

          alice Alice Sherepa added a comment -

          on 10.1 http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10242/steps/test_2/logs/stdio

          innodb.doublewrite '32k,innodb_plugin'   w3 [ fail ]  Found warnings/errors in server log file!
                  Test ended at 2017-11-02 22:13:57
          line
          171102 22:13:52 [ERROR] mysqld got signal 11 ;
          

          http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10242/steps/test_2/logs/mysqld.1.err.3

          CURRENT_TEST: innodb.doublewrite
          2017-11-02 22:13:18 2815422272 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown
           
          2017-11-02 22:13:18 2815422272 [Note] Event Scheduler: Purging the queue. 0 events
          2017-11-02 22:13:18 2835536704 [Note] InnoDB: FTS optimize thread exiting.
          2017-11-02 22:13:18 2815422272 [Note] InnoDB: Starting shutdown...
          2017-11-02 22:13:20 2815422272 [Note] InnoDB: Shutdown completed; log sequence number 3151910
          2017-11-02 22:13:20 2815422272 [Note] Debug sync points hit:                   2136
          2017-11-02 22:13:20 2815422272 [Note] Debug sync points executed:              0
          2017-11-02 22:13:20 2815422272 [Note] Debug sync points max active per thread: 0
          2017-11-02 22:13:20 2815422272 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete
           
          2017-11-02 22:13:20 3071912256 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3010 ...
          2017-11-02 22:13:20 3071912256 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:20 3071912256 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:20 3071912256 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:20 3071912256 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:21 3071912256 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:21 3071912256 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:21 3071912256 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:22 3071912256 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:22 3071912256 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:22 3071912256 [Note] InnoDB: 5.6.37 started; log sequence number 3151910
          2017-11-02 22:13:22 2812279616 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:22 3071912256 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:22 3071912256 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:22 3071912256 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:22 3071912256 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Sync to disk of '"test"."t1"' started.
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Stopping purge
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Writing table metadata to './test/t1.cfg'
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Table '"test"."t1"' flushed to disk
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg'
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Resuming purge
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Saving InnoDB page number: 0
          2017-11-02 22:13:23 2891276096 [Note] InnoDB: Dirtying page:0 of space:11
          2017-11-02 22:13:23 3072452928 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3067 ...
          2017-11-02 22:13:23 3072452928 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:23 3072452928 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:23 3072452928 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:23 3072452928 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:23 3072452928 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:23 3072452928 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3151952
          InnoDB: Error: Current page size 32768 !=  page size on page 16384
          2017-11-02 22:13:23 3072452928 [ERROR] InnoDB: innodb-page-size mismatch in tablespace ./test/t1.ibd (table test/t1)
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:1024 Pages to analyze:64
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 1024, Possible space_id count:0
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:2048 Pages to analyze:64
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 2048, Possible space_id count:0
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:4096 Pages to analyze:48
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 4096, Possible space_id count:0
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:8192 Pages to analyze:24
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 8192, Possible space_id count:0
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:16384 Pages to analyze:12
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 16384, Possible space_id count:0
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:32768 Pages to analyze:6
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:1 page_size:32768
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:2 page_size:32768
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:3 page_size:32768
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 32768, Possible space_id count:1
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: space_id:11, Number of pages matched: 3/3 (32768)
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Chosen space:11
           
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Restoring page 0 of tablespace 11
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Writing 32768 bytes into file: ./test/t1.ibd
          2017-11-02 22:13:23 3072452928 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 3328
          2017-11-02 22:13:24 3072452928 [Note] InnoDB: Starting final batch to recover 5 pages from redo log
          2017-11-02 22:13:25 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:25 ac033b40  InnoDB: Rolling back trx with id 2820, 1 rows to undo
          2017-11-02 22:13:25 3072452928 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:25 2885892928 [Note] InnoDB: Rollback of trx with id 2820 completed
          2017-11-02 22:13:25 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:25 3072452928 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:25 3072452928 [Note] InnoDB: 5.6.37 started; log sequence number 3156408
          2017-11-02 22:13:25 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:25 3072452928 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:25 3072452928 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:25 3072452928 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:25 3072452928 [Note] Starting crash recovery...
          2017-11-02 22:13:25 3072452928 [Note] Crash recovery finished.
          2017-11-02 22:13:26 3072452928 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:26 3072452928 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Sync to disk of '"test"."t1"' started.
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Stopping purge
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Writing table metadata to './test/t1.cfg'
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Table '"test"."t1"' flushed to disk
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg'
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Resuming purge
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Saving InnoDB page number: 0
          2017-11-02 22:13:26 2823786304 [Note] InnoDB: Dirtying page:0 of space:11
          2017-11-02 22:13:26 3072059712 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3146 ...
          2017-11-02 22:13:26 3072059712 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:26 3072059712 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:27 3072059712 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:27 3072059712 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:27 3072059712 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:27 3072059712 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3156450
          InnoDB: Error: Current page size 32768 !=  page size on page 16384
          2017-11-02 22:13:27 3072059712 [ERROR] InnoDB: innodb-page-size mismatch in tablespace ./test/t1.ibd (table test/t1)
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:1024 Pages to analyze:64
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 1024, Possible space_id count:0
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:2048 Pages to analyze:64
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 2048, Possible space_id count:0
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:4096 Pages to analyze:48
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 4096, Possible space_id count:0
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:8192 Pages to analyze:24
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 8192, Possible space_id count:0
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:16384 Pages to analyze:12
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 16384, Possible space_id count:0
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:32768 Pages to analyze:6
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Log sequence number at the start 0 and the end 3156564 do not match.
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:1 page_size:32768
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:2 page_size:32768
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:3 page_size:32768
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 32768, Possible space_id count:1
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: space_id:11, Number of pages matched: 3/3 (32768)
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Chosen space:11
           
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Restoring page 0 of tablespace 11
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Writing 32768 bytes into file: ./test/t1.ibd
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 3840
          2017-11-02 22:13:27 3072059712 [Note] InnoDB: Starting final batch to recover 1 pages from redo log
          2017-11-02 22:13:29 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:29 abf96b40  InnoDB: Rolling back trx with id 3332, 1 rows to undo
          2017-11-02 22:13:29 3072059712 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:29 2885249856 [Note] InnoDB: Rollback of trx with id 3332 completed
          2017-11-02 22:13:29 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:29 3072059712 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:29 3072059712 [Note] InnoDB: 5.6.37 started; log sequence number 3156564
          2017-11-02 22:13:29 2812275520 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:29 3072059712 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:29 3072059712 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:29 3072059712 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:29 3072059712 [Note] Starting crash recovery...
          2017-11-02 22:13:29 3072059712 [Note] Crash recovery finished.
          2017-11-02 22:13:29 3072059712 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:29 3072059712 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Sync to disk of '"test"."t1"' started.
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Stopping purge
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Writing table metadata to './test/t1.cfg'
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Table '"test"."t1"' flushed to disk
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg'
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Resuming purge
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Saving InnoDB page number: 1
          2017-11-02 22:13:29 2891276096 [Note] InnoDB: Dirtying page:1 of space:11
          2017-11-02 22:13:30 3072432448 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3198 ...
          2017-11-02 22:13:30 3072432448 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:30 3072432448 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:30 3072432448 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:30 3072432448 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:30 3072432448 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:30 3072432448 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3156606
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          2017-11-02 22:13:30 3072432448 [Note] InnoDB: Recovered page 11:1 from the doublewrite buffer.
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 4352
          2017-11-02 22:13:31 3072432448 [Note] InnoDB: Starting final batch to recover 2 pages from redo log
          2017-11-02 22:13:32 3072432448 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:32 3072432448 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:32 2885897024 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:32 ac034b40  InnoDB: Rolling back trx with id 3844, 1 rows to undo
          2017-11-02 22:13:32 2885897024 [Note] InnoDB: Rollback of trx with id 3844 completed
          2017-11-02 22:13:32 2885897024 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:32 3072432448 [Note] InnoDB: 5.6.37 started; log sequence number 3157123
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:32 2813328192 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:32 3072432448 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:32 3072432448 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:32 3072432448 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:32 3072432448 [Note] Starting crash recovery...
          2017-11-02 22:13:32 3072432448 [Note] Crash recovery finished.
          2017-11-02 22:13:33 3072432448 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:33 3072432448 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Sync to disk of '"test"."t1"' started.
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Stopping purge
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Writing table metadata to './test/t1.cfg'
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Table '"test"."t1"' flushed to disk
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg'
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Resuming purge
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Saving InnoDB page number: 1
          2017-11-02 22:13:33 2815393600 [Note] InnoDB: Dirtying page:1 of space:11
          2017-11-02 22:13:33 3072555328 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3327 ...
          2017-11-02 22:13:33 3072555328 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:33 3072555328 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:33 3072555328 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:33 3072555328 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:34 3072555328 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:34 3072555328 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3157165
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Log sequence number at the start 0 and the end 3157682 do not match.
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Trying to recover page 11:1 from the doublewrite buffer.
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Recovered page 11:1 from the doublewrite buffer.
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 4864
          2017-11-02 22:13:34 3072555328 [Note] InnoDB: Starting final batch to recover 2 pages from redo log
          2017-11-02 22:13:35 2885897024 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:35 ac034b40  InnoDB: Rolling back trx with id 4356, 1 rows to undo
          2017-11-02 22:13:35 3072555328 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:35 2885897024 [Note] InnoDB: Rollback of trx with id 4356 completed
          2017-11-02 22:13:35 2885897024 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:35 3072555328 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:35 3072555328 [Note] InnoDB: 5.6.37 started; log sequence number 3157682
          2017-11-02 22:13:35 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:35 3072555328 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:35 3072555328 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:35 3072555328 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:35 3072555328 [Note] Starting crash recovery...
          2017-11-02 22:13:35 3072555328 [Note] Crash recovery finished.
          2017-11-02 22:13:35 3072555328 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:35 3072555328 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:36 2890616640 [Note] InnoDB: Saving InnoDB page number: 0
          2017-11-02 22:13:36 2890616640 [Note] InnoDB: Dirtying page:0 of space:0
          2017-11-02 22:13:36 3071957312 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3384 ...
          2017-11-02 22:13:36 3071957312 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:36 3071957312 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:36 3071957312 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:36 3071957312 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:37 3071957312 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:37 3071957312 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Completed initialization of buffer pool
          InnoDB: Error: Current page size 32768 !=  page size on page 16384
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Restoring page 0 of tablespace 0
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Writing 32768 bytes into file: ibdata1
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3157724
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 5376
          2017-11-02 22:13:37 3071957312 [Note] InnoDB: Starting final batch to recover 2 pages from redo log
          2017-11-02 22:13:38 3071957312 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:38 3071957312 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:38 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:38 abf96b40  InnoDB: Rolling back trx with id 4868, 1 rows to undo
          2017-11-02 22:13:38 2885249856 [Note] InnoDB: Rollback of trx with id 4868 completed
          2017-11-02 22:13:38 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:38 3071957312 [Note] InnoDB: 5.6.37 started; log sequence number 3158241
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:38 2812279616 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:38 3071957312 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:38 3071957312 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:38 3071957312 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:38 3071957312 [Note] Starting crash recovery...
          2017-11-02 22:13:38 3071957312 [Note] Crash recovery finished.
          2017-11-02 22:13:38 3071957312 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:38 3071957312 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:39 2891276096 [Note] InnoDB: Saving InnoDB page number: 0
          2017-11-02 22:13:39 2891276096 [Note] InnoDB: Dirtying page:0 of space:0
          2017-11-02 22:13:39 3071883584 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3474 ...
          2017-11-02 22:13:39 3071883584 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:39 3071883584 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:39 3071883584 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:39 3071883584 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:40 3071883584 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:40 3071883584 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Completed initialization of buffer pool
          InnoDB: Error: Current page size 32768 !=  page size on page 16384
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Restoring page 0 of tablespace 0
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Writing 32768 bytes into file: ibdata1
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3158283
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 5888
          2017-11-02 22:13:40 3071883584 [Note] InnoDB: Starting final batch to recover 2 pages from redo log
          2017-11-02 22:13:42 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:42 abf96b40  InnoDB: Rolling back trx with id 5380, 1 rows to undo
          2017-11-02 22:13:42 3071883584 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:42 2885249856 [Note] InnoDB: Rollback of trx with id 5380 completed
          2017-11-02 22:13:42 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:42 3071883584 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:42 3071883584 [Note] InnoDB: 5.6.37 started; log sequence number 3158800
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:42 3071883584 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:42 3071883584 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:42 3071883584 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:42 3071883584 [Note] Starting crash recovery...
          2017-11-02 22:13:42 2812275520 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:42 3071883584 [Note] Crash recovery finished.
          2017-11-02 22:13:42 3071883584 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:42 3071883584 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:42 2889871168 [Note] InnoDB: Saving InnoDB page number: 1
          2017-11-02 22:13:42 2889871168 [Note] InnoDB: Dirtying page:1 of space:0
          2017-11-02 22:13:43 3072416064 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3522 ...
          2017-11-02 22:13:43 3072416064 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:43 3072416064 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:43 3072416064 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:43 3072416064 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:43 3072416064 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:43 3072416064 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3158842
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Recovered page 0:1 from the doublewrite buffer.
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 6400
          2017-11-02 22:13:43 3072416064 [Note] InnoDB: Starting final batch to recover 1 pages from redo log
          2017-11-02 22:13:45 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:45 ac033b40  InnoDB: Rolling back trx with id 5892, 1 rows to undo
          2017-11-02 22:13:45 3072416064 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:45 2885892928 [Note] InnoDB: Rollback of trx with id 5892 completed
          2017-11-02 22:13:45 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:45 3072416064 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:45 3072416064 [Note] InnoDB: 5.6.37 started; log sequence number 3159359
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:45 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:45 3072416064 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:45 3072416064 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:45 3072416064 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:45 3072416064 [Note] Starting crash recovery...
          2017-11-02 22:13:45 3072416064 [Note] Crash recovery finished.
          2017-11-02 22:13:45 3072416064 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:45 3072416064 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:45 2823786304 [Note] InnoDB: Saving InnoDB page number: 1
          2017-11-02 22:13:45 2823786304 [Note] InnoDB: Dirtying page:1 of space:0
          2017-11-02 22:13:46 3072301376 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3577 ...
          2017-11-02 22:13:46 3072301376 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:46 3072301376 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:46 3072301376 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:46 3072301376 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:46 3072301376 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:46 3072301376 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3159401
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer...
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Log sequence number at the start 0 and the end 3159918 do not match.
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Trying to recover page 0:1 from the doublewrite buffer.
          2017-11-02 22:13:46 3072301376 [Note] InnoDB: Recovered page 0:1 from the doublewrite buffer.
          InnoDB: 1 transaction(s) which must be rolled back or cleaned up
          InnoDB: in total 1 row operations to undo
          InnoDB: Trx id counter is 6912
          2017-11-02 22:13:47 3072301376 [Note] InnoDB: Starting final batch to recover 1 pages from redo log
          2017-11-02 22:13:48 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions
          2017-11-02 22:13:48 ac033b40  InnoDB: Rolling back trx with id 6404, 1 rows to undo
          2017-11-02 22:13:48 2885892928 [Note] InnoDB: Rollback of trx with id 6404 completed
          2017-11-02 22:13:48 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed
          2017-11-02 22:13:48 3072301376 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:48 3072301376 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:48 3072301376 [Note] InnoDB: 5.6.37 started; log sequence number 3159918
          2017-11-02 22:13:48 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:48 3072301376 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:48 3072301376 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:48 3072301376 [Note] Recovering after a crash using tc.log
          2017-11-02 22:13:48 3072301376 [Note] Starting crash recovery...
          2017-11-02 22:13:48 3072301376 [Note] Crash recovery finished.
          2017-11-02 22:13:48 3072301376 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:49 3072301376 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:49 2823483200 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown
           
          2017-11-02 22:13:49 2823483200 [Note] Event Scheduler: Purging the queue. 0 events
          2017-11-02 22:13:49 2840574784 [Note] InnoDB: FTS optimize thread exiting.
          2017-11-02 22:13:49 2823483200 [Note] InnoDB: Starting shutdown...
          2017-11-02 22:13:50 2823483200 [Note] InnoDB: Shutdown completed; log sequence number 3161533
          2017-11-02 22:13:50 2823483200 [Note] Debug sync points hit:                   344
          2017-11-02 22:13:50 2823483200 [Note] Debug sync points executed:              0
          2017-11-02 22:13:50 2823483200 [Note] Debug sync points max active per thread: 0
          2017-11-02 22:13:50 2823483200 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete
           
          2017-11-02 22:13:51 3072088384 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3655 ...
          2017-11-02 22:13:51 3072088384 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:51 3072088384 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:51 3072088384 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: The first specified data file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata1 did not exist: a new database to be created!
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata1 size to 1 MB
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Data file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata2 did not exist: new to be created
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata2 size to 1 MB
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile101 size to 5 MB
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile1 size to 5 MB
          2017-11-02 22:13:51 3072088384 [Note] InnoDB: Renaming log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile101 to /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile0
          2017-11-02 22:13:51 3072088384 [Warning] InnoDB: New log files created, LSN=74142
          2017-11-02 22:13:51 3072088384 [ERROR] InnoDB: Cannot create doublewrite buffer: the first file in innodb_data_file_path must be at least 6M.
          2017-11-02 22:13:51 3072088384 [ERROR] InnoDB: Database creation was aborted at /home/buildbot/buildbot/build/mariadb-10.1.29/storage/innobase/srv/srv0start.cc [2832] with error Generic error. You may need to delete the ibdata1 file before trying to start up again.
          2017-11-02 22:13:51 3072088384 [ERROR] Plugin 'InnoDB' init function returned error.
          2017-11-02 22:13:51 3072088384 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:51 3072088384 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:51 3072088384 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:52 3072088384 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:52 3072088384 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          2017-11-02 22:13:52 2885676864 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown
           
          2017-11-02 22:13:52 2885676864 [Note] Event Scheduler: Purging the queue. 0 events
          2017-11-02 22:13:52 2885676864 [Note] Debug sync points hit:                   265
          2017-11-02 22:13:52 2885676864 [Note] Debug sync points executed:              0
          2017-11-02 22:13:52 2885676864 [Note] Debug sync points max active per thread: 0
          2017-11-02 22:13:52 2885676864 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete
           
          171102 22:13:52 [ERROR] mysqld got signal 11 ;
          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.1.29-MariaDB-debug
          read_buffer_size=131072
          max_used_connections=1
          thread_count=0
          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...
          2017-11-02 22:13:54 3071990080 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3726 ...
          2017-11-02 22:13:54 3071990080 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162)
          2017-11-02 22:13:54 3071990080 [Note] Plugin 'partition' is disabled.
          2017-11-02 22:13:54 3071990080 [Note] Plugin 'SEQUENCE' is disabled.
          2017-11-02 22:13:54 3071990080 [Note] InnoDB:  innodb-page-size has been changed from the default value 16384 to 32768.
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using mutexes to ref count buffer pool pages
          2017-11-02 22:13:55 3071990080 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:55 3071990080 [Note] InnoDB:  InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!!
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: The InnoDB memory heap is disabled
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Compressed tables use zlib 1.2.3.4
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using Linux native AIO
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using generic crc32 instructions
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Initializing buffer pool, size = 24.0M
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Completed initialization of buffer pool
          2017-11-02 22:13:55 3071990080 [Note] InnoDB: Highest supported file format is Barracuda.
          2017-11-02 22:13:56 3071990080 [Note] InnoDB: 128 rollback segment(s) are active.
          2017-11-02 22:13:56 3071990080 [Note] InnoDB: Waiting for purge to start
          2017-11-02 22:13:56 3071990080 [Note] InnoDB: 5.6.37 started; log sequence number 3161533
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_READ_VIEW' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_RSEG' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_DELETED' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_MUTEXES' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_CONFIG' is disabled.
          2017-11-02 22:13:56 2813328192 [Note] InnoDB: Dumping buffer pool(s) not yet started
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'FEEDBACK' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP_RESET' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled.
          2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled.
          2017-11-02 22:13:56 3071990080 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug'
          2017-11-02 22:13:56 3071990080 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages'
          2017-11-02 22:13:56 3071990080 [Note] Server socket created on IP: '127.0.0.1'.
          2017-11-02 22:13:56 3071990080 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections.
          Version: '10.1.29-MariaDB-debug'  socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock'  port: 16000  Source distribution
          

          alice Alice Sherepa added a comment - on 10.1 http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10242/steps/test_2/logs/stdio innodb.doublewrite '32k,innodb_plugin' w3 [ fail ] Found warnings/errors in server log file! Test ended at 2017-11-02 22:13:57 line 171102 22:13:52 [ERROR] mysqld got signal 11 ; http://buildbot.askmonty.org/buildbot/builders/kvm-fulltest2/builds/10242/steps/test_2/logs/mysqld.1.err.3 CURRENT_TEST: innodb.doublewrite 2017-11-02 22:13:18 2815422272 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown   2017-11-02 22:13:18 2815422272 [Note] Event Scheduler: Purging the queue. 0 events 2017-11-02 22:13:18 2835536704 [Note] InnoDB: FTS optimize thread exiting. 2017-11-02 22:13:18 2815422272 [Note] InnoDB: Starting shutdown... 2017-11-02 22:13:20 2815422272 [Note] InnoDB: Shutdown completed; log sequence number 3151910 2017-11-02 22:13:20 2815422272 [Note] Debug sync points hit: 2136 2017-11-02 22:13:20 2815422272 [Note] Debug sync points executed: 0 2017-11-02 22:13:20 2815422272 [Note] Debug sync points max active per thread: 0 2017-11-02 22:13:20 2815422272 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete   2017-11-02 22:13:20 3071912256 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3010 ... 2017-11-02 22:13:20 3071912256 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:20 3071912256 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:20 3071912256 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:20 3071912256 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:21 3071912256 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:21 3071912256 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:21 3071912256 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:21 3071912256 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:21 3071912256 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:22 3071912256 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:22 3071912256 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:22 3071912256 [Note] InnoDB: 5.6.37 started; log sequence number 3151910 2017-11-02 22:13:22 2812279616 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:22 3071912256 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:22 3071912256 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:22 3071912256 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:22 3071912256 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:22 3071912256 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Sync to disk of '"test"."t1"' started. 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Stopping purge 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Writing table metadata to './test/t1.cfg' 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Table '"test"."t1"' flushed to disk 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg' 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Resuming purge 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Saving InnoDB page number: 0 2017-11-02 22:13:23 2891276096 [Note] InnoDB: Dirtying page:0 of space:11 2017-11-02 22:13:23 3072452928 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3067 ... 2017-11-02 22:13:23 3072452928 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:23 3072452928 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:23 3072452928 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:23 3072452928 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:23 3072452928 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:23 3072452928 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:23 3072452928 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:23 3072452928 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3151952 InnoDB: Error: Current page size 32768 != page size on page 16384 2017-11-02 22:13:23 3072452928 [ERROR] InnoDB: innodb-page-size mismatch in tablespace ./test/t1.ibd (table test/t1) 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:1024 Pages to analyze:64 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 1024, Possible space_id count:0 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:2048 Pages to analyze:64 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 2048, Possible space_id count:0 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:4096 Pages to analyze:48 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 4096, Possible space_id count:0 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:8192 Pages to analyze:24 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 8192, Possible space_id count:0 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:16384 Pages to analyze:12 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 16384, Possible space_id count:0 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size:32768 Pages to analyze:6 2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:1 page_size:32768 2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:2 page_size:32768 2017-11-02 22:13:23 3072452928 [Note] InnoDB: VALID: space:11 page_no:3 page_size:32768 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Page size: 32768, Possible space_id count:1 2017-11-02 22:13:23 3072452928 [Note] InnoDB: space_id:11, Number of pages matched: 3/3 (32768) 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Chosen space:11   2017-11-02 22:13:23 3072452928 [Note] InnoDB: Restoring page 0 of tablespace 11 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Writing 32768 bytes into file: ./test/t1.ibd 2017-11-02 22:13:23 3072452928 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 3328 2017-11-02 22:13:24 3072452928 [Note] InnoDB: Starting final batch to recover 5 pages from redo log 2017-11-02 22:13:25 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:25 ac033b40 InnoDB: Rolling back trx with id 2820, 1 rows to undo 2017-11-02 22:13:25 3072452928 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:25 2885892928 [Note] InnoDB: Rollback of trx with id 2820 completed 2017-11-02 22:13:25 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:25 3072452928 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:25 3072452928 [Note] InnoDB: 5.6.37 started; log sequence number 3156408 2017-11-02 22:13:25 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:25 3072452928 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:25 3072452928 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:25 3072452928 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:25 3072452928 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:25 3072452928 [Note] Starting crash recovery... 2017-11-02 22:13:25 3072452928 [Note] Crash recovery finished. 2017-11-02 22:13:26 3072452928 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:26 3072452928 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Sync to disk of '"test"."t1"' started. 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Stopping purge 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Writing table metadata to './test/t1.cfg' 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Table '"test"."t1"' flushed to disk 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg' 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Resuming purge 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Saving InnoDB page number: 0 2017-11-02 22:13:26 2823786304 [Note] InnoDB: Dirtying page:0 of space:11 2017-11-02 22:13:26 3072059712 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3146 ... 2017-11-02 22:13:26 3072059712 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:26 3072059712 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:27 3072059712 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:27 3072059712 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:27 3072059712 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:27 3072059712 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:27 3072059712 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:27 3072059712 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3156450 InnoDB: Error: Current page size 32768 != page size on page 16384 2017-11-02 22:13:27 3072059712 [ERROR] InnoDB: innodb-page-size mismatch in tablespace ./test/t1.ibd (table test/t1) 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:1024 Pages to analyze:64 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 1024, Possible space_id count:0 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:2048 Pages to analyze:64 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 2048, Possible space_id count:0 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:4096 Pages to analyze:48 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 4096, Possible space_id count:0 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:8192 Pages to analyze:24 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 8192, Possible space_id count:0 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:16384 Pages to analyze:12 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 16384, Possible space_id count:0 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size:32768 Pages to analyze:6 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Log sequence number at the start 0 and the end 3156564 do not match. 2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:1 page_size:32768 2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:2 page_size:32768 2017-11-02 22:13:27 3072059712 [Note] InnoDB: VALID: space:11 page_no:3 page_size:32768 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Page size: 32768, Possible space_id count:1 2017-11-02 22:13:27 3072059712 [Note] InnoDB: space_id:11, Number of pages matched: 3/3 (32768) 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Chosen space:11   2017-11-02 22:13:27 3072059712 [Note] InnoDB: Restoring page 0 of tablespace 11 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Writing 32768 bytes into file: ./test/t1.ibd 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 3840 2017-11-02 22:13:27 3072059712 [Note] InnoDB: Starting final batch to recover 1 pages from redo log 2017-11-02 22:13:29 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:29 abf96b40 InnoDB: Rolling back trx with id 3332, 1 rows to undo 2017-11-02 22:13:29 3072059712 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:29 2885249856 [Note] InnoDB: Rollback of trx with id 3332 completed 2017-11-02 22:13:29 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:29 3072059712 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:29 3072059712 [Note] InnoDB: 5.6.37 started; log sequence number 3156564 2017-11-02 22:13:29 2812275520 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:29 3072059712 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:29 3072059712 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:29 3072059712 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:29 3072059712 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:29 3072059712 [Note] Starting crash recovery... 2017-11-02 22:13:29 3072059712 [Note] Crash recovery finished. 2017-11-02 22:13:29 3072059712 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:29 3072059712 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Sync to disk of '"test"."t1"' started. 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Stopping purge 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Writing table metadata to './test/t1.cfg' 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Table '"test"."t1"' flushed to disk 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg' 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Resuming purge 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Saving InnoDB page number: 1 2017-11-02 22:13:29 2891276096 [Note] InnoDB: Dirtying page:1 of space:11 2017-11-02 22:13:30 3072432448 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3198 ... 2017-11-02 22:13:30 3072432448 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:30 3072432448 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:30 3072432448 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:30 3072432448 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:30 3072432448 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:30 3072432448 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:30 3072432448 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:30 3072432448 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3156606 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... 2017-11-02 22:13:30 3072432448 [Note] InnoDB: Recovered page 11:1 from the doublewrite buffer. InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 4352 2017-11-02 22:13:31 3072432448 [Note] InnoDB: Starting final batch to recover 2 pages from redo log 2017-11-02 22:13:32 3072432448 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:32 3072432448 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:32 2885897024 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:32 ac034b40 InnoDB: Rolling back trx with id 3844, 1 rows to undo 2017-11-02 22:13:32 2885897024 [Note] InnoDB: Rollback of trx with id 3844 completed 2017-11-02 22:13:32 2885897024 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:32 3072432448 [Note] InnoDB: 5.6.37 started; log sequence number 3157123 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:32 2813328192 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:32 3072432448 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:32 3072432448 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:32 3072432448 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:32 3072432448 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:32 3072432448 [Note] Starting crash recovery... 2017-11-02 22:13:32 3072432448 [Note] Crash recovery finished. 2017-11-02 22:13:33 3072432448 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:33 3072432448 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Sync to disk of '"test"."t1"' started. 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Stopping purge 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Writing table metadata to './test/t1.cfg' 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Table '"test"."t1"' flushed to disk 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Deleting the meta-data file './test/t1.cfg' 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Resuming purge 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Saving InnoDB page number: 1 2017-11-02 22:13:33 2815393600 [Note] InnoDB: Dirtying page:1 of space:11 2017-11-02 22:13:33 3072555328 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3327 ... 2017-11-02 22:13:33 3072555328 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:33 3072555328 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:33 3072555328 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:33 3072555328 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:34 3072555328 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:34 3072555328 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:34 3072555328 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:34 3072555328 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3157165 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Log sequence number at the start 0 and the end 3157682 do not match. 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Trying to recover page 11:1 from the doublewrite buffer. 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Recovered page 11:1 from the doublewrite buffer. InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 4864 2017-11-02 22:13:34 3072555328 [Note] InnoDB: Starting final batch to recover 2 pages from redo log 2017-11-02 22:13:35 2885897024 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:35 ac034b40 InnoDB: Rolling back trx with id 4356, 1 rows to undo 2017-11-02 22:13:35 3072555328 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:35 2885897024 [Note] InnoDB: Rollback of trx with id 4356 completed 2017-11-02 22:13:35 2885897024 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:35 3072555328 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:35 3072555328 [Note] InnoDB: 5.6.37 started; log sequence number 3157682 2017-11-02 22:13:35 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:35 3072555328 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:35 3072555328 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:35 3072555328 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:35 3072555328 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:35 3072555328 [Note] Starting crash recovery... 2017-11-02 22:13:35 3072555328 [Note] Crash recovery finished. 2017-11-02 22:13:35 3072555328 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:35 3072555328 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:36 2890616640 [Note] InnoDB: Saving InnoDB page number: 0 2017-11-02 22:13:36 2890616640 [Note] InnoDB: Dirtying page:0 of space:0 2017-11-02 22:13:36 3071957312 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3384 ... 2017-11-02 22:13:36 3071957312 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:36 3071957312 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:36 3071957312 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:36 3071957312 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:37 3071957312 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:37 3071957312 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:37 3071957312 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:37 3071957312 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Completed initialization of buffer pool InnoDB: Error: Current page size 32768 != page size on page 16384 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Restoring page 0 of tablespace 0 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Writing 32768 bytes into file: ibdata1 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3157724 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 5376 2017-11-02 22:13:37 3071957312 [Note] InnoDB: Starting final batch to recover 2 pages from redo log 2017-11-02 22:13:38 3071957312 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:38 3071957312 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:38 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:38 abf96b40 InnoDB: Rolling back trx with id 4868, 1 rows to undo 2017-11-02 22:13:38 2885249856 [Note] InnoDB: Rollback of trx with id 4868 completed 2017-11-02 22:13:38 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:38 3071957312 [Note] InnoDB: 5.6.37 started; log sequence number 3158241 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:38 2812279616 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:38 3071957312 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:38 3071957312 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:38 3071957312 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:38 3071957312 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:38 3071957312 [Note] Starting crash recovery... 2017-11-02 22:13:38 3071957312 [Note] Crash recovery finished. 2017-11-02 22:13:38 3071957312 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:38 3071957312 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:39 2891276096 [Note] InnoDB: Saving InnoDB page number: 0 2017-11-02 22:13:39 2891276096 [Note] InnoDB: Dirtying page:0 of space:0 2017-11-02 22:13:39 3071883584 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3474 ... 2017-11-02 22:13:39 3071883584 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:39 3071883584 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:39 3071883584 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:39 3071883584 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:40 3071883584 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:40 3071883584 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:40 3071883584 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:40 3071883584 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Completed initialization of buffer pool InnoDB: Error: Current page size 32768 != page size on page 16384 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Restoring page 0 of tablespace 0 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Writing 32768 bytes into file: ibdata1 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3158283 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 5888 2017-11-02 22:13:40 3071883584 [Note] InnoDB: Starting final batch to recover 2 pages from redo log 2017-11-02 22:13:42 2885249856 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:42 abf96b40 InnoDB: Rolling back trx with id 5380, 1 rows to undo 2017-11-02 22:13:42 3071883584 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:42 2885249856 [Note] InnoDB: Rollback of trx with id 5380 completed 2017-11-02 22:13:42 2885249856 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:42 3071883584 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:42 3071883584 [Note] InnoDB: 5.6.37 started; log sequence number 3158800 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:42 3071883584 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:42 3071883584 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:42 3071883584 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:42 3071883584 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:42 3071883584 [Note] Starting crash recovery... 2017-11-02 22:13:42 2812275520 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:42 3071883584 [Note] Crash recovery finished. 2017-11-02 22:13:42 3071883584 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:42 3071883584 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:42 2889871168 [Note] InnoDB: Saving InnoDB page number: 1 2017-11-02 22:13:42 2889871168 [Note] InnoDB: Dirtying page:1 of space:0 2017-11-02 22:13:43 3072416064 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3522 ... 2017-11-02 22:13:43 3072416064 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:43 3072416064 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:43 3072416064 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:43 3072416064 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:43 3072416064 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:43 3072416064 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:43 3072416064 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:43 3072416064 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3158842 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Recovered page 0:1 from the doublewrite buffer. InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 6400 2017-11-02 22:13:43 3072416064 [Note] InnoDB: Starting final batch to recover 1 pages from redo log 2017-11-02 22:13:45 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:45 ac033b40 InnoDB: Rolling back trx with id 5892, 1 rows to undo 2017-11-02 22:13:45 3072416064 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:45 2885892928 [Note] InnoDB: Rollback of trx with id 5892 completed 2017-11-02 22:13:45 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:45 3072416064 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:45 3072416064 [Note] InnoDB: 5.6.37 started; log sequence number 3159359 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:45 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:45 3072416064 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:45 3072416064 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:45 3072416064 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:45 3072416064 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:45 3072416064 [Note] Starting crash recovery... 2017-11-02 22:13:45 3072416064 [Note] Crash recovery finished. 2017-11-02 22:13:45 3072416064 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:45 3072416064 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:45 2823786304 [Note] InnoDB: Saving InnoDB page number: 1 2017-11-02 22:13:45 2823786304 [Note] InnoDB: Dirtying page:1 of space:0 2017-11-02 22:13:46 3072301376 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3577 ... 2017-11-02 22:13:46 3072301376 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:46 3072301376 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:46 3072301376 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:46 3072301376 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:46 3072301376 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:46 3072301376 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:46 3072301376 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:46 3072301376 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Starting crash recovery from checkpoint LSN=3159401 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Restoring possible half-written data pages from the doublewrite buffer... 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Log sequence number at the start 0 and the end 3159918 do not match. 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Trying to recover page 0:1 from the doublewrite buffer. 2017-11-02 22:13:46 3072301376 [Note] InnoDB: Recovered page 0:1 from the doublewrite buffer. InnoDB: 1 transaction(s) which must be rolled back or cleaned up InnoDB: in total 1 row operations to undo InnoDB: Trx id counter is 6912 2017-11-02 22:13:47 3072301376 [Note] InnoDB: Starting final batch to recover 1 pages from redo log 2017-11-02 22:13:48 2885892928 [Note] InnoDB: Starting in background the rollback of recovered transactions 2017-11-02 22:13:48 ac033b40 InnoDB: Rolling back trx with id 6404, 1 rows to undo 2017-11-02 22:13:48 2885892928 [Note] InnoDB: Rollback of trx with id 6404 completed 2017-11-02 22:13:48 2885892928 [Note] InnoDB: Rollback of non-prepared transactions completed 2017-11-02 22:13:48 3072301376 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:48 3072301376 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:48 3072301376 [Note] InnoDB: 5.6.37 started; log sequence number 3159918 2017-11-02 22:13:48 2813324096 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:48 3072301376 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:48 3072301376 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:48 3072301376 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:48 3072301376 [Note] Recovering after a crash using tc.log 2017-11-02 22:13:48 3072301376 [Note] Starting crash recovery... 2017-11-02 22:13:48 3072301376 [Note] Crash recovery finished. 2017-11-02 22:13:48 3072301376 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:49 3072301376 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:49 2823483200 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown   2017-11-02 22:13:49 2823483200 [Note] Event Scheduler: Purging the queue. 0 events 2017-11-02 22:13:49 2840574784 [Note] InnoDB: FTS optimize thread exiting. 2017-11-02 22:13:49 2823483200 [Note] InnoDB: Starting shutdown... 2017-11-02 22:13:50 2823483200 [Note] InnoDB: Shutdown completed; log sequence number 3161533 2017-11-02 22:13:50 2823483200 [Note] Debug sync points hit: 344 2017-11-02 22:13:50 2823483200 [Note] Debug sync points executed: 0 2017-11-02 22:13:50 2823483200 [Note] Debug sync points max active per thread: 0 2017-11-02 22:13:50 2823483200 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete   2017-11-02 22:13:51 3072088384 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3655 ... 2017-11-02 22:13:51 3072088384 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:51 3072088384 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:51 3072088384 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:51 3072088384 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:51 3072088384 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:51 3072088384 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:51 3072088384 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:51 3072088384 [Note] InnoDB: The first specified data file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata1 did not exist: a new database to be created! 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata1 size to 1 MB 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Data file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata2 did not exist: new to be created 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ibdata2 size to 1 MB 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile101 size to 5 MB 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Setting log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile1 size to 5 MB 2017-11-02 22:13:51 3072088384 [Note] InnoDB: Renaming log file /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile101 to /mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/3/tmp/doublewrite/ib_logfile0 2017-11-02 22:13:51 3072088384 [Warning] InnoDB: New log files created, LSN=74142 2017-11-02 22:13:51 3072088384 [ERROR] InnoDB: Cannot create doublewrite buffer: the first file in innodb_data_file_path must be at least 6M. 2017-11-02 22:13:51 3072088384 [ERROR] InnoDB: Database creation was aborted at /home/buildbot/buildbot/build/mariadb-10.1.29/storage/innobase/srv/srv0start.cc [2832] with error Generic error. You may need to delete the ibdata1 file before trying to start up again. 2017-11-02 22:13:51 3072088384 [ERROR] Plugin 'InnoDB' init function returned error. 2017-11-02 22:13:51 3072088384 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:51 3072088384 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:51 3072088384 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:51 3072088384 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:52 3072088384 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:52 3072088384 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution 2017-11-02 22:13:52 2885676864 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Normal shutdown   2017-11-02 22:13:52 2885676864 [Note] Event Scheduler: Purging the queue. 0 events 2017-11-02 22:13:52 2885676864 [Note] Debug sync points hit: 265 2017-11-02 22:13:52 2885676864 [Note] Debug sync points executed: 0 2017-11-02 22:13:52 2885676864 [Note] Debug sync points max active per thread: 0 2017-11-02 22:13:52 2885676864 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: Shutdown complete   171102 22:13:52 [ERROR] mysqld got signal 11 ; 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.1.29-MariaDB-debug read_buffer_size=131072 max_used_connections=1 thread_count=0 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... 2017-11-02 22:13:54 3071990080 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld (mysqld 10.1.29-MariaDB-debug) starting as process 3726 ... 2017-11-02 22:13:54 3071990080 [Warning] Could not increase number of max_open_files to more than 1024 (request: 4162) 2017-11-02 22:13:54 3071990080 [Note] Plugin 'partition' is disabled. 2017-11-02 22:13:54 3071990080 [Note] Plugin 'SEQUENCE' is disabled. 2017-11-02 22:13:54 3071990080 [Note] InnoDB: innodb-page-size has been changed from the default value 16384 to 32768. 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using mutexes to ref count buffer pool pages 2017-11-02 22:13:55 3071990080 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:55 3071990080 [Note] InnoDB: InnoDB: !!!!!!!! UNIV_SYNC_DEBUG switched on !!!!!!!!! 2017-11-02 22:13:55 3071990080 [Note] InnoDB: The InnoDB memory heap is disabled 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins 2017-11-02 22:13:55 3071990080 [Note] InnoDB: GCC builtin __sync_synchronize() is used for memory barrier 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Compressed tables use zlib 1.2.3.4 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using Linux native AIO 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Using generic crc32 instructions 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Initializing buffer pool, size = 24.0M 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Completed initialization of buffer pool 2017-11-02 22:13:55 3071990080 [Note] InnoDB: Highest supported file format is Barracuda. 2017-11-02 22:13:56 3071990080 [Note] InnoDB: 128 rollback segment(s) are active. 2017-11-02 22:13:56 3071990080 [Note] InnoDB: Waiting for purge to start 2017-11-02 22:13:56 3071990080 [Note] InnoDB: 5.6.37 started; log sequence number 3161533 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_DATAFILES' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_TABLESTATS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_READ_VIEW' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_RSEG' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_DELETED' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_LOCK_WAITS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'CHANGED_PAGE_BITMAPS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_TABLESPACES_ENCRYPTION' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_FIELDS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'XTRADB_INTERNAL_HASH_TABLES' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_TABLESPACES_SCRUBBING' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMPMEM_RESET' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_INDEX_TABLE' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_BEING_DELETED' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_MUTEXES' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_CONFIG' is disabled. 2017-11-02 22:13:56 2813328192 [Note] InnoDB: Dumping buffer pool(s) not yet started 2017-11-02 22:13:56 3071990080 [Note] Plugin 'FEEDBACK' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_INDEX_CACHE' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_FT_DEFAULT_STOPWORD' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP_RESET' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_COLUMNS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_CMP_PER_INDEX_RESET' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_TABLESPACES' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_SEMAPHORE_WAITS' is disabled. 2017-11-02 22:13:56 3071990080 [Note] Plugin 'INNODB_SYS_INDEXES' is disabled. 2017-11-02 22:13:56 3071990080 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-pam-debug' 2017-11-02 22:13:56 3071990080 [Warning] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: unknown option '--loose-skip-plugin-innodb-changed-pages' 2017-11-02 22:13:56 3071990080 [Note] Server socket created on IP: '127.0.0.1'. 2017-11-02 22:13:56 3071990080 [Note] /mnt/buildbot/build/mariadb-10.1.29/sql/mysqld: ready for connections. Version: '10.1.29-MariaDB-debug' socket: '/mnt/buildbot/build/mariadb-10.1.29/mysql-test/var/tmp/3/mysqld.1.sock' port: 16000 Source distribution

          alice, your report of a server crash at shutdown after failed InnoDB startup is a known problem that I fixed in 10.2. It is not easily fixable in 10.1 or earlier versions, because it would require very intrusive refactoring of the startup and shutdown code.

          It also is unrelated to the original report, which is a crash at server startup when InnoDB is invoking sql_print_information(). This crash is theoretically possible in 10.0 and 10.1 as well, depending on what causes the problem.

          In any case, I would suggest to change the bug title not to refer to innodb.doublewrite, because both failures (the startup crash and the shutdown crash) are possible also in other tests, and neither failure has anything directly to do with the InnoDB doublewrite buffer.

          marko Marko Mäkelä added a comment - alice , your report of a server crash at shutdown after failed InnoDB startup is a known problem that I fixed in 10.2. It is not easily fixable in 10.1 or earlier versions, because it would require very intrusive refactoring of the startup and shutdown code. It also is unrelated to the original report, which is a crash at server startup when InnoDB is invoking sql_print_information(). This crash is theoretically possible in 10.0 and 10.1 as well, depending on what causes the problem. In any case, I would suggest to change the bug title not to refer to innodb.doublewrite, because both failures (the startup crash and the shutdown crash) are possible also in other tests, and neither failure has anything directly to do with the InnoDB doublewrite buffer.

          changed the title to reflect the actual problem, in the original crash.

          wlad Vladislav Vaintroub added a comment - changed the title to reflect the actual problem, in the original crash.

          People

            wlad Vladislav Vaintroub
            elenst Elena Stepanova
            Votes:
            0 Vote for this issue
            Watchers:
            5 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved:

              Git Integration

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