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

Excessive InnoDB logging on release server

    XMLWordPrintable

    Details

    • Sprint:
      10.2.5-1

      Description

      We are getting error logs from users which are flooded with this:

      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Start merge-sorting index `IDX_MODIDATE` (2 / 3), estimated cost : 10.0000
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : End of  merge-sorting index `IDX_MODIDATE` (2 / 3)
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Start building index `IDX_MODIDATE` (2 / 3), estimated cost : 15.0000
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : End of building index `IDX_MODIDATE` (2 / 3)
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Completed
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Start merge-sorting index `IDX_CREATEDATE` (3 / 3), estimated cost : 10.0000
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : End of  merge-sorting index `IDX_CREATEDATE` (3 / 3)
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Start building index `IDX_CREATEDATE` (3 / 3), estimated cost : 15.0000
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : End of building index `IDX_CREATEDATE` (3 / 3)
      2016-08-02 17:16:56 139591697147648 [Note] InnoDB: Online DDL : Completed
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Start
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Start reading clustered index of the table and create temporary files
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : End of reading clustered index of the table and create temporary files
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Start merge-sorting index `idx_CREATEDATE1`--temporary-- (1 / 1), estimated cost : 20.0000
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : End of  merge-sorting index `idx_CREATEDATE1`--temporary-- (1 / 1)
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Start building index `idx_CREATEDATE1`--temporary-- (1 / 1), estimated cost : 30.0000
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : End of building index `idx_CREATEDATE1`--temporary-- (1 / 1)
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Start applying row log
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : End of applying row log
      2016-08-03 11:18:37 139668872341248 [Note] InnoDB: Online DDL : Completed
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Start
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Start reading clustered index of the table and create temporary files
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : End of reading clustered index of the table and create temporary files
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Start merge-sorting index `idx_MODIDATE1`--temporary-- (1 / 1), estimated cost : 20.0000
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : End of  merge-sorting index `idx_MODIDATE1`--temporary-- (1 / 1)
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Start building index `idx_MODIDATE1`--temporary-- (1 / 1), estimated cost : 30.0000
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : End of building index `idx_MODIDATE1`--temporary-- (1 / 1)
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Start applying row log
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : End of applying row log
      2016-08-03 11:18:48 139668872341248 [Note] InnoDB: Online DDL : Completed
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Start
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Start reading clustered index of the table and create temporary files
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : End of reading clustered index of the table and create temporary files
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Start merge-sorting index `idx_comp1`--temporary-- (1 / 1), estimated cost : 20.0000
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : End of  merge-sorting index `idx_comp1`--temporary-- (1 / 1)
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Start building index `idx_comp1`--temporary-- (1 / 1), estimated cost : 30.0000
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : End of building index `idx_comp1`--temporary-- (1 / 1)
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Start applying row log
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : End of applying row log
      2016-08-03 11:19:34 139668872341248 [Note] InnoDB: Online DDL : Completed
      

      All of this is completely meaningless on the user side – even if they were interested in this kind of detail, the logging says nothing about the table which the operation was performed on, query, connection, account, etc. It is purely debug info, and should be logged as such.

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              marko Marko Mäkelä
              Reporter:
              elenst Elena Stepanova
              Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved: