Uploaded image for project: 'Percona Monitoring and Management'
  1. Percona Monitoring and Management
  2. PMM-7137

log_slow_extra=ON + log_slow_verbosity=full breaks Query Analytics slow log parsing

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Done
    • Priority: Medium
    • Resolution: Won't Fix
    • Affects Version/s: 2.12.0
    • Fix Version/s: None
    • Component/s: PMM Agent
    • Environment:

      Percona Server for MySQL 8.0.15 - 8.0.22

    • Needs Review:
      Yes
    • Needs QA:
      Yes
    • Platform Team:
      Server Features

      Description

      Enabling log_slow_verbosity + log_slow_extra in Percona Server for MySQL 8 will break Query Analytics parsing of the slow log.

      STR

      1. Deploy Percona Server for MySQL 8.0.14 or newer (when log_slow_extra was introduced)
      2. Enable with SET GLOBAL log_slow_verbosity=full; SET GLOBAL log_slow_extra=ON;

      log_slow_verbosity=full

      # Time: 2020-12-09T19:18:31.719393Z
      # User@Host: sbtest[sbtest] @ localhost []  Id:  2166
      # Schema: sbtest  Last_errno: 0  Killed: 0
      # Query_time: 0.003275  Lock_time: 0.000096  Rows_sent: 0  Rows_examined: 1  Rows_affected: 1
      # Bytes_sent: 52  Tmp_tables: 0  Tmp_disk_tables: 0  Tmp_table_sizes: 0
      # InnoDB_trx_id: B2FDFF
      # Full_scan: No  Full_join: No  Tmp_table: No  Tmp_table_on_disk: No
      # Filesort: No  Filesort_on_disk: No  Merge_passes: 0
      #   InnoDB_IO_r_ops: 0  InnoDB_IO_r_bytes: 0  InnoDB_IO_r_wait: 0.000000
      #   InnoDB_rec_lock_wait: 0.000000  InnoDB_queue_wait: 0.000000
      #   InnoDB_pages_distinct: 4
      SET timestamp=1607541511;
      UPDATE sbtest1 SET c='52030597984-91951729173-72624061254-27472409100-53383636912-96014387216-73789058878-06605448135-35265358927-33301992094' WHERE id=5044; 

      + log_slow_extra=ON

      # Time: 2020-12-09T19:21:35.374108Z
      # User@Host: sbtest[sbtest] @ localhost []  Id:  2166
      # Query_time: 0.002575  Lock_time: 0.000114 Rows_sent: 0  Rows_examined: 1 Thread_id: 2166 Errno: 0 Killed: 0 Bytes_received: 0 Bytes_sent: 52 Read_first: 0 Read_last: 0 Read_key: 1 Read_next: 0 Read_prev: 0 Read_rnd: 0 Read_rnd_next: 0 Sort_merge_passes: 0 Sort_range_count: 0 Sort_rows: 0 Sort_scan_count: 0 Created_tmp_disk_tables: 0 Created_tmp_tables: 0 Start: 2020-12-09T19:21:35.371533Z End: 2020-12-09T19:21:35.374108Z Schema: sbtest Rows_affected: 1
        Tmp_tables: 0  Tmp_disk_tables: 0  Tmp_table_sizes: 0
      # InnoDB_trx_id: B43D17
      # Full_scan: No  Full_join: No  Tmp_table: No  Tmp_table_on_disk: No
      # Filesort: No  Filesort_on_disk: No  Merge_passes: 0
      #   InnoDB_IO_r_ops: 0  InnoDB_IO_r_bytes: 0  InnoDB_IO_r_wait: 0.000000
      #   InnoDB_rec_lock_wait: 0.000000  InnoDB_queue_wait: 0.000000
      #   InnoDB_pages_distinct: 3
      SET timestamp=1607541695;
      UPDATE sbtest1 SET c='16345165387-09118488204-80196276203-72484701811-98907421242-34753057913-75232806999-46215706819-16000796136-12299977587' WHERE id=5036; 

       

       

        Attachments

          Issue Links

            Activity

              People

              Assignee:
              maksym.hilliaka Maksym Hilliaka
              Reporter:
              michael.coburn@percona.com Michael Coburn
              Votes:
              0 Vote for this issue
              Watchers:
              8 Start watching this issue

                Dates

                Created:
                Updated:
                Resolved:

                  Smart Checklist