# cat /tmp/mysqld.trace My_Realpath: info: executing realpath my_realpath: info: executing realpath ?func: info: ./libexec/mysqld Ver 5.0.85-debug-log for unknown-linux-gnu on tile set_max_open_files: info: rlim_cur: 1024 rlim_max: 1024 get_charsets_dir: info: charsets dir: '/usr/share/mysql/charsets/' ?func: info: lower_case_table_names: 0 my_stat: error: Got errno: 2 from stat my_stat: error: Got errno: 2 from stat init_io_cache: info: init_io_cache: cachesize = 8192 find_log_pos: info: Found log file entry init_server_components: info: Format_description_log_event::server_version_split: '5.0.85-debug-log' 5 0 85 init_io_cache: info: init_io_cache: cachesize = 8192 Log_event::read_log_event(IO_CACHE *, Format_description_log_event *: info: my_b_tell: 4 Log_event::read_log_event(char*,...): info: binlog_version: 4 Log_event::read_log_event(char*,...): info: log_pos: 98 Format_description_log_event::Format_description_log_event(char*,...): info: common_header_len=19 number_of_event_types=18 Format_description_log_event::Format_description_log_event(char*,...): info: Format_description_log_event::server_version_split: '5.0.85-debug-log' 5 0 85 MYSQL_LOG::init: info: log_type: 4 max_size: 1073741824 init_io_cache: info: init_io_cache: cachesize = 8192 MYSQL_LOG::open: info: Format_description_log_event::server_version_split: '5.0.85-debug-log' 5 0 85 init_key_cache: info: key_cache_block_size: 1024 network_init: general: IP Socket is 3306 network_init: general: UNIX Socket is /tmp/mysql.sock network_init: info: server started ~THD(): info: freeing security context openfrm: info: i_count: 1 i_parts: 3 index: 1 n_length: 252 int_length: 6 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb3ddf550 into the cache openfrm: info: i_count: 2 i_parts: 8 index: 1 n_length: 494 int_length: 28 com_length: 0 my_realpath: info: executing realpath openfrm: info: records: 37 seeks: 75 max links: 6 hitrate: 2.03 open_table: info: inserting table 0xb1a83ca8 into the cache openfrm: info: i_count: 1 i_parts: 3 index: 2 n_length: 257 int_length: 6 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a88368 into the cache mi_get_status: info: key_file: 2048 data_file: 140 concurrent_insert: 0 mi_get_status: info: key_file: 4096 data_file: 876 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 init_read_record: info: using rr_sequential init_io_cache: info: init_io_cache: cachesize = 16384 init_read_record: info: using rr_sequential init_io_cache: info: init_io_cache: cachesize = 16384 acl_load: info: user table fields: 37, password length: 41 _mi_writeinfo: info: operation: 0 tot_locks: 1 init_read_record: info: using rr_sequential init_io_cache: info: init_io_cache: cachesize = 16384 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 close_thread_tables: info: thd->open_tables: 0xb1a88368 ~THD(): info: freeing security context openfrm: info: i_count: 0 i_parts: 0 index: 1 n_length: 29 int_length: 0 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb3dd73a0 into the cache openfrm: info: i_count: 0 i_parts: 0 index: 1 n_length: 20 int_length: 0 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a8ef78 into the cache openfrm: info: i_count: 1 i_parts: 3 index: 1 n_length: 32 int_length: 6 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a99330 into the cache openfrm: info: i_count: 0 i_parts: 0 index: 1 n_length: 61 int_length: 0 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a9acd8 into the cache openfrm: info: i_count: 0 i_parts: 0 index: 1 n_length: 50 int_length: 0 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a9cb50 into the cache mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_rnext: error: Got error: -1, errno: 137 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 close_thread_tables: info: thd->open_tables: 0xb1a9cb50 ~THD(): info: freeing security context openfrm: info: i_count: 2 i_parts: 18 index: 2 n_length: 67 int_length: 127 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb3dd73a0 into the cache openfrm: info: i_count: 1 i_parts: 5 index: 1 n_length: 60 int_length: 34 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a8f8e8 into the cache openfrm: info: i_count: 2 i_parts: 7 index: 2 n_length: 69 int_length: 51 com_length: 0 my_realpath: info: executing realpath open_table: info: inserting table 0xb1a9f3c8 into the cache mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_get_status: info: key_file: 1024 data_file: 0 concurrent_insert: 0 mi_rnext: error: Got error: -1, errno: 137 mi_rnext: error: Got error: -1, errno: 137 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 mi_lock_database: info: changed: 0 w_locks: 0 close_thread_tables: info: thd->open_tables: 0xb1a9f3c8 ~THD(): info: freeing security context my_realpath: info: executing realpath my_realpath: error: realpath failed with errno: 2 handle_connections_sockets: general: Waiting for connections. create_new_thread: info: creating thread 1 create_new_thread: info: Thread created ?func: info: handle_one_connection called by thread 1 ?func: info: New connection received on socket (28) ?func: info: Host: localhost ?func: info: vio_read returned -1 errno: 11 ?func: info: client_character_set: 8 check_user: info: Capabilities: 239109 packet_length: 16777216 Host: 'localhost' Login user: 'root' Priv_user: 'root' Using password: no Access: 67108863 db: '*none*' send_ok: info: affected_rows: 0 id: 0 status: 2 warning_count: 0 send_ok: info: OK sent, so no more error sending allowed do_command: info: vio_read returned -1 errno: 11 do_command: info: Command on socket (28) = 3 (Query) dispatch_command: query: select @@version_comment limit 1 st_select_lex::add_item_to_list: info: Item: 0xb1853ec8 JOIN::optimize: info: No tables send_eof: info: EOF sent, so no more error sending allowed handle_select: info: res: 0 report_error: 0 dispatch_command: info: query ready do_command: info: vio_read returned -1 errno: 11 do_command: info: Command on socket (28) = 3 (Query) dispatch_command: query: SELECT DATABASE() st_select_lex::add_item_to_list: info: Item: 0xb1853ea8 JOIN::optimize: info: No tables send_eof: info: EOF sent, so no more error sending allowed handle_select: info: res: 0 report_error: 0 dispatch_command: info: query ready do_command: info: Command on socket (28) = 2 (Init DB) mysql_change_db: info: Use database: huangbenpeng init_io_cache: info: init_io_cache: cachesize = 8192 send_ok: info: affected_rows: 0 id: 0 status: 2 warning_count: 0 send_ok: info: OK sent, so no more error sending allowed do_command: info: vio_read returned -1 errno: 11 do_command: info: Command on socket (28) = 3 (Query) dispatch_command: query: select * from t1 WHERE hdl_nr < 2000000 GROUP BY kundentyp st_select_lex::add_item_to_list: info: Item: 0xb1853ee0 openfrm: info: i_count: 0 i_parts: 0 index: 14 n_length: 311 int_length: 0 com_length: 0 my_realpath: info: executing realpath openfrm: info: records: 33 seeks: 68 max links: 5 hitrate: 2.06 open_table: info: inserting table 0xb1aaf8a0 into the cache mi_get_status: info: key_file: 16384 data_file: 1592 concurrent_insert: 0 Field_iterator_table_ref::set_field_iterator: info: field_it for 't1' is Field_iterator_table WHERE:(original) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) WHERE:(after equal_items) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) WHERE:(after const change) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) WHERE:(after remove) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) Item_field::collect_item_field_processor: info: kundentyp SQL_SELECT::test_quick_select: info: records: 7 SQL_SELECT::test_quick_select: info: Time to scan table: 5.88867 print_sel_tree: info: SEL_TREE 0xb1a8ca58 (tree scans) scans:idx_hdl_nr _mi_search_pos: info: keynr: 6 offset: 1 max_keynr: 7 nod: 0 flag: 1 mi_records_in_range: info: records: 6 get_key_scans_params: info: key idx_hdl_nr: found_read_time: 8.21 (cur. read_time: 5.88867) print_sel_tree: info: SEL_TREE 0xb1a8ca58 (ROR scans) scans:(empty) get_key_scans_params: info: No 'range' table read plan found get_best_combination: info: type: 0 WHERE:(after substitute_best_equal) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) WHERE:(t1) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) WHERE:(t1) (`huangbenpeng`.`t1`.`hdl_nr` < 2000000) Info about JOIN t1 type: ALL q_keys: 4 refs: 0 key: -1 len: 0 select used JOIN::optimize: info: Creating tmp table create_tmp_table: info: Creating group key in temporary table mi_create: info: info_length: 564 my_realpath: info: executing realpath my_realpath: error: realpath failed with errno: 2 mi_create: info: write state info and base info mi_create: info: write key and keyseg definitions mi_create: info: write unique definitions mi_create: info: write field definitions mi_create: info: enlarge to keystart: 1024 my_chsize: info: old_size: 564 my_realpath: info: executing realpath JOIN::exec: info: Copying to tmp table init_io_cache: info: init_io_cache: cachesize = 262144 do_select: info: Using end_write init_read_record: info: using rr_sequential init_io_cache: info: init_io_cache: cachesize = 16384 do_select: info: select cond 0xb1854250 mi_write: info: reclength: 1029 reclength2: 918 _mi_writeinfo: info: operation: 1 tot_locks: 1 do_select: info: select cond 0xb1854250 _mi_get_binary_pack_key: info: key: b1bcc1d9 from: b1bcc6ac length: 2 _mi_get_binary_pack_key: info: key: b1bcd3a5 from: b1bcc6ac length: 2 mi_write: error: Got error: 121 on write _mi_writeinfo: info: operation: 1 tot_locks: 1 do_select: info: select cond 0xb1854250 _mi_get_binary_pack_key: info: key: b1bcc1d9 from: b1bcc6ac length: 2 _mi_get_binary_pack_key: info: key: b1bcd3a5 from: b1bcc6ac length: 2 mi_write: error: Got error: 121 on write _mi_writeinfo: info: operation: 1 tot_locks: 1 do_select: info: select cond 0xb1854250 _mi_get_binary_pack_key: info: key: b1bcc1d9 from: b1bcc6ac length: 2 _mi_get_binary_pack_key: info: key: b1bcd3a5 from: b1bcc6ac length: 2 mi_write: error: Got error: 121 on write _mi_writeinfo: info: operation: 1 tot_locks: 1 do_select: info: select cond 0xb1854250 do_select: info: select cond 0xb1854250 _mi_get_binary_pack_key: info: key: b1bcc1d9 from: b1bcc6ac length: 2 _mi_get_binary_pack_key: info: key: b1bcd3a5 from: b1bcc6ac length: 2 mi_write: error: Got error: 121 on write _mi_writeinfo: info: operation: 1 tot_locks: 1 do_select: info: select cond 0xb1854250 _mi_get_binary_pack_key: info: key: b1bcc1d9 from: b1bcc6ac length: 2 _mi_get_binary_pack_key: info: key: b1bcd3a5 from: b1bcc6ac length: 2 mi_write: error: Got error: 121 on write _mi_writeinfo: info: operation: 1 tot_locks: 1 _mi_writeinfo: info: operation: 0 tot_locks: 1 do_select: info: 1 records output