LP #1453277: query_response_time_range_base is not read on startup

Description

**Reported in Launchpad by John Cesario last update 04-06-2015 13:40:05

This is a duplicate/extension of https://bugs.launchpad.net/percona-server/+bug/1056855

This now effects 5.5 and 5.6

5.6
[vagrant@build ~]$ sudo /sbin/service mysql restart
Shutting down MySQL (Percona Server)... [ OK ]
Starting MySQL (Percona Server). [ OK ]
[vagrant@build ~]$ sudo mysql
Welcome to the MySQL monitor. Commands end with ; or \g.
Your MySQL connection id is 1
Server version: 5.6.24-72.2 Percona Server (GPL), Release 72.2, Revision 8d0f85b

Copyright (c) 2009-2015 Percona LLC and/or its affiliates
Copyright (c) 2000, 2015, Oracle and/or its affiliates. All rights reserved.

Oracle is a registered trademark of Oracle Corporation and/or its
affiliates. Other names may be trademarks of their respective
owners.

Type 'help;' or '\h' for help. Type '\c' to clear the current input statement.

mysql> SELECT VERSION();
-------------

VERSION()

-------------

5.6.24-72.2

-------------
1 row in set (0.00 sec)

mysql> SELECT * FROM information_schema.QUERY_RESPONSE_TIME;
---------------------------------------

TIME

COUNT

TOTAL

---------------------------------------

0.000001

0

0.000000

0.000010

0

0.000000

0.000100

1

0.000081

0.001000

2

0.000327

0.010000

0

0.000000

0.100000

0

0.000000

1.000000

0

0.000000

10.000000

0

0.000000

100.000000

0

0.000000

1000.000000

0

0.000000

10000.000000

0

0.000000

100000.000000

0

0.000000

1000000.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
14 rows in set (0.00 sec)

mysql> SHOW GLOBAL VARIABLES LIKE '%query_response_%';
---------------------------------------+

Variable_name

Value

---------------------------------------+

query_response_time_flush

OFF

query_response_time_range_base

2

query_response_time_stats

ON

---------------------------------------+
3 rows in set (0.00 sec)

mysql> SHOW GLOBAL STATUS LIKE '%query_response_%';
Empty set (0.00 sec)

mysql> SET GLOBAL query_response_time_flush=1;
Query OK, 0 rows affected (0.00 sec)

mysql> SELECT * FROM information_schema.QUERY_RESPONSE_TIME;
---------------------------------------

TIME

COUNT

TOTAL

---------------------------------------

0.000001

0

0.000000

0.000003

0

0.000000

0.000007

0

0.000000

0.000015

0

0.000000

0.000030

0

0.000000

0.000061

0

0.000000

0.000122

1

0.000089

0.000244

0

0.000000

0.000488

0

0.000000

0.000976

0

0.000000

0.001953

0

0.000000

0.003906

0

0.000000

0.007812

0

0.000000

0.015625

0

0.000000

0.031250

0

0.000000

0.062500

0

0.000000

0.125000

0

0.000000

0.250000

0

0.000000

0.500000

0

0.000000

1.000000

0

0.000000

2.000000

0

0.000000

4.000000

0

0.000000

8.000000

0

0.000000

16.000000

0

0.000000

32.000000

0

0.000000

64.000000

0

0.000000

128.000000

0

0.000000

256.000000

0

0.000000

512.000000

0

0.000000

1024.000000

0

0.000000

2048.000000

0

0.000000

4096.000000

0

0.000000

8192.000000

0

0.000000

16384.000000

0

0.000000

32768.000000

0

0.000000

65536.000000

0

0.000000

131072.000000

0

0.000000

262144.000000

0

0.000000

524288.000000

0

0.000000

1048576.000000

0

0.000000

2097152.000000

0

0.000000

4194304.000000

0

0.000000

8388608.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
44 rows in set (0.00 sec)

5.5
mysql> SELECT * FROM information_schema.QUERY_RESPONSE_TIME;
---------------------------------------

time

count

total

---------------------------------------

0.000001

0

0.000000

0.000010

0

0.000000

0.000100

0

0.000000

0.001000

0

0.000000

0.010000

0

0.000000

0.100000

0

0.000000

1.000000

0

0.000000

10.000000

0

0.000000

100.000000

0

0.000000

1000.000000

0

0.000000

10000.000000

0

0.000000

100000.000000

0

0.000000

1000000.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
14 rows in set (0.00 sec)

mysql> SHOW GLOBAL VARIABLES LIKE '%query%';
-------------------------------------------------------------------+

