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

MariaDB 10.3.8 crashes while inserting data

    XMLWordPrintable

Details

    • Bug
    • Status: Closed (View Workflow)
    • Trivial
    • Resolution: Not a Bug
    • None
    • N/A
    • Server
    • None
    • Windows 10

    Description

      I upgraded to version 10.3.8 from 10.2.7 today, and having problem in inserting data to the table.
      MySQL crashes after inserting a few million rows with the following error.

      2018-08-12 16:14:02 81 [Warning] Aborted connection 81 to db: 'XXX' user: 'XXXX' host: 'XXXX' (Got an error reading communication packets)
      2018-08-12 16:14:10 0x2fa8  InnoDB: Assertion failure in file d:\winx64-packages\build\src\storage\innobase\btr\btr0sea.cc line 1418
      InnoDB: Failing assertion: index->id == btr_page_get_index_id(page)
      InnoDB: We intentionally generate a memory trap.
      InnoDB: Submit a detailed bug report to https://jira.mariadb.org/
      InnoDB: If you get repeated assertion failures or crashes, even
      InnoDB: immediately after the mysqld startup, there may be
      InnoDB: corruption in the InnoDB tablespace. Please refer to
      InnoDB: https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/
      InnoDB: about forcing recovery.
      180812 16:14:10 [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.
       
       
      Server version: 10.3.8-MariaDB
      key_buffer_size=134217728
      read_buffer_size=131072
      max_used_connections=3
      max_threads=65537
      thread_count=8
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 136181 K  bytes of memory
      Hope that's ok; if not, decrease some variables in the equation.
       
      Thread pointer: 0x0
      Attempting backtrace. You can use the following information to find out
      where mysqld died. If you see no messages after this, something went
      terribly wrong...
      mysqld.exe!my_sigabrt_handler()[my_thr_init.c:485]
      mysqld.exe!raise()[signal.cpp:516]
      mysqld.exe!abort()[abort.cpp:71]
      mysqld.exe!ut_dbg_assertion_failed()[ut0dbg.cc:61]
      mysqld.exe!btr_search_build_page_hash_index()[btr0sea.cc:1418]
      mysqld.exe!btr_search_info_update_slow()[btr0sea.cc:1577]
      mysqld.exe!btr_cur_search_to_nth_level_func()[btr0cur.cc:2171]
      mysqld.exe!row_sel_open_pcur()[row0sel.cc:1341]
      mysqld.exe!row_sel()[row0sel.cc:1680]
      mysqld.exe!row_sel_step()[row0sel.cc:2349]
      mysqld.exe!que_thr_step()[que0que.cc:1022]
      mysqld.exe!que_run_threads_low()[que0que.cc:1111]
      mysqld.exe!que_run_threads()[que0que.cc:1150]
      mysqld.exe!que_eval_sql()[que0que.cc:1227]
      mysqld.exe!dict_stats_exec_sql()[dict0stats.cc:311]
      mysqld.exe!dict_stats_save()[dict0stats.cc:2472]
      mysqld.exe!dict_stats_update()[dict0stats.cc:3368]
      mysqld.exe!dict_stats_process_entry_from_recalc_pool()[dict0stats_bg.cc:390]
      mysqld.exe!dict_stats_thread()[dict0stats_bg.cc:467]
      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.
      2018-08-12 16:14:12 82 [Warning] Aborted connection 82 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:18:21 0 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2018-08-12 16:18:21 0 [Note] InnoDB: Uses event mutexes
      2018-08-12 16:18:21 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2018-08-12 16:18:21 0 [Note] InnoDB: Number of pools: 1
      2018-08-12 16:18:21 0 [Note] InnoDB: Using SSE2 crc32 instructions
      2018-08-12 16:18:21 0 [Note] InnoDB: Initializing buffer pool, total size = 16G, instances = 8, chunk size = 128M
      2018-08-12 16:18:21 0 [Note] InnoDB: Completed initialization of buffer pool
      2018-08-12 16:18:22 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=411121794943
      2018-08-12 16:18:22 0 [Note] InnoDB: Starting final batch to recover 1526 pages from redo log.
      2018-08-12 16:18:23 0 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2018-08-12 16:18:23 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2018-08-12 16:18:23 0 [Note] InnoDB: Creating shared tablespace for temporary tables
      2018-08-12 16:18:23 0 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2018-08-12 16:18:23 0 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2018-08-12 16:18:23 0 [Note] InnoDB: 10.3.8 started; log sequence number 411121794985; transaction id 12789543
      2018-08-12 16:18:23 0 [Note] InnoDB: Loading buffer pool(s) from E:\MySQLData\ib_buffer_pool
      2018-08-12 16:18:23 0 [Note] Plugin 'FEEDBACK' is disabled.
      2018-08-12 16:18:23 0 [Note] Server socket created on IP: '::'.
      2018-08-12 16:18:23 0 [Note] Reading of all Master_info entries succeded
      2018-08-12 16:18:23 0 [Note] Added new Master_info '' to hash table
      2018-08-12 16:18:23 0 [Note] C:\Program Files\MariaDB 10.3\bin\mysqld.exe: ready for connections.
      Version: '10.3.8-MariaDB'  socket: ''  port: 3306  mariadb.org binary distribution
      2018-08-12 16:18:29 0 [Note] InnoDB: Buffer pool(s) load completed at 180812 16:18:29
      2018-08-12 16:24:17 9 [Warning] Aborted connection 9 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:24:28 10 [Warning] Aborted connection 10 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:24:41 11 [Warning] Aborted connection 11 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:24:50 12 [Warning] Aborted connection 12 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:00 13 [Warning] Aborted connection 13 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:09 14 [Warning] Aborted connection 14 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:19 15 [Warning] Aborted connection 15 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:29 16 [Warning] Aborted connection 16 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:38 17 [Warning] Aborted connection 17 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:48 18 [Warning] Aborted connection 18 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:25:58 19 [Warning] Aborted connection 19 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:08 20 [Warning] Aborted connection 20 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:17 21 [Warning] Aborted connection 21 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:27 22 [Warning] Aborted connection 22 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:40 23 [Warning] Aborted connection 23 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:50 24 [Warning] Aborted connection 24 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:26:58 25 [Warning] Aborted connection 25 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:27:07 26 [Warning] Aborted connection 26 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:27:16 27 [Warning] Aborted connection 27 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:27:26 28 [Warning] Aborted connection 28 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:27:49 29 [Warning] Aborted connection 29 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:27:58 30 [Warning] Aborted connection 30 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:07 31 [Warning] Aborted connection 31 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:17 32 [Warning] Aborted connection 32 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:27 33 [Warning] Aborted connection 33 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:39 34 [Warning] Aborted connection 34 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:49 35 [Warning] Aborted connection 35 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:28:58 36 [Warning] Aborted connection 36 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:07 37 [Warning] Aborted connection 37 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:16 38 [Warning] Aborted connection 38 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:25 39 [Warning] Aborted connection 39 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:35 40 [Warning] Aborted connection 40 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:46 41 [Warning] Aborted connection 41 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:29:55 42 [Warning] Aborted connection 42 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:30:05 43 [Warning] Aborted connection 43 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:30:14 44 [Warning] Aborted connection 44 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:30:23 45 [Warning] Aborted connection 45 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:30:33 46 [Warning] Aborted connection 46 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:30:46 47 [Warning] Aborted connection 47 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:17 48 [Warning] Aborted connection 48 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:17 49 [Warning] Aborted connection 49 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:27 50 [Warning] Aborted connection 50 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:36 51 [Warning] Aborted connection 51 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:46 52 [Warning] Aborted connection 52 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:31:54 0x1170  InnoDB: Assertion failure in file d:\winx64-packages\build\src\storage\innobase\btr\btr0sea.cc line 1418
      InnoDB: Failing assertion: index->id == btr_page_get_index_id(page)
      InnoDB: We intentionally generate a memory trap.
      InnoDB: Submit a detailed bug report to https://jira.mariadb.org/
      InnoDB: If you get repeated assertion failures or crashes, even
      InnoDB: immediately after the mysqld startup, there may be
      InnoDB: corruption in the InnoDB tablespace. Please refer to
      InnoDB: https://mariadb.com/kb/en/library/xtradbinnodb-recovery-modes/
      InnoDB: about forcing recovery.
      180812 16:31:54 [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.3.8-MariaDB
      key_buffer_size=134217728
      read_buffer_size=131072
      max_used_connections=4
      max_threads=65537
      thread_count=8
      It is possible that mysqld could use up to 
      key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 136181 K  bytes of memory
      Hope that's ok; if not, decrease some variables in the equation.
       
      Thread pointer: 0x0
      Attempting backtrace. You can use the following information to find out
      where mysqld died. If you see no messages after this, something went
      terribly wrong...
      mysqld.exe!my_sigabrt_handler()[my_thr_init.c:485]
      mysqld.exe!raise()[signal.cpp:516]
      mysqld.exe!abort()[abort.cpp:71]
      mysqld.exe!ut_dbg_assertion_failed()[ut0dbg.cc:61]
      mysqld.exe!btr_search_build_page_hash_index()[btr0sea.cc:1418]
      mysqld.exe!btr_search_info_update_slow()[btr0sea.cc:1577]
      mysqld.exe!btr_cur_search_to_nth_level_func()[btr0cur.cc:2171]
      mysqld.exe!row_sel_open_pcur()[row0sel.cc:1341]
      mysqld.exe!row_sel()[row0sel.cc:1680]
      mysqld.exe!row_sel_step()[row0sel.cc:2349]
      mysqld.exe!que_thr_step()[que0que.cc:1022]
      mysqld.exe!que_run_threads_low()[que0que.cc:1111]
      mysqld.exe!que_run_threads()[que0que.cc:1150]
      mysqld.exe!que_eval_sql()[que0que.cc:1227]
      mysqld.exe!dict_stats_exec_sql()[dict0stats.cc:311]
      mysqld.exe!dict_stats_save()[dict0stats.cc:2472]
      mysqld.exe!dict_stats_update()[dict0stats.cc:3368]
      mysqld.exe!dict_stats_process_entry_from_recalc_pool()[dict0stats_bg.cc:390]
      mysqld.exe!dict_stats_thread()[dict0stats_bg.cc:467]
      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.
      2018-08-12 16:31:56 53 [Warning] Aborted connection 53 to db: 'data' user: 'XXX' host: 'XXX' (Got an error reading communication packets)
      2018-08-12 16:32:59 0 [Note] InnoDB: Mutexes and rw_locks use Windows interlocked functions
      2018-08-12 16:32:59 0 [Note] InnoDB: Uses event mutexes
      2018-08-12 16:32:59 0 [Note] InnoDB: Compressed tables use zlib 1.2.3
      2018-08-12 16:32:59 0 [Note] InnoDB: Number of pools: 1
      2018-08-12 16:32:59 0 [Note] InnoDB: Using SSE2 crc32 instructions
      2018-08-12 16:32:59 0 [Note] InnoDB: Initializing buffer pool, total size = 16G, instances = 8, chunk size = 128M
      2018-08-12 16:33:00 0 [Note] InnoDB: Completed initialization of buffer pool
      2018-08-12 16:33:00 0 [Note] InnoDB: Starting crash recovery from checkpoint LSN=412941896009
      2018-08-12 16:33:01 0 [Note] InnoDB: Starting final batch to recover 1345 pages from redo log.
      2018-08-12 16:33:02 0 [Note] InnoDB: 128 out of 128 rollback segments are active.
      2018-08-12 16:33:02 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2018-08-12 16:33:02 0 [Note] InnoDB: Creating shared tablespace for temporary tables
      2018-08-12 16:33:02 0 [Note] InnoDB: Setting file '.\ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
      2018-08-12 16:33:02 0 [Note] InnoDB: File '.\ibtmp1' size is now 12 MB.
      2018-08-12 16:33:02 0 [Note] InnoDB: 10.3.8 started; log sequence number 412941896051; transaction id 12789789
      2018-08-12 16:33:02 0 [Note] InnoDB: Loading buffer pool(s) from E:\MySQLData\ib_buffer_pool
      2018-08-12 16:33:02 0 [Note] Plugin 'FEEDBACK' is disabled.
      2018-08-12 16:33:02 0 [Note] Server socket created on IP: '::'.
      2018-08-12 16:33:02 0 [Note] Reading of all Master_info entries succeded
      2018-08-12 16:33:02 0 [Note] Added new Master_info '' to hash table
      2018-08-12 16:33:02 0 [Note] C:\Program Files\MariaDB 10.3\bin\mysqld.exe: ready for connections.
      Version: '10.3.8-MariaDB'  socket: ''  port: 3306  mariadb.org binary distribution
      2018-08-12 16:33:08 0 [Note] InnoDB: Buffer pool(s) load completed at 180812 16:33:08
      2018-08-12 16:35:19 0 [Note] C:\Program Files\MariaDB 10.3\bin\mysqld.exe (initiated by: unknown): Normal shutdown
      2018-08-12 16:35:19 0 [Note] Event Scheduler: Purging the queue. 0 events
      2018-08-12 16:35:19 0 [Note] InnoDB: FTS optimize thread exiting.
      2018-08-12 16:35:19 0 [Note] InnoDB: Starting shutdown...
      2018-08-12 16:35:19 0 [Note] InnoDB: Dumping buffer pool(s) to E:\MySQLData\ib_buffer_pool
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 0, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 1, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 2, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 3, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 4, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 5, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 6, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Instance 7, restricted to 32768 pages due to innodb_buf_pool_dump_pct=25
      2018-08-12 16:35:19 0 [Note] InnoDB: Buffer pool(s) dump completed at 180812 16:35:19
      2018-08-12 16:35:21 0 [Note] InnoDB: Shutdown completed; log sequence number 412944217162; transaction id 12789821
      2018-08-12 16:35:21 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
      2018-08-12 16:35:21 0 [Note] C:\Program Files\MariaDB 10.3\bin\mysqld.exe: Shutdown complete
      

      Attachments

        Issue Links

          Activity

            People

              Unassigned Unassigned
              nirvana11 Nirv
              Votes:
              0 Vote for this issue
              Watchers:
              3 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.