/* Copyright (c) 2000, 2024, Oracle and/or its affiliates. This program is free software; you can redistribute it and/or modify it under the terms of the GNU General Public License, version 2.0, as published by the Free Software Foundation. This program is designed to work with certain software (including but not limited to OpenSSL) that is licensed under separate terms, as designated in a particular file or component or in included license documentation. The authors of MySQL hereby grant you an additional permission to link the program and your derivative works with the separately licensed software that they have either included with the program or referenced in the documentation. This program is distributed in the hope that it will be useful, but WITHOUT ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License, version 2.0, for more details. You should have received a copy of the GNU General Public License along with this program; if not, write to the Free Software Foundation, Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA */ /* Write some debug info */ #include "my_config.h" #include #include #include #include #include #include "keycache.h" #include "lex_string.h" #include "m_string.h" #include "my_compiler.h" #include "my_dbug.h" #include "my_inttypes.h" #include "my_io.h" #include "my_list.h" #include "my_macros.h" #include "my_sys.h" #include "my_thread_local.h" #include "mysql/psi/mysql_mutex.h" #include "mysql/strings/m_ctype.h" #include "prealloced_array.h" #include "sql/events.h" #include "sql/field.h" #include "sql/item.h" #include "sql/join_optimizer/access_path.h" #include "sql/key.h" #include "sql/keycaches.h" #include "sql/mysqld.h" // LOCK_status #include "sql/mysqld_thd_manager.h" // Global_THD_manager #include "sql/opt_explain.h" // join_type_str #include "sql/opt_trace.h" #include "sql/opt_trace_context.h" #include "sql/psi_memory_key.h" #include "sql/range_optimizer/path_helpers.h" #include "sql/range_optimizer/range_optimizer.h" #include "sql/sql_bitmap.h" #include "sql/sql_class.h" #include "sql/sql_const.h" #include "sql/sql_opt_exec_shared.h" #include "sql/sql_optimizer.h" // JOIN #include "sql/sql_select.h" #include "sql/sql_show.h" // calc_sum_of_all_status #include "sql/sql_test.h" #include "sql/system_variables.h" #include "sql/table.h" #include "sql/table_cache.h" // table_cache_manager #include "sql_string.h" #if defined(HAVE_MALLOC_INFO) && defined(HAVE_MALLOC_H) #include #endif const char *lock_descriptions[TL_WRITE_ONLY + 1] = { /* TL_UNLOCK */ "No lock", /* TL_READ_DEFAULT */ nullptr, /* TL_READ */ "Low priority read lock", /* TL_READ_WITH_SHARED_LOCKS */ "Shared read lock", /* TL_READ_HIGH_PRIORITY */ "High priority read lock", /* TL_READ_NO_INSERT */ "Read lock without concurrent inserts", /* TL_WRITE_ALLOW_WRITE */ "Write lock that allows other writers", /* TL_WRITE_CONCURRENT_DEFAULT*/ nullptr, /* TL_WRITE_CONCURRENT_INSERT */ "Concurrent insert lock", /* TL_WRITE_DEFAULT */ nullptr, /* TL_WRITE_LOW_PRIORITY */ "Low priority write lock", /* TL_WRITE */ "High priority write lock", /* TL_WRITE_ONLY */ "Highest priority write lock"}; #ifndef NDEBUG void print_where(const THD *thd, const Item *cond, const char *info, enum_query_type query_type) { char buff[256]; String str(buff, sizeof(buff), system_charset_info); str.length(0); if (cond) cond->print(thd, &str, query_type); str.append('\0'); DBUG_LOCK_FILE; (void)fprintf(DBUG_FILE, "\nWHERE:(%s) %p ", info, cond); (void)fputs(str.ptr(), DBUG_FILE); (void)fputc('\n', DBUG_FILE); DBUG_UNLOCK_FILE; } void TEST_join(JOIN *join) { uint i, ref; DBUG_TRACE; assert(!join->join_tab); /* Assemble results of all the calls to full_name() first, in order not to garble the tabular output below. */ String ref_key_parts[MAX_TABLES]; for (i = 0; i < join->tables; i++) { JOIN_TAB *tab = join->best_ref[i]; for (ref = 0; ref < tab->ref().key_parts; ref++) { ref_key_parts[i].append(tab->ref().items[ref]->full_name()); ref_key_parts[i].append(" "); } } DBUG_LOCK_FILE; (void)fputs("\nInfo about JOIN\n", DBUG_FILE); for (i = 0; i < join->tables; i++) { JOIN_TAB *tab = join->best_ref[i]; TABLE *form = tab->table(); if (!form) continue; char key_map_buff[128]; fprintf(DBUG_FILE, "%-16.16s type: %-7s q_keys: %s refs: %d key: %d len: %d\n", form->alias, join_type_str[tab->type()], tab->keys().print(key_map_buff), tab->ref().key_parts, tab->ref().key, tab->ref().key_length); if (tab->range_scan()) { char buf[MAX_KEY / 8 + 1]; if (tab->use_quick == QS_DYNAMIC_RANGE) fprintf(DBUG_FILE, " quick select checked for each record (keys: " "%s)\n", form->quick_keys.print(buf)); else { fprintf(DBUG_FILE, " quick select used:\n"); dbug_dump(tab->range_scan(), 18, false); } } if (tab->ref().key_parts) { fprintf(DBUG_FILE, " refs: %s\n", ref_key_parts[i].ptr()); } } DBUG_UNLOCK_FILE; } #endif /* !NDEBUG */ void print_keyuse_array(THD *thd, Opt_trace_context *trace, const Key_use_array *keyuse_array) { if (unlikely(!trace->is_started())) return; const Opt_trace_object wrapper(trace); const Opt_trace_array trace_key_uses(trace, "ref_optimizer_key_uses"); DBUG_PRINT("opt", ("Key_use array (%zu elements)", keyuse_array->size())); for (uint i = 0; i < keyuse_array->size(); i++) { const Key_use &keyuse = keyuse_array->at(i); // those are too obscure for opt trace DBUG_PRINT("opt", ("Key_use: optimize= %d used_tables=0x%" PRIx64 " " "ref_table_rows= %lu keypart_map= %0lx", keyuse.optimize, keyuse.used_tables, (ulong)keyuse.ref_table_rows, keyuse.keypart_map)); Opt_trace_object(trace) .add_utf8_table(keyuse.table_ref) .add_utf8("field", (keyuse.keypart == FT_KEYPART) ? "" : get_field_name_or_expression( thd, keyuse.table_ref->table->key_info[keyuse.key] .key_part[keyuse.keypart] .field)) .add("equals", keyuse.val) .add("null_rejecting", keyuse.null_rejecting); } } #ifndef NDEBUG /* purecov: begin inspected */ /** Print the current state during query optimization. @param join pointer to the structure providing all context info for the query @param idx length of the partial QEP in 'join->positions' also an index in the array 'join->best_ref' @param record_count estimate for the number of records returned by the best partial plan @param read_time the cost of the best partial plan. If a complete plan is printed (join->best_read is set), this argument is ignored. @param current_read_time the accumulated cost of the current partial plan @param info comment string to appear above the printout @details This function prints to the log file DBUG_FILE the members of 'join' that are used during query optimization (join->positions, join->best_positions, and join->best_ref) and few other related variables (read_time, record_count). Useful to trace query optimizer functions. */ void print_plan(JOIN *join, uint idx, double record_count, double read_time, double current_read_time, const char *info) { uint i; POSITION pos; JOIN_TAB *join_table; JOIN_TAB **plan_nodes; TABLE *table; if (info == nullptr) info = ""; DBUG_LOCK_FILE; if (join->best_read == DBL_MAX) { fprintf(DBUG_FILE, "%s; idx: %u best: DBL_MAX atime: %g itime: %g count: %g\n", info, idx, current_read_time, read_time, record_count); } else { fprintf( DBUG_FILE, "%s; idx :%u best: %g accumulated: %g increment: %g count: %g\n", info, idx, join->best_read, current_read_time, read_time, record_count); } /* Print the tables in JOIN->positions */ fputs(" POSITIONS: ", DBUG_FILE); for (i = 0; i < idx; i++) { pos = join->positions[i]; table = pos.table->table(); if (table) fputs(table->alias, DBUG_FILE); fputc(' ', DBUG_FILE); } fputc('\n', DBUG_FILE); /* Print the tables in JOIN->best_positions only if at least one complete plan has been found. An indicator for this is the value of 'join->best_read'. */ if (join->best_read < DBL_MAX) { fputs("BEST_POSITIONS: ", DBUG_FILE); for (i = 0; i < idx; i++) { pos = join->best_positions[i]; table = pos.table->table(); if (table) fputs(table->alias, DBUG_FILE); fputc(' ', DBUG_FILE); } } fputc('\n', DBUG_FILE); /* Print the tables in JOIN->best_ref */ fputs(" BEST_REF: ", DBUG_FILE); for (plan_nodes = join->best_ref; *plan_nodes; plan_nodes++) { join_table = (*plan_nodes); fputs(join_table->table()->s->table_name.str, DBUG_FILE); fprintf(DBUG_FILE, "(%lu,%lu,%lu)", (ulong)join_table->found_records, (ulong)join_table->records(), (ulong)join_table->read_time); fputc(' ', DBUG_FILE); } fputc('\n', DBUG_FILE); DBUG_UNLOCK_FILE; } #endif /* !NDEBUG */ struct TABLE_LOCK_INFO { my_thread_id thread_id; char table_name[FN_REFLEN]; bool waiting; const char *lock_text; enum thr_lock_type type; }; typedef Prealloced_array Saved_locks_array; static inline int dl_compare(const TABLE_LOCK_INFO *a, const TABLE_LOCK_INFO *b) { if (a->thread_id > b->thread_id) return 1; if (a->thread_id < b->thread_id) return -1; if (a->waiting == b->waiting) return 0; if (a->waiting) return -1; return 1; } class DL_commpare { public: bool operator()(const TABLE_LOCK_INFO &a, const TABLE_LOCK_INFO &b) { return dl_compare(&a, &b) < 0; } }; #ifndef NDEBUG #ifdef EXTRA_DEBUG_DUMP_TABLE_LISTS /* A fixed-size FIFO pointer queue that also doesn't allow one to put an element that has previously been put into it. There is a hard-coded limit of the total number of queue put operations. The implementation is trivial and is intended for use in debug dumps only. */ template class Unique_fifo_queue { public: /* Add an element to the queue */ void push_back(T *tbl) { if (!tbl) return; // check if we've already scheduled and/or dumped the element for (int i = 0; i < last; i++) { if (elems[i] == tbl) return; } elems[last++] = tbl; } bool pop_first(T **elem) { if (first < last) { *elem = elems[first++]; return true; } return false; } void reset() { first = last = 0; } enum { MAX_ELEMS = 1000 }; T *elems[MAX_ELEMS]; int first; // First undumped table int last; // Last undumped element }; class Dbug_table_list_dumper { FILE *out; Unique_fifo_queue tables_fifo; Unique_fifo_queue> tbl_lists; public: void dump_one_struct(Table_ref *tbl); int dump_graph(Query_block *query_block, Table_ref *first_leaf); }; void dump_TABLE_LIST_graph(Query_block *query_block, Table_ref *tl) { Dbug_table_list_dumper dumper; dumper.dump_graph(query_block, tl); } /* - Dump one Table_ref objects and its outgoing edges - Schedule that other objects seen along the edges are dumped too. */ void Dbug_table_list_dumper::dump_one_struct(Table_ref *tbl) { fprintf(out, "\"%p\" [\n", tbl); fprintf(out, " label = \"%p|", tbl); fprintf(out, "alias=%s|", tbl->alias ? tbl->alias : "NULL"); fprintf(out, "next_leaf=%p|", tbl->next_leaf); fprintf(out, "next_local=%p|", tbl->next_local); fprintf(out, "next_global=%p|", tbl->next_global); fprintf(out, "embedding=%p", tbl->embedding); if (tbl->nested_join) fprintf(out, "|nested_j=%p", tbl->nested_join); if (tbl->join_list) fprintf(out, "|join_list=%p", tbl->join_list); if (tbl->on_expr) fprintf(out, "|on_expr=%p", tbl->on_expr); fprintf(out, "\"\n"); fprintf(out, " shape = \"record\"\n];\n\n"); if (tbl->next_leaf) { fprintf(out, "\n\"%p\":next_leaf -> \"%p\"[ color = \"#000000\" ];\n", tbl, tbl->next_leaf); tables_fifo.push_back(tbl->next_leaf); } if (tbl->next_local) { fprintf(out, "\n\"%p\":next_local -> \"%p\"[ color = \"#404040\" ];\n", tbl, tbl->next_local); tables_fifo.push_back(tbl->next_local); } if (tbl->next_global) { fprintf(out, "\n\"%p\":next_global -> \"%p\"[ color = \"#808080\" ];\n", tbl, tbl->next_global); tables_fifo.push_back(tbl->next_global); } if (tbl->embedding) { fprintf(out, "\n\"%p\":embedding -> \"%p\"[ color = \"#FF0000\" ];\n", tbl, tbl->embedding); tables_fifo.push_back(tbl->embedding); } if (tbl->join_list) { fprintf(out, "\n\"%p\":join_list -> \"%p\"[ color = \"#0000FF\" ];\n", tbl, tbl->join_list); tbl_lists.push_back(tbl->join_list); } } int Dbug_table_list_dumper::dump_graph(Query_block *query_block, Table_ref *first_leaf) { DBUG_TRACE; char filename[500]; int no = 0; do { sprintf(filename, "tlist_tree%.3d.g", no); if ((out = fopen(filename, "rt"))) { /* File exists, try next name */ fclose(out); } no++; } while (out); /* Ok, found an unoccupied name, create the file */ if (!(out = fopen(filename, "wt"))) { DBUG_PRINT("tree_dump", ("Failed to create output file")); return 1; } DBUG_PRINT("tree_dump", ("dumping tree to %s", filename)); fputs("digraph g {\n", out); fputs("graph [", out); fputs(" rankdir = \"LR\"", out); fputs("];", out); Table_ref *tbl; tables_fifo.reset(); dump_one_struct(first_leaf); while (tables_fifo.pop_first(&tbl)) { dump_one_struct(tbl); } mem_root_deque *plist; tbl_lists.push_back(&query_block->m_table_nest); while (tbl_lists.pop_first(&plist)) { fprintf(out, "\"%p\" [\n", plist); fprintf(out, " bgcolor = \"\""); fprintf(out, " label = \"L %p\"", plist); fprintf(out, " shape = \"record\"\n];\n\n"); } fprintf(out, " { rank = same; "); for (Table_ref *tl = first_leaf; tl; tl = tl->next_leaf) fprintf(out, " \"%p\"; ", tl); fprintf(out, "};\n"); fputs("}", out); fclose(out); char filename2[500]; filename[strlen(filename) - 1] = 0; filename[strlen(filename) - 1] = 0; sprintf(filename2, "%s.query", filename); if ((out = fopen(filename2, "wt"))) { // fprintf(out, "%s", current_thd->query); fclose(out); } return 0; } #endif #endif