Bug #83912 | Time spent sleeping before entering InnoDB is not measured/reported separately | ||
---|---|---|---|
Submitted: | 21 Nov 2016 15:28 | Modified: | 23 Nov 2016 8:07 |
Reporter: | Valeriy Kravchuk | Email Updates: | |
Status: | Verified | Impact on me: | |
Category: | MySQL Server: Performance Schema | Severity: | S3 (Non-critical) |
Version: | 5.6, 5.7, 5.6.34 | OS: | Any |
Assigned to: | CPU Architecture: | Any | |
Tags: | performance_schema, sleeping before entering InnoDB, statistics, wait |
[21 Nov 2016 15:28]
Valeriy Kravchuk
[21 Nov 2016 15:32]
Valeriy Kravchuk
In gdb thread doing SELECT has the following backtrace while waiting: Thread 14 (Thread 0x7f6523b71b00 (LWP 3603)): #0 0x00007f65212a3903 in select () from /lib64/libc.so.6 #1 0x00005579d21e4deb in os_thread_sleep (tm=tm@entry=23582) at /home/openxs/git/server/storage/xtradb/os/os0thread.cc:307 #2 0x00005579d2241db9 in srv_conc_enter_innodb_with_atomics ( trx=trx@entry=0x7f64f40e2668) at /home/openxs/git/server/storage/xtradb/srv/srv0conc.cc:298 #3 srv_conc_enter_innodb (trx=trx@entry=0x7f64f40e2668) at /home/openxs/git/server/storage/xtradb/srv/srv0conc.cc:597 #4 0x00005579d21893b5 in innobase_srv_conc_enter_innodb (trx=0x7f64f40e2668) at /home/openxs/git/server/storage/xtradb/handler/ha_innodb.cc:1936 #5 ha_innobase::index_read (this=0x7f64f406f820, buf=0x7f64f4032820 "\377\r", key_ptr=<optimized out>, key_len=<optimized out>, find_flag=<optimized out>) at /home/openxs/git/server/storage/xtradb/handler/ha_innodb.cc:10123 #6 0x00005579d20431dd in handler::index_read_idx_map (this=0x7f64f406f820, buf=0x7f64f4032820 "\377\r", index=0, key=0x7f64f413b848 "\r", keypart_map=1, find_flag=HA_READ_KEY_EXACT) at /home/openxs/git/server/sql/handler.cc:5441 #7 0x00005579d204751c in handler::ha_index_read_idx_map (this=0x7f64f406f820, buf=0x7f64f4032820 "\377\r", index=<optimized out>, key=0x7f64f413b848 "\r", keypart_map=1, find_flag=find_flag@entry=HA_READ_KEY_EXACT) at /home/openxs/git/server/sql/handler.cc:2646 #8 0x00005579d1f17c87 in join_read_const (tab=tab@entry=0x7f64f413a780) at /home/openxs/git/server/sql/sql_select.cc:18896 #9 0x00005579d1f17df4 in join_read_const_table (thd=0x7f651f213008, tab=tab@entry=0x7f64f413a780, pos=<optimized out>, pos=<optimized out>) at /home/openxs/git/server/sql/sql_select.cc:18771 #10 0x00005579d1f1ced1 in make_join_statistics ( join=join@entry=0x7f64f4139be0, tables_list=..., keyuse_array=keyuse_array@entry=0x7f64f4139f10) at /home/openxs/git/server/sql/sql_select.cc:3929 #11 0x00005579d1f24727 in JOIN::optimize_inner (this=0x7f64f4139be0) at /home/openxs/git/server/sql/sql_select.cc:1361 #12 0x00005579d1f279c8 in JOIN::optimize (this=0x7f64f4139be0) at /home/openxs/git/server/sql/sql_select.cc:1040 #13 0x00005579d1f283af in mysql_select (thd=thd@entry=0x7f651f213008, rref_pointer_array=rref_pointer_array@entry=0x7f651f217288, tables=0x7f64f4139208, wild_num=<optimized out>, fields=..., conds= 0x7f64f4139998, og_num=0, order=0x0, group=0x0, having=0x0, proc_param=0x0, select_options=2147748608, result=0x7f64f4139bc0, unit= 0x7f651f216910, select_lex=0x7f651f217010) at /home/openxs/git/server/sql/sql_select.cc:3424 #14 0x00005579d1f28570 in handle_select (thd=thd@entry=0x7f651f213008, lex=lex@entry=0x7f651f216848, result=result@entry=0x7f64f4139bc0, setup_tables_done_option=setup_tables_done_option@entry=0) at /home/openxs/git/server/sql/sql_select.cc:384 #15 0x00005579d1ecf948 in execute_sqlcom_select (thd=thd@entry=0x7f651f213008, all_tables=0x7f64f4139208) at /home/openxs/git/server/sql/sql_parse.cc:5893 #16 0x00005579d1edc28d in mysql_execute_command (thd=thd@entry=0x7f651f213008) at /home/openxs/git/server/sql/sql_parse.cc:2965 #17 0x00005579d1edef2b in mysql_parse (thd=0x7f651f213008, rawbuf=<optimized out>, length=<optimized out>, parser_state=<optimized out>) at /home/openxs/git/server/sql/sql_parse.cc:7316 #18 0x00005579d1ee1e80 in dispatch_command (command=command@entry=COM_QUERY, thd=thd@entry=0x7f651f213008, packet=packet@entry=0x7f650d3c8009 "select * from t0 where id=13", packet_length=packet_length@entry=28) at /home/openxs/git/server/sql/sql_parse.cc:1487 #19 0x00005579d1ee25d3 in do_command (thd=0x7f651f213008) at /home/openxs/git/server/sql/sql_parse.cc:1108 #20 0x00005579d1f9998c in do_handle_one_connection ( thd_arg=thd_arg@entry=0x7f651f213008) at /home/openxs/git/server/sql/sql_connect.cc:1350 #21 0x00005579d1f99b57 in handle_one_connection (arg=0x7f651f213008) at /home/openxs/git/server/sql/sql_connect.cc:1262 #22 0x00007f652381b61a in start_thread () from /lib64/libpthread.so.0 #23 0x00007f65212ad5fd in clone () from /lib64/libc.so.6
[23 Nov 2016 8:07]
MySQL Verification Team
Hello Valerii, Thank you for the report and test case. Observed this with 5.6.34. Thanks, Umesh