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

InnoDB writes corrupted log on macOS and AIX due to uninitialized log_sys.lsn_lock

    XMLWordPrintable

Details

    Description

      I have encountered a recurring issue with MariaDB 11.5.1 where the InnoDB storage engine fails to initialize due to a "Missing FILE_CHECKPOINT" error. This issue arises consistently after importing data into a freshly created database and then attempting to restart the MariaDB server.

      *Steps to Reproduce:*

      1. Install MariaDB 11.5.1.
      2. Initialize a new, empty database.
      3. Import data into the database.
      4. Restart the MariaDB server.

      *Expected Behavior:*

      MariaDB should restart successfully without any errors.

      *Actual Behavior:*

      The server fails to restart, and the following error messages appear in the log files:

      240619 11:35:26 mysqld_safe Starting mariadbd daemon with databases from /Applications/ServBay/db/mariadb/11.5
      		 
      2024-06-19 11:35:26 0 [Note] Starting MariaDB 11.5.1-MariaDB-log source revision 65079ece42f383bdeb9b3f913a833a6f0123919d as process 32024
      		 
      2024-06-19 11:35:26 0 [Warning] Setting lower_case_table_names=2 because file system for /Applications/ServBay/db/mariadb/11.5/ is case insensitive
      		 
      2024-06-19 11:35:26 0 [Warning] --innodb-file-per-table is deprecated and will be removed in a future release
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Compressed tables use zlib 1.3.1
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Number of transaction pools: 1
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Using generic crc32 instructions
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Initializing buffer pool, total size = 4.000GiB, chunk size = 64.000MiB
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Completed initialization of buffer pool
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: End of log at LSN=47733
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Opened 3 undo tablespaces
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: 128 rollback segments in 3 undo tablespaces are active.
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Setting file '/Applications/ServBay/db/mariadb/11.5/ibtmp1' size to 12.000MiB. Physically writing the file full; Please wait ...
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: File '/Applications/ServBay/db/mariadb/11.5/ibtmp1' size is now 12.000MiB.
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: log sequence number 47733; transaction id 14
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Loading buffer pool(s) from /Applications/ServBay/db/mariadb/11.5/ib_buffer_pool
      		 
      2024-06-19 11:35:26 0 [Note] Plugin 'FEEDBACK' is disabled.
      		 
      2024-06-19 11:35:26 0 [Note] Plugin 'wsrep-provider' is disabled.
      		 
      2024-06-19 11:35:26 0 [Note] InnoDB: Buffer pool(s) load completed at 240619 11:35:26
      		 
      2024-06-19 11:35:27 0 [Note] Server socket created on IP: '::1'.
      		 
      2024-06-19 11:35:27 0 [Note] Server socket created on IP: '127.0.0.1'.
      		 
      2024-06-19 11:35:27 0 [Note] Server socket created on IP: '::'.
      		 
      2024-06-19 11:35:27 0 [Note] Server socket created on IP: '0.0.0.0'.
      		 
      2024-06-19 11:35:27 0 [Note] mariadbd: Event Scheduler: Loaded 0 events
      		 
      2024-06-19 11:35:27 0 [Note] /Applications/ServBay/package/mariadb/11.5/current/bin/mariadbd: ready for connections.
      		 
      Version: '11.5.1-MariaDB-log'  socket: '/Applications/ServBay/tmp/mariadb-11.5.sock'  port: 3306  ServBay.dev
      		 
      2024-06-19 11:37:01 0 [Note] /Applications/ServBay/package/mariadb/11.5/current/bin/mariadbd (initiated by: unknown): Normal shutdown
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: FTS optimize thread exiting.
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: Starting shutdown...
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: Dumping buffer pool(s) to /Applications/ServBay/db/mariadb/11.5/ib_buffer_pool
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: Buffer pool(s) dump completed at 240619 11:37:01
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: Removed temporary tablespace data file: "/Applications/ServBay/db/mariadb/11.5/ibtmp1"
      		 
      2024-06-19 11:37:01 0 [Note] InnoDB: Shutdown completed; log sequence number 10225990; transaction id 623
      		 
      2024-06-19 11:37:01 0 [Note] /Applications/ServBay/package/mariadb/11.5/current/bin/mariadbd: Shutdown complete
      		 
       
      		 
      240619 11:37:01 mysqld_safe mysqld from pid file /Applications/ServBay/tmp/mariadb-11.5.pid ended
      		 
      240619 11:37:02 mysqld_safe Starting mariadbd daemon with databases from /Applications/ServBay/db/mariadb/11.5
      		 
      2024-06-19 11:37:02 0 [Warning] Setting lower_case_table_names=2 because file system for /Applications/ServBay/db/mariadb/11.5/ is case insensitive
      		 
      2024-06-19 11:37:02 0 [Note] Starting MariaDB 11.5.1-MariaDB-log source revision 65079ece42f383bdeb9b3f913a833a6f0123919d as process 32351
      		 
      2024-06-19 11:37:02 0 [Warning] --innodb-file-per-table is deprecated and will be removed in a future release
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Compressed tables use zlib 1.3.1
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Number of transaction pools: 1
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Using generic crc32 instructions
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Initializing buffer pool, total size = 4.000GiB, chunk size = 64.000MiB
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Completed initialization of buffer pool
      		 
      2024-06-19 11:37:02 0 [ERROR] InnoDB: Missing FILE_CHECKPOINT(10225974) at 10225974
      		 
      2024-06-19 11:37:02 0 [ERROR] InnoDB: Log scan aborted at LSN 10225974
      		 
      2024-06-19 11:37:02 0 [ERROR] InnoDB: Plugin initialization aborted with error Generic error
      		 
      2024-06-19 11:37:02 0 [Note] InnoDB: Starting shutdown...
      		 
      2024-06-19 11:37:03 0 [ERROR] Plugin 'InnoDB' registration as a STORAGE ENGINE failed.
      		 
      2024-06-19 11:37:03 0 [Note] Plugin 'FEEDBACK' is disabled.
      		 
      2024-06-19 11:37:03 0 [Note] Plugin 'wsrep-provider' is disabled.
      		 
      2024-06-19 11:37:03 0 [ERROR] Unknown/unsupported storage engine: InnoDB
      		 
      2024-06-19 11:37:03 0 [ERROR] Aborting
      		 
      240619 11:37:03 mysqld_safe mysqld from pid file /Applications/ServBay/tmp/mariadb-11.5.pid ended

      This issue has been observed consistently across multiple users and environments.

      *Environment Details:*

      • MariaDB version: 11.5.1
      • Operating System: macOS Sonoma

      *Additional Information:*

      The issue does not occur when the database is empty. It only arises after data has been imported and the server is restarted. Sometimes it will also cause ib_logfile0 to be lost.

      11.5.0 works fine.

      *Reproducibility:*

      This issue is reproducible every time following the steps outlined above.

      Attachments

        Issue Links

          is blocked by

          Task - A task that needs to be done. MDBF-714 Provide MacOS builders to dev

          • Major - Major loss of function.
          • Open
          is caused by

          Bug - A problem which impairs or prevents the functions of the product. MDEV-33515 log_sys.lsn_lock causes excessive context switching

          • Critical - Crashes, loss of data, severe memory leak.
          • Closed
          is duplicated by

          Bug - A problem which impairs or prevents the functions of the product. MDEV-34521 Running mysql_install_db on 10.11 results in crash by assert failure at log0log.cc

          • Blocker - Blocks development and/or testing work, production could not run.
          • Closed
          relates to

          Bug - A problem which impairs or prevents the functions of the product. MDEV-34678 pthread_mutex_init() without pthread_mutex_destroy() if SUX_LOCK_GENERIC

          • Major - Major loss of function.
          • Closed

          Task - A task that needs to be done. MDEV-14425 Change the InnoDB redo log format to reduce write amplification

          • Blocker - Blocks development and/or testing work, production could not run.
          • Closed

          Bug - A problem which impairs or prevents the functions of the product. MDEV-34350 11.4.2 breaks beyond repair the ib_logfile0 after restart

          • Major - Major loss of function.
          • Closed

          Activity

            People

              marko Marko Mäkelä
              naizhao Sam
              Votes:
              5 Vote for this issue
              Watchers:
              18 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.