Variable_name

Value

-------------------------------------------------------------------+

ft_query_expansion_limit

20

have_query_cache

YES

long_query_time

10.000000

query_alloc_block_size

8192

query_cache_limit

1048576

query_cache_min_res_unit

4096

query_cache_size

0

query_cache_strip_comments

OFF

query_cache_type

ON

query_cache_wlock_invalidate

OFF

query_prealloc_size

8192

query_response_time_range_base

2

query_response_time_stats

OFF

slow_query_log

OFF

slow_query_log_always_write_time

10.000000

slow_query_log_file

/var/lib/mysql/build-slow.log

slow_query_log_timestamp_always

OFF

slow_query_log_timestamp_precision

second

slow_query_log_use_global_control

 

-------------------------------------------------------------------+
19 rows in set (0.00 sec)

mysql> FLUSH QUERY_RESPONSE_TIME;
Query OK, 0 rows affected (0.00 sec)

mysql> SELECT * FROM information_schema.QUERY_RESPONSE_TIME;
---------------------------------------

time

count

total

---------------------------------------

0.000001

0

0.000000

0.000003

0

0.000000

0.000007

0

0.000000

0.000015

0

0.000000

0.000030

0

0.000000

0.000061

0

0.000000

0.000122

0

0.000000

0.000244

0

0.000000

0.000488

0

0.000000

0.000976

0

0.000000

0.001953

0

0.000000

0.003906

0

0.000000

0.007812

0

0.000000

0.015625

0

0.000000

0.031250

0

0.000000

0.062500

0

0.000000

0.125000

0

0.000000

0.250000

0

0.000000

0.500000

0

0.000000

1.000000

0

0.000000

2.000000

0

0.000000

4.000000

0

0.000000

8.000000

0

0.000000

16.000000

0

0.000000

32.000000

0

0.000000

64.000000

0

0.000000

128.000000

0

0.000000

256.000000

0

0.000000

512.000000

0

0.000000

1024.000000

0

0.000000

2048.000000

0

0.000000

4096.000000

0

0.000000

8192.000000

0

0.000000

16384.000000

0

0.000000

32768.000000

0

0.000000

65536.000000

0

0.000000

131072.000000

0

0.000000

262144.000000

0

0.000000

524288.000000

0

0.000000

1048576.000000

0

0.000000

2097152.000000

0

0.000000

4194304.000000

0

0.000000

8388608.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
44 rows in set (0.00 sec)

Environment

None

Smart Checklist

Activity

lpjirasync January 22, 2018 at 11:49 AM

**Comment from Launchpad by: Laurynas Biveinis on: 04-06-2015 13:39:47

The patch at
https://github.com/percona/percona-server/pull/72
was merged in
https://github.com/percona/percona-server/pull/85 and https://github.com/percona/percona-server/pull/86.

(John, sorry for missing your triage question.)

lpjirasync January 22, 2018 at 11:49 AM

**Comment from Launchpad by: John Cesario on: 27-05-2015 15:59:41

What was the triage ?

lpjirasync January 22, 2018 at 11:49 AM

**Comment from Launchpad by: Nilnandan Joshi on: 27-05-2015 08:34:50

Confirmed with PS 5.5.40

mysql> SHOW GLOBAL VARIABLES LIKE '%query_response_%';
---------------------------------------+

Variable_name

Value

---------------------------------------+

query_response_time_range_base

2

query_response_time_stats

ON

---------------------------------------+
2 rows in set (0.00 sec)

mysql> SELECT * from INFORMATION_SCHEMA.QUERY_RESPONSE_TIME;
---------------------------------------

time

count

total

---------------------------------------

0.000001

0

0.000000

0.000010

0

0.000000

0.000100

0

0.000000

0.001000

1

0.000225

0.010000

1

0.005754

0.100000

0

0.000000

1.000000

0

0.000000

10.000000

0

0.000000

100.000000

0

0.000000

1000.000000

0

0.000000

10000.000000

0

0.000000

100000.000000

0

0.000000

1000000.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
14 rows in set (0.00 sec)

mysql> FLUSH QUERY_RESPONSE_TIME;
Query OK, 0 rows affected (0.00 sec)

mysql> SELECT * from INFORMATION_SCHEMA.QUERY_RESPONSE_TIME;
---------------------------------------

time

count

total

---------------------------------------

0.000001

0

0.000000

0.000003

0

0.000000

0.000007

0

0.000000

0.000015

0

0.000000

0.000030

0

0.000000

0.000061

1

0.000031

0.000122

0

0.000000

0.000244

0

0.000000

0.000488

0

0.000000

0.000976

0

0.000000

0.001953

0

0.000000

0.003906

0

0.000000

0.007812

0

0.000000

0.015625

0

0.000000

0.031250

0

0.000000

0.062500

0

0.000000

0.125000

0

0.000000

0.250000

0

0.000000

0.500000

0

0.000000

1.000000

0

0.000000

2.000000

0

0.000000

4.000000

0

0.000000

8.000000

0

0.000000

16.000000

0

0.000000

32.000000

0

0.000000

64.000000

0

0.000000

128.000000

0

0.000000

256.000000

0

0.000000

512.000000

0

0.000000

1024.000000

0

0.000000

2048.000000

0

0.000000

4096.000000

0

0.000000

8192.000000

0

0.000000

16384.000000

0

0.000000

32768.000000

0

0.000000

65536.000000

0

0.000000

131072.000000

0

0.000000

262144.000000

0

0.000000

524288.000000

0

0.000000

1048576.000000

0

0.000000

2097152.000000

0

0.000000

4194304.000000

0

0.000000

8388608.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
44 rows in set (0.00 sec)

mysql>

lpjirasync January 22, 2018 at 11:49 AM

**Comment from Launchpad by: Nilnandan Joshi on: 26-05-2015 07:14:07

Confirmed with PS 5.6.22

mysql> SELECT VERSION();
-----------------

VERSION()

-----------------

5.6.24-72.2-log

-----------------
1 row in set (0.00 sec)

mysql> SELECT * from INFORMATION_SCHEMA.QUERY_RESPONSE_TIME;
---------------------------------------

TIME

COUNT

TOTAL

---------------------------------------

0.000001

0

0.000000

0.000010

0

0.000000

0.000100

2

0.000126

0.001000

0

0.000000

0.010000

0

0.000000

0.100000

0

0.000000

1.000000

0

0.000000

10.000000

0

0.000000

100.000000

0

0.000000

1000.000000

0

0.000000

10000.000000

0

0.000000

100000.000000

0

0.000000

1000000.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
14 rows in set (0.00 sec)

mysql> SHOW GLOBAL VARIABLES LIKE '%query_response_%';
---------------------------------------+

Variable_name

Value

---------------------------------------+

query_response_time_flush

OFF

query_response_time_range_base

2

query_response_time_stats

ON

---------------------------------------+
3 rows in set (0.00 sec)

mysql> SET GLOBAL query_response_time_flush=1;
Query OK, 0 rows affected (0.00 sec)

mysql> SELECT * FROM information_schema.QUERY_RESPONSE_TIME;
---------------------------------------

TIME

COUNT

TOTAL

---------------------------------------

0.000001

0

0.000000

0.000003

0

0.000000

0.000007

0

0.000000

0.000015

0

0.000000

0.000030

0

0.000000

0.000061

1

0.000049

0.000122

0

0.000000

0.000244

0

0.000000

0.000488

0

0.000000

0.000976

0

0.000000

0.001953

0

0.000000

0.003906

0

0.000000

0.007812

0

0.000000

0.015625

0

0.000000

0.031250

0

0.000000

0.062500

0

0.000000

0.125000

0

0.000000

0.250000

0

0.000000

0.500000

0

0.000000

1.000000

0

0.000000

2.000000

0

0.000000

4.000000

0

0.000000

8.000000

0

0.000000

16.000000

0

0.000000

32.000000

0

0.000000

64.000000

0

0.000000

128.000000

0

0.000000

256.000000

0

0.000000

512.000000

0

0.000000

1024.000000

0

0.000000

2048.000000

0

0.000000

4096.000000

0

0.000000

8192.000000

0

0.000000

16384.000000

0

0.000000

32768.000000

0

0.000000

65536.000000

0

0.000000

131072.000000

0

0.000000

262144.000000

0

0.000000

524288.000000

0

0.000000

1048576.000000

0

0.000000

2097152.000000

0

0.000000

4194304.000000

0

0.000000

8388608.000000

0

0.000000

TOO LONG

0

TOO LONG

---------------------------------------
44 rows in set (0.00 sec)

mysql>

lpjirasync January 22, 2018 at 11:49 AM

**Comment from Launchpad by: John Cesario on: 14-05-2015 13:35:16

The bug is triaged here: https://github.com/percona/percona-server/pull/72

Done

Details

Assignee

Reporter

Priority

Smart Checklist

Created January 22, 2018 at 11:48 AM
Updated January 22, 2018 at 11:49 AM
Resolved January 22, 2018 at 11:48 AM