[MDEV-10509] Excessive InnoDB logging on release server - Jira

XML

Word

Printable

Details

Type: Bug
Status: Closed (View Workflow)
Priority: Major
Resolution: Fixed
Affects Version/s: 10.1
Fix Version/s: 10.1.23, 10.2.6
Component/s: Storage Engine - InnoDB
Labels:
- 10.2-ga

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

is duplicated by

MDEV-12322 MariaDB 10.1.19 - Error log - every 4 seconds error message "clustered index of the table and create temporary files"

Closed

relates to

MDEV-6812 Merge Kakao: Add global status variables which tell you the progress of inplace alter table and row log buffer usage

Closed

MDEV-12512 Accurately report progress for ALTER TABLE...ALGORITHM=INPLACE

Open

MDEV-11027 InnoDB log recovery is too noisy

Closed

Activity

People

Assignee:: Marko Mäkelä

Reporter:: Elena Stepanova

Votes:: 0 Vote for this issue

Watchers:: 4 Start watching this issue

Dates

Created:: 2016-08-07 15:19

Updated:: 2017-04-17 00:54

Resolved:: 2017-04-17 00:54

Git Integration

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