Able to reproduce the same with MySQL/PS 5.6.21 and 1st test case. root@Dell-XPS:/usr/share/mysql-test# ./mysql-test-run.pl bug1388632 Logging: ./mysql-test-run.pl bug1388632 2014-11-25 15:25:59 24478 [Note] Plugin 'FEDERATED' is disabled. 2014-11-25 15:25:59 24478 [Note] Binlog end 2014-11-25 15:25:59 24478 [Note] Shutting down plugin 'MyISAM' 2014-11-25 15:25:59 24478 [Note] Shutting down plugin 'CSV' MySQL Version 5.6.21 Checking supported features... - SSL connections supported Collecting tests... Checking leftover processes... Removing old var directory... Creating var directory '/usr/share/mysql-test/var'... Installing system database... ============================================================================== TEST RESULT TIME (ms) or COMMENT -------------------------------------------------------------------------- worker[1] Using MTR_BUILD_THREAD 300, with reserved ports 13000..13009 worker[1] mysql-test-run: WARNING: running this script as _root_ will cause some tests to be skipped set @log_output.saved = @@global.log_output; set @slow_query_log.saved = @@global.slow_query_log; set global log_output = 'FILE', global slow_query_log = 1; set long_query_time = 10; set statement LONG_QUERY_TIME = 0.01 for select sleep(0.11); sleep(0.11) 0 #-------------------------------------------------------------------- # The last set statement is expected to be in the log #------ /usr/sbin/mysqld, Version: 5.6.21-70.1-log (Percona Server (GPL), Release 70.1, Revision 698). started with: Tcp port: 13001 Unix socket: /usr/share/mysql-test/var/tmp/mysqld.1.sock Time Id Command Argument #-------------------------------------------------------------------- set long_query_time = 0.01; set statement LOG_SLOW_FILTER = 'full_scan' for select sleep(0.14); sleep(0.14) 0 #-------------------------------------------------------------------- # The last set statement is NOT expected to be in the log #------ /usr/sbin/mysqld, Version: 5.6.21-70.1-log (Percona Server (GPL), Release 70.1, Revision 698). started with: Tcp port: 13001 Unix socket: /usr/share/mysql-test/var/tmp/mysqld.1.sock Time Id Command Argument # Time: 141125 12:56:02 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.140294 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 use test; SET timestamp=1416909362; set statement LOG_SLOW_FILTER = 'full_scan' for select sleep(0.14); #-------------------------------------------------------------------- set statement LOG_SLOW_VERBOSITY = 'innodb' for select host, sleep(0.15) from mysql.user where user = 'root'; host sleep(0.15) 127.0.0.1 0 ::1 0 dell-xps 0 localhost 0 #-------------------------------------------------------------------- # The last set statement should have something about InnoDB stats #------ /usr/sbin/mysqld, Version: 5.6.21-70.1-log (Percona Server (GPL), Release 70.1, Revision 698). started with: Tcp port: 13001 Unix socket: /usr/share/mysql-test/var/tmp/mysqld.1.sock Time Id Command Argument # Time: 141125 12:56:02 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.140294 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 use test; SET timestamp=1416909362; set statement LOG_SLOW_FILTER = 'full_scan' for select sleep(0.14); # Time: 141125 12:56:03 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.601298 Lock_time: 0.000287 Rows_sent: 4 Rows_examined: 4 Rows_affected: 0 # Bytes_sent: 164 SET timestamp=1416909363; set statement LOG_SLOW_VERBOSITY = 'innodb' for select host, sleep(0.15) from mysql.user where user = 'root'; #-------------------------------------------------------------------- set statement LOG_SLOW_RATE_LIMIT = 4 for select sleep(0.16); sleep(0.16) 0 #-------------------------------------------------------------------- # The last set statement is NOT expected to be in the log #------ /usr/sbin/mysqld, Version: 5.6.21-70.1-log (Percona Server (GPL), Release 70.1, Revision 698). started with: Tcp port: 13001 Unix socket: /usr/share/mysql-test/var/tmp/mysqld.1.sock Time Id Command Argument # Time: 141125 12:56:02 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.140294 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 use test; SET timestamp=1416909362; set statement LOG_SLOW_FILTER = 'full_scan' for select sleep(0.14); # Time: 141125 12:56:03 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.601298 Lock_time: 0.000287 Rows_sent: 4 Rows_examined: 4 Rows_affected: 0 # Bytes_sent: 164 SET timestamp=1416909363; set statement LOG_SLOW_VERBOSITY = 'innodb' for select host, sleep(0.15) from mysql.user where user = 'root'; # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.160408 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 SET timestamp=1416909363; set statement LOG_SLOW_RATE_LIMIT = 4 for select sleep(0.16); #-------------------------------------------------------------------- set global log_output = @log_output.saved; set global slow_query_log = @slow_query_log.saved; main.bug1388632 [ pass ] 1019 -------------------------------------------------------------------------- The servers were restarted 0 times Spent 1.019 of 6 seconds executing testcases Completed: All 1 tests were successful. root@Dell-XPS:/usr/share/mysql-test# In slow log, I got this, /usr/sbin/mysqld, Version: 5.6.21-70.1-log (Percona Server (GPL), Release 70.1, Revision 698). started with: Tcp port: 13001 Unix socket: /usr/share/mysql-test/var/tmp/mysqld.1.sock Time Id Command Argument # Time: 141125 12:56:02 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.140294 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 use test; SET timestamp=1416909362; set statement LOG_SLOW_FILTER = 'full_scan' for select sleep(0.14); # Time: 141125 12:56:03 # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.601298 Lock_time: 0.000287 Rows_sent: 4 Rows_examined: 4 Rows_affected: 0 # Bytes_sent: 164 SET timestamp=1416909363; set statement LOG_SLOW_VERBOSITY = 'innodb' for select host, sleep(0.15) from mysql.user where user = 'root'; # User@Host: root[root] @ localhost [] Id: 3 # Schema: test Last_errno: 0 Killed: 0 # Query_time: 0.160408 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 Rows_affected: 0 # Bytes_sent: 66 SET timestamp=1416909363; set statement LOG_SLOW_RATE_LIMIT = 4 for select sleep(0.16);