Uploaded image for project: 'Percona Server'
  1. Percona Server
  2. PS-4546

Can't run mysql on Ubuntu systems with long recovery time

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Done
    • Priority: Medium
    • Resolution: Fixed
    • Affects Version/s: None
    • Fix Version/s: 5.7.23-23
    • Component/s: None
    • Labels:

      Description

      Jun 18 21:03:58 rocky mysqld[29333]: 2018-06-19T01:03:58.715930Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885818472960
      Jun 18 21:03:58 rocky mysqld[29333]: 2018-06-19T01:03:58.816573Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885823715840
      Jun 18 21:03:58 rocky mysqld[29333]: 2018-06-19T01:03:58.917522Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885828958720
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.017656Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885834201600
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.119072Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885839444480
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.219786Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885844687360
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.382150Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 8885848013285
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.388139Z 0 [Note] InnoDB: Database was not shutdown normally!
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.388148Z 0 [Note] InnoDB: Starting crash recovery.
      Jun 18 21:03:59 rocky mysqld[29333]: 2018-06-19T01:03:59.768888Z 0 [Note] InnoDB: Created parallel doublewrite buffer at /mnt/data/mysql/xb_doublewrite, size 31457280 bytes
      Jun 18 21:04:07 rocky mysqld[29333]: 2018-06-19T01:04:07.878308Z 0 [Note] InnoDB: Starting an apply batch of log records to the database...
      Jun 18 21:05:01 rocky CRON[29547]: pam_unix(cron:session): session opened for user root by (uid=0)
      Jun 18 21:05:01 rocky CRON[29548]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
      Jun 18 21:05:01 rocky CRON[29547]: pam_unix(cron:session): session closed for user root
      Jun 18 21:05:58 rocky systemd[1]: mysql.service: State 'stop-final-sigterm' timed out. Killing.
      Jun 18 21:06:03 rocky mysqld[29333]: InnoDB: Progress in percent: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48
      Jun 18 21:06:03 rocky systemd[1]: Stopped Percona Server.
      -- Subject: Unit mysql.service has finished shutting down
      -- Defined-By: systemd
      -- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
      

      This issue appeared due to TimeoutSec=600
      So If recovery takes longer than 10 minutes it fails.

        Attachments

          Activity

            People

            • Assignee:
              evgeniy.patlan Evgeniy Patlan
              Reporter:
              evgeniy.patlan Evgeniy Patlan
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved:

                Time Tracking

                Estimated:
                Original Estimate - 0 minutes
                0m
                Remaining:
                Remaining Estimate - 0 minutes
                0m
                Logged:
                Time Spent - 1 day, 1 hour
                1d 1h