/* Copyright (C) 2000 MySQL AB & MySQL Finland AB & TCX DataKonsult AB This program is free software; you can redistribute it and/or modify it under the terms of the GNU General Public License as published by the Free Software Foundation; either version 2 of the License, or (at your option) any later version. 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 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., 59 Temple Place, Suite 330, Boston, MA 02111-1307 USA */ #include "mysql_priv.h" #include <mysql.h> #include <myisam.h> #include "mini_client.h" #include "slave.h" #include "sql_repl.h" #include "repl_failsafe.h" #include <thr_alarm.h> #include <my_dir.h> #include <assert.h> bool use_slave_mask = 0; MY_BITMAP slave_error_mask; volatile bool slave_sql_running = 0, slave_io_running = 0; char* slave_load_tmpdir = 0; MASTER_INFO main_mi; MASTER_INFO* active_mi; volatile int active_mi_in_use = 0; HASH replicate_do_table, replicate_ignore_table; DYNAMIC_ARRAY replicate_wild_do_table, replicate_wild_ignore_table; bool do_table_inited = 0, ignore_table_inited = 0; bool wild_do_table_inited = 0, wild_ignore_table_inited = 0; bool table_rules_on = 0; static TABLE* save_temporary_tables = 0; // when slave thread exits, we need to remember the temporary tables so we // can re-use them on slave start // TODO: move the vars below under MASTER_INFO #ifndef DBUG_OFF int disconnect_slave_event_count = 0, abort_slave_event_count = 0; static int events_till_disconnect = -1; int events_till_abort = -1; static int stuck_count = 0; #endif typedef enum { SLAVE_THD_IO, SLAVE_THD_SQL} SLAVE_THD_TYPE; void skip_load_data_infile(NET* net); static int process_io_rotate(MASTER_INFO* mi, Rotate_log_event* rev); static int queue_old_event(MASTER_INFO* mi, const char* buf, uint event_len); static inline bool slave_killed(THD* thd,MASTER_INFO* mi); static inline bool slave_killed(THD* thd,RELAY_LOG_INFO* rli); static int init_slave_thread(THD* thd, SLAVE_THD_TYPE thd_type); static int safe_connect(THD* thd, MYSQL* mysql, MASTER_INFO* mi); static int safe_reconnect(THD* thd, MYSQL* mysql, MASTER_INFO* mi); static int connect_to_master(THD* thd, MYSQL* mysql, MASTER_INFO* mi, bool reconnect); static int safe_sleep(THD* thd, MASTER_INFO* mi, int sec); static int request_table_dump(MYSQL* mysql, const char* db, const char* table); static int create_table_from_dump(THD* thd, NET* net, const char* db, const char* table_name); static int check_master_version(MYSQL* mysql, MASTER_INFO* mi); char* rewrite_db(char* db); void init_thread_mask(int* mask,MASTER_INFO* mi,bool inverse) { bool set_io = mi->slave_running, set_sql = mi->rli.slave_running; if (inverse) { /* This makes me think of the Russian idiom "I am not I, and this is not my horse", which is used to deny reponsibility for one's actions. */ set_io = !set_io; set_sql = !set_sql; } register int tmp_mask=0; if (set_io) tmp_mask |= SLAVE_IO; if (set_sql) tmp_mask |= SLAVE_SQL; *mask = tmp_mask; } void lock_slave_threads(MASTER_INFO* mi) { //TODO: see if we can do this without dual mutex pthread_mutex_lock(&mi->run_lock); pthread_mutex_lock(&mi->rli.run_lock); } void unlock_slave_threads(MASTER_INFO* mi) { //TODO: see if we can do this without dual mutex pthread_mutex_unlock(&mi->rli.run_lock); pthread_mutex_unlock(&mi->run_lock); } int init_slave() { // TODO (multi-master): replace this with list initialization active_mi = &main_mi; // TODO: the code below is a copy-paste mess - clean it up /* make sure slave thread gets started if server_id is set, valid master.info is present, and master_host has not been specified */ if (server_id && !master_host) { // TODO: re-write this to interate through the list of files // for multi-master char fname[FN_REFLEN+128]; MY_STAT stat_area; fn_format(fname, master_info_file, mysql_data_home, "", 4+16+32); if (my_stat(fname, &stat_area, MYF(0)) && !init_master_info(active_mi,master_info_file,relay_log_info_file)) master_host = active_mi->host; } // slave thread if (master_host) { if (!opt_skip_slave_start && start_slave_threads(1 /* need mutex */, 0 /* no wait for start*/, active_mi, master_info_file, relay_log_info_file, SLAVE_IO|SLAVE_SQL )) sql_print_error("Warning: Can't create threads to handle slave"); else if (opt_skip_slave_start) if (init_master_info(active_mi, master_info_file, relay_log_info_file)) sql_print_error("Warning: failed to initialized master info"); } return 0; } static void free_table_ent(TABLE_RULE_ENT* e) { my_free((gptr) e, MYF(0)); } static byte* get_table_key(TABLE_RULE_ENT* e, uint* len, my_bool not_used __attribute__((unused))) { *len = e->key_len; return (byte*)e->db; } // TODO: check proper initialization of master_log_name/master_log_pos int init_relay_log_pos(RELAY_LOG_INFO* rli,const char* log, ulonglong pos, bool need_data_lock, const char** errmsg) { if (rli->log_pos_current) return 0; pthread_mutex_t *log_lock=rli->relay_log.get_log_lock(); pthread_mutex_lock(log_lock); if (need_data_lock) pthread_mutex_lock(&rli->data_lock); if (rli->cur_log_fd >= 0) { end_io_cache(&rli->cache_buf); my_close(rli->cur_log_fd, MYF(MY_WME)); rli->cur_log_fd = -1; } if (!log) log = rli->relay_log_name; // already inited if (!pos) pos = rli->relay_log_pos; // already inited else rli->relay_log_pos = pos; if (rli->relay_log.find_first_log(&rli->linfo,log)) { *errmsg="Could not find first log during relay log initialization"; goto err; } strnmov(rli->relay_log_name,rli->linfo.log_file_name, sizeof(rli->relay_log_name)); // to make end_io_cache(&rli->cache_buf) safe in all cases if (!rli->inited) bzero((char*) &rli->cache_buf, sizeof(IO_CACHE)); if (rli->relay_log.is_active(rli->linfo.log_file_name)) { if (my_b_tell((rli->cur_log=rli->relay_log.get_log_file())) == 0 && check_binlog_magic(rli->cur_log,errmsg)) { goto err; } rli->cur_log_init_count=rli->cur_log->init_count; } else { if (rli->inited) end_io_cache(&rli->cache_buf); if (rli->cur_log_fd>=0) my_close(rli->cur_log_fd,MYF(MY_WME)); if ((rli->cur_log_fd=open_binlog(&rli->cache_buf, rli->linfo.log_file_name,errmsg)) < 0) { goto err; } rli->cur_log = &rli->cache_buf; } if (pos > 4) my_b_seek(rli->cur_log,(off_t)pos); rli->log_pos_current=1; err: pthread_cond_broadcast(&rli->data_cond); if (need_data_lock) pthread_mutex_unlock(&rli->data_lock); pthread_mutex_unlock(log_lock); return (*errmsg) ? 1 : 0; } /* called from get_options() in mysqld.cc on start-up */ void init_slave_skip_errors(char* arg) { char* p; my_bool last_was_digit = 0; if (bitmap_init(&slave_error_mask,MAX_SLAVE_ERROR,0)) { fprintf(stderr, "Badly out of memory, please check your system status\n"); exit(1); } use_slave_mask = 1; for (;isspace(*arg);++arg) /* empty */; if (!my_casecmp(arg,"all",3)) { bitmap_set_all(&slave_error_mask); return; } for (p= arg ; *p; ) { long err_code; if (!(p= str2int(p, 10, 0, LONG_MAX, &err_code))) break; if (err_code < MAX_SLAVE_ERROR) bitmap_set_bit(&slave_error_mask,(uint)err_code); while (!isdigit(*p) && *p) p++; } } // we assume we have a run lock on rli and that the both slave thread // are not running int purge_relay_logs(RELAY_LOG_INFO* rli, bool just_reset, const char** errmsg) { if (!rli->inited) return 0; /* successfully do nothing */ DBUG_ASSERT(rli->slave_running == 0); DBUG_ASSERT(rli->mi->slave_running == 0); int error=0; rli->slave_skip_counter=0; pthread_mutex_lock(&rli->data_lock); rli->pending=0; rli->master_log_name[0]=0; rli->master_log_pos=0; // 0 means uninitialized if (rli->relay_log.reset_logs(rli->sql_thd) || rli->relay_log.find_first_log(&rli->linfo,"")) { *errmsg = "Failed during log reset"; error=1; goto err; } strnmov(rli->relay_log_name,rli->linfo.log_file_name, sizeof(rli->relay_log_name)); rli->relay_log_pos=4; rli->log_pos_current=0; if (!just_reset) error = init_relay_log_pos(rli,0,0,0/*do not need data lock*/,errmsg); err: pthread_mutex_unlock(&rli->data_lock); return error; } int terminate_slave_threads(MASTER_INFO* mi,int thread_mask,bool skip_lock) { if (!mi->inited) return 0; /* successfully do nothing */ int error,force_all = (thread_mask & SLAVE_FORCE_ALL); pthread_mutex_t *sql_lock = &mi->rli.run_lock, *io_lock = &mi->run_lock; pthread_mutex_t *sql_cond_lock,*io_cond_lock; sql_cond_lock=sql_lock; io_cond_lock=io_lock; if (skip_lock) { sql_lock = io_lock = 0; } if ((thread_mask & (SLAVE_IO|SLAVE_FORCE_ALL)) && mi->slave_running) { mi->abort_slave=1; if ((error=terminate_slave_thread(mi->io_thd,io_lock, io_cond_lock, &mi->stop_cond, &mi->slave_running)) && !force_all) return error; } if ((thread_mask & (SLAVE_SQL|SLAVE_FORCE_ALL)) && mi->rli.slave_running) { DBUG_ASSERT(mi->rli.sql_thd != 0) ; mi->rli.abort_slave=1; if ((error=terminate_slave_thread(mi->rli.sql_thd,sql_lock, sql_cond_lock, &mi->rli.stop_cond, &mi->rli.slave_running)) && !force_all) return error; } return 0; } int terminate_slave_thread(THD* thd, pthread_mutex_t* term_lock, pthread_mutex_t *cond_lock, pthread_cond_t* term_cond, volatile bool* slave_running) { if (term_lock) { pthread_mutex_lock(term_lock); if (!*slave_running) { pthread_mutex_unlock(term_lock); return ER_SLAVE_NOT_RUNNING; } } DBUG_ASSERT(thd != 0); KICK_SLAVE(thd); while (*slave_running) { /* there is a small chance that slave thread might miss the first alarm. To protect againts it, resend the signal until it reacts */ struct timespec abstime; #ifdef HAVE_TIMESPEC_TS_SEC abstime.ts_sec=time(NULL)+2; abstime.ts_nsec=0; #elif defined(__WIN__) abstime.tv_sec=time((time_t*) 0)+2; abstime.tv_nsec=0; #else struct timeval tv; gettimeofday(&tv,0); abstime.tv_sec=tv.tv_sec+2; abstime.tv_nsec=tv.tv_usec*1000; #endif pthread_cond_timedwait(term_cond, cond_lock, &abstime); if (*slave_running) KICK_SLAVE(thd); } if (term_lock) pthread_mutex_unlock(term_lock); return 0; } int start_slave_thread(pthread_handler h_func, pthread_mutex_t* start_lock, pthread_mutex_t *cond_lock, pthread_cond_t* start_cond, volatile bool* slave_running, MASTER_INFO* mi) { pthread_t th; DBUG_ASSERT(mi->inited); if (start_lock) pthread_mutex_lock(start_lock); if (!server_id) { if (start_cond) pthread_cond_broadcast(start_cond); if (start_lock) pthread_mutex_unlock(start_lock); sql_print_error("Server id not set, will not start slave"); return ER_BAD_SLAVE; } if (*slave_running) { if (start_cond) pthread_cond_broadcast(start_cond); if (start_lock) pthread_mutex_unlock(start_lock); return ER_SLAVE_MUST_STOP; } if (pthread_create(&th, &connection_attrib, h_func, (void*)mi)) { if (start_lock) pthread_mutex_unlock(start_lock); return ER_SLAVE_THREAD; } if (start_cond && cond_lock) { THD* thd = current_thd; while (!*slave_running) { const char* old_msg = thd->enter_cond(start_cond,cond_lock, "Waiting for slave thread to start"); pthread_cond_wait(start_cond,cond_lock); thd->exit_cond(old_msg); // TODO: in a very rare case of init_slave_thread failing, it is // possible that we can get stuck here since slave_running will not // be set. We need to change slave_running to int and have -1 as // error code if (thd->killed) { pthread_mutex_unlock(cond_lock); return ER_SERVER_SHUTDOWN; } } } if (start_lock) pthread_mutex_unlock(start_lock); return 0; } /* SLAVE_FORCE_ALL is not implemented here on purpose since it does not make sense to do that for starting a slave - we always care if it actually started the threads that were not previously running */ int start_slave_threads(bool need_slave_mutex, bool wait_for_start, MASTER_INFO* mi, const char* master_info_fname, const char* slave_info_fname, int thread_mask) { pthread_mutex_t *lock_io=0,*lock_sql=0,*lock_cond_io=0,*lock_cond_sql=0; pthread_cond_t* cond_io=0,*cond_sql=0; int error=0; if (need_slave_mutex) { lock_io = &mi->run_lock; lock_sql = &mi->rli.run_lock; } if (wait_for_start) { cond_io = &mi->start_cond; cond_sql = &mi->rli.start_cond; lock_cond_io = &mi->run_lock; lock_cond_sql = &mi->rli.run_lock; } if (init_master_info(mi,master_info_fname,slave_info_fname)) return ER_MASTER_INFO; if ((thread_mask & SLAVE_IO) && (error=start_slave_thread(handle_slave_io,lock_io,lock_cond_io, cond_io,&mi->slave_running, mi))) return error; if ((thread_mask & SLAVE_SQL) && (error=start_slave_thread(handle_slave_sql,lock_sql,lock_cond_sql, cond_sql, &mi->rli.slave_running,mi))) return error; return 0; } void init_table_rule_hash(HASH* h, bool* h_inited) { hash_init(h, TABLE_RULE_HASH_SIZE,0,0, (hash_get_key) get_table_key, (void (*)(void*)) free_table_ent, 0); *h_inited = 1; } void init_table_rule_array(DYNAMIC_ARRAY* a, bool* a_inited) { init_dynamic_array(a, sizeof(TABLE_RULE_ENT*), TABLE_RULE_ARR_SIZE, TABLE_RULE_ARR_SIZE); *a_inited = 1; } static TABLE_RULE_ENT* find_wild(DYNAMIC_ARRAY *a, const char* key, int len) { uint i; const char* key_end = key + len; for (i = 0; i < a->elements; i++) { TABLE_RULE_ENT* e ; get_dynamic(a, (gptr)&e, i); if (!wild_case_compare(key, key_end, (const char*)e->db, (const char*)(e->db + e->key_len),'\\')) return e; } return 0; } int tables_ok(THD* thd, TABLE_LIST* tables) { for (; tables; tables = tables->next) { if (!tables->updating) continue; char hash_key[2*NAME_LEN+2]; char* p; p = strmov(hash_key, tables->db ? tables->db : thd->db); *p++ = '.'; uint len = strmov(p, tables->real_name) - hash_key ; if (do_table_inited) // if there are any do's { if (hash_search(&replicate_do_table, (byte*) hash_key, len)) return 1; } if (ignore_table_inited) // if there are any ignores { if (hash_search(&replicate_ignore_table, (byte*) hash_key, len)) return 0; } if (wild_do_table_inited && find_wild(&replicate_wild_do_table, hash_key, len)) return 1; if (wild_ignore_table_inited && find_wild(&replicate_wild_ignore_table, hash_key, len)) return 0; } // if no explicit rule found // and there was a do list, do not replicate. If there was // no do list, go ahead return !do_table_inited && !wild_do_table_inited; } int add_table_rule(HASH* h, const char* table_spec) { const char* dot = strchr(table_spec, '.'); if(!dot) return 1; // len is always > 0 because we know the there exists a '.' uint len = (uint)strlen(table_spec); TABLE_RULE_ENT* e = (TABLE_RULE_ENT*)my_malloc(sizeof(TABLE_RULE_ENT) + len, MYF(MY_WME)); if(!e) return 1; e->db = (char*)e + sizeof(TABLE_RULE_ENT); e->tbl_name = e->db + (dot - table_spec) + 1; e->key_len = len; memcpy(e->db, table_spec, len); (void)hash_insert(h, (byte*)e); return 0; } int add_wild_table_rule(DYNAMIC_ARRAY* a, const char* table_spec) { const char* dot = strchr(table_spec, '.'); if(!dot) return 1; uint len = (uint)strlen(table_spec); TABLE_RULE_ENT* e = (TABLE_RULE_ENT*)my_malloc(sizeof(TABLE_RULE_ENT) + len, MYF(MY_WME)); if(!e) return 1; e->db = (char*)e + sizeof(TABLE_RULE_ENT); e->tbl_name = e->db + (dot - table_spec) + 1; e->key_len = len; memcpy(e->db, table_spec, len); insert_dynamic(a, (gptr)&e); return 0; } static void free_string_array(DYNAMIC_ARRAY *a) { uint i; for(i = 0; i < a->elements; i++) { char* p; get_dynamic(a, (gptr) &p, i); my_free(p, MYF(MY_WME)); } delete_dynamic(a); } static int end_slave_on_walk(MASTER_INFO* mi, gptr /*unused*/) { end_master_info(mi); return 0; } void end_slave() { // TODO: replace the line below with // list_walk(&master_list, (list_walk_action)end_slave_on_walk,0); // once multi-master code is ready terminate_slave_threads(active_mi,SLAVE_FORCE_ALL); end_master_info(active_mi); if (do_table_inited) hash_free(&replicate_do_table); if (ignore_table_inited) hash_free(&replicate_ignore_table); if (wild_do_table_inited) free_string_array(&replicate_wild_do_table); if (wild_ignore_table_inited) free_string_array(&replicate_wild_ignore_table); } static inline bool slave_killed(THD* thd, MASTER_INFO* mi) { DBUG_ASSERT(mi->io_thd == thd); DBUG_ASSERT(mi->slave_running == 1); // tracking buffer overrun return mi->abort_slave || abort_loop || thd->killed; } static inline bool slave_killed(THD* thd, RELAY_LOG_INFO* rli) { DBUG_ASSERT(rli->sql_thd == thd); DBUG_ASSERT(rli->slave_running == 1);// tracking buffer overrun return rli->abort_slave || abort_loop || thd->killed; } void slave_print_error(RELAY_LOG_INFO* rli, int err_code, const char* msg, ...) { va_list args; va_start(args,msg); my_vsnprintf(rli->last_slave_error, sizeof(rli->last_slave_error), msg, args); sql_print_error("Slave: %s, error_code=%d", rli->last_slave_error, err_code); rli->last_slave_errno = err_code; } void skip_load_data_infile(NET* net) { (void)my_net_write(net, "\xfb/dev/null", 10); (void)net_flush(net); (void)my_net_read(net); // discard response send_ok(net); // the master expects it } char* rewrite_db(char* db) { if(replicate_rewrite_db.is_empty() || !db) return db; I_List_iterator<i_string_pair> it(replicate_rewrite_db); i_string_pair* tmp; while((tmp=it++)) { if(!strcmp(tmp->key, db)) return tmp->val; } return db; } int db_ok(const char* db, I_List<i_string> &do_list, I_List<i_string> &ignore_list ) { if(do_list.is_empty() && ignore_list.is_empty()) return 1; // ok to replicate if the user puts no constraints // if the user has specified restrictions on which databases to replicate // and db was not selected, do not replicate if(!db) return 0; if(!do_list.is_empty()) // if the do's are not empty { I_List_iterator<i_string> it(do_list); i_string* tmp; while((tmp=it++)) { if(!strcmp(tmp->ptr, db)) return 1; // match } return 0; } else // there are some elements in the don't, otherwise we cannot get here { I_List_iterator<i_string> it(ignore_list); i_string* tmp; while((tmp=it++)) { if(!strcmp(tmp->ptr, db)) return 0; // match } return 1; } } static int init_strvar_from_file(char* var, int max_size, IO_CACHE* f, char* default_val) { uint length; if ((length=my_b_gets(f,var, max_size))) { char* last_p = var + length -1; if (*last_p == '\n') *last_p = 0; // if we stopped on newline, kill it else { // if we truncated a line or stopped on last char, remove all chars // up to and including newline int c; while( ((c=my_b_get(f)) != '\n' && c != my_b_EOF)); } return 0; } else if (default_val) { strmake(var, default_val, max_size-1); return 0; } return 1; } static int init_intvar_from_file(int* var, IO_CACHE* f, int default_val) { char buf[32]; if (my_b_gets(f, buf, sizeof(buf))) { *var = atoi(buf); return 0; } else if(default_val) { *var = default_val; return 0; } return 1; } static int check_master_version(MYSQL* mysql, MASTER_INFO* mi) { MYSQL_RES* res; MYSQL_ROW row; const char* version; const char* errmsg = 0; if (mc_mysql_query(mysql, "SELECT VERSION()", 0) || !(res = mc_mysql_store_result(mysql))) { sql_print_error("Error checking master version: %s", mc_mysql_error(mysql)); return 1; } if (!(row = mc_mysql_fetch_row(res))) { errmsg = "Master returned no rows for SELECT VERSION()"; goto err; } if (!(version = row[0])) { errmsg = "Master reported NULL for the version"; goto err; } switch (*version) { case '3': mi->old_format = 1; break; case '4': mi->old_format = 0; break; default: errmsg = "Master reported unrecognized MySQL version"; goto err; } err: if (res) mc_mysql_free_result(res); if (errmsg) { sql_print_error(errmsg); return 1; } return 0; } static int create_table_from_dump(THD* thd, NET* net, const char* db, const char* table_name) { ulong packet_len = my_net_read(net); // read create table statement Vio* save_vio; HA_CHECK_OPT check_opt; TABLE_LIST tables; int error= 1; handler *file; uint save_options; if (packet_len == packet_error) { send_error(&thd->net, ER_MASTER_NET_READ); return 1; } if (net->read_pos[0] == 255) // error from master { net->read_pos[packet_len] = 0; net_printf(&thd->net, ER_MASTER, net->read_pos + 3); return 1; } thd->command = COM_TABLE_DUMP; thd->query = sql_alloc(packet_len + 1); if (!thd->query) { sql_print_error("create_table_from_dump: out of memory"); net_printf(&thd->net, ER_GET_ERRNO, "Out of memory"); return 1; } memcpy(thd->query, net->read_pos, packet_len); thd->query[packet_len] = 0; thd->current_tablenr = 0; thd->query_error = 0; thd->net.no_send_ok = 1; /* we do not want to log create table statement */ save_options = thd->options; thd->options &= ~OPTION_BIN_LOG; thd->proc_info = "Creating table from master dump"; // save old db in case we are creating in a different database char* save_db = thd->db; thd->db = (char*)db; mysql_parse(thd, thd->query, packet_len); // run create table thd->db = save_db; // leave things the way the were before thd->options = save_options; if (thd->query_error) goto err; // mysql_parse took care of the error send bzero((char*) &tables,sizeof(tables)); tables.db = (char*)db; tables.name = tables.real_name = (char*)table_name; tables.lock_type = TL_WRITE; thd->proc_info = "Opening master dump table"; if (!open_ltable(thd, &tables, TL_WRITE)) { send_error(&thd->net,0,0); // Send error from open_ltable sql_print_error("create_table_from_dump: could not open created table"); goto err; } file = tables.table->file; thd->proc_info = "Reading master dump table data"; if (file->net_read_dump(net)) { net_printf(&thd->net, ER_MASTER_NET_READ); sql_print_error("create_table_from_dump::failed in\ handler::net_read_dump()"); goto err; } check_opt.init(); check_opt.flags|= T_VERY_SILENT | T_CALC_CHECKSUM; check_opt.quick = 1; thd->proc_info = "Rebuilding the index on master dump table"; // we do not want repair() to spam us with messages // just send them to the error log, and report the failure in case of // problems save_vio = thd->net.vio; thd->net.vio = 0; error=file->repair(thd,&check_opt) != 0; thd->net.vio = save_vio; if (error) net_printf(&thd->net, ER_INDEX_REBUILD,tables.table->real_name); err: close_thread_tables(thd); thd->net.no_send_ok = 0; return error; } int fetch_master_table(THD* thd, const char* db_name, const char* table_name, MASTER_INFO* mi, MYSQL* mysql) { int error = 1; int fetch_errno = 0; bool called_connected = (mysql != NULL); if (!called_connected && !(mysql = mc_mysql_init(NULL))) { sql_print_error("fetch_master_table: Error in mysql_init()"); fetch_errno = ER_GET_ERRNO; goto err; } if (!called_connected) { if (connect_to_master(thd, mysql, mi)) { sql_print_error("Could not connect to master while fetching table\ '%-64s.%-64s'", db_name, table_name); fetch_errno = ER_CONNECT_TO_MASTER; goto err; } } if (thd->killed) goto err; if (request_table_dump(mysql, db_name, table_name)) { fetch_errno = ER_GET_ERRNO; sql_print_error("fetch_master_table: failed on table dump request "); goto err; } if (create_table_from_dump(thd, &mysql->net, db_name, table_name)) { // create_table_from_dump will have sent the error alread sql_print_error("fetch_master_table: failed on create table "); goto err; } error = 0; err: if (mysql && !called_connected) mc_mysql_close(mysql); if (fetch_errno && thd->net.vio) send_error(&thd->net, fetch_errno, "Error in fetch_master_table"); thd->net.no_send_ok = 0; // Clear up garbage after create_table_from_dump return error; } void end_master_info(MASTER_INFO* mi) { if (!mi->inited) return; end_relay_log_info(&mi->rli); if (mi->fd >= 0) { end_io_cache(&mi->file); (void)my_close(mi->fd, MYF(MY_WME)); mi->fd = -1; } mi->inited = 0; } int init_relay_log_info(RELAY_LOG_INFO* rli, const char* info_fname) { if (rli->inited) return 0; MY_STAT stat_area; char fname[FN_REFLEN+128]; int info_fd; const char* msg = 0; int error = 0; fn_format(fname, info_fname, mysql_data_home, "", 4+32); pthread_mutex_lock(&rli->data_lock); info_fd = rli->info_fd; rli->pending = 0; rli->cur_log_fd = -1; rli->slave_skip_counter=0; rli->log_pos_current=0; // TODO: make this work with multi-master if (!opt_relay_logname) { char tmp[FN_REFLEN]; /* TODO: The following should be using fn_format(); We just need to first change fn_format() to cut the file name if it's too long. */ strmake(tmp,glob_hostname,FN_REFLEN-5); strmov(strcend(tmp,'.'),"-relay-bin"); opt_relay_logname=my_strdup(tmp,MYF(MY_WME)); } rli->relay_log.set_index_file_name(opt_relaylog_index_name); open_log(&rli->relay_log, glob_hostname, opt_relay_logname, "-relay-bin", LOG_BIN, 1 /* read_append cache */, 1 /* no auto events*/); /* if file does not exist */ if (!my_stat(fname, &stat_area, MYF(0))) { // if someone removed the file from underneath our feet, just close // the old descriptor and re-create the old file if (info_fd >= 0) my_close(info_fd, MYF(MY_WME)); if ((info_fd = my_open(fname, O_CREAT|O_RDWR|O_BINARY, MYF(MY_WME))) < 0 || init_io_cache(&rli->info_file, info_fd, IO_SIZE*2, READ_CACHE, 0L,0, MYF(MY_WME))) { if(info_fd >= 0) my_close(info_fd, MYF(0)); rli->info_fd=-1; pthread_mutex_unlock(&rli->data_lock); return 1; } if (init_relay_log_pos(rli,"",4,0/*no data mutex*/,&msg)) goto err; rli->master_log_pos = 0; // uninitialized rli->info_fd = info_fd; } else // file exists { if(info_fd >= 0) reinit_io_cache(&rli->info_file, READ_CACHE, 0L,0,0); else if((info_fd = my_open(fname, O_RDWR|O_BINARY, MYF(MY_WME))) < 0 || init_io_cache(&rli->info_file, info_fd, IO_SIZE*2, READ_CACHE, 0L, 0, MYF(MY_WME))) { if (info_fd >= 0) my_close(info_fd, MYF(0)); rli->info_fd=-1; pthread_mutex_unlock(&rli->data_lock); return 1; } rli->info_fd = info_fd; if (init_strvar_from_file(rli->relay_log_name, sizeof(rli->relay_log_name), &rli->info_file, (char*)"") || init_intvar_from_file((int*)&rli->relay_log_pos, &rli->info_file, 4) || init_strvar_from_file(rli->master_log_name, sizeof(rli->master_log_name), &rli->info_file, (char*)"") || init_intvar_from_file((int*)&rli->master_log_pos, &rli->info_file, 0)) { msg="Error reading slave log configuration"; goto err; } if (init_relay_log_pos(rli,0 /*log already inited*/, 0 /*pos already inited*/, 0 /* no data lock*/, &msg)) goto err; } DBUG_ASSERT(rli->relay_log_pos >= 4); DBUG_ASSERT(my_b_tell(rli->cur_log) == rli->relay_log_pos); rli->inited = 1; // now change the cache from READ to WRITE - must do this // before flush_relay_log_info reinit_io_cache(&rli->info_file, WRITE_CACHE,0L,0,1); error=test(flush_relay_log_info(rli)); pthread_mutex_unlock(&rli->data_lock); return error; err: sql_print_error(msg); end_io_cache(&rli->info_file); my_close(info_fd, MYF(0)); rli->info_fd=-1; pthread_mutex_unlock(&rli->data_lock); return 1; } int init_master_info(MASTER_INFO* mi, const char* master_info_fname, const char* slave_info_fname) { if (mi->inited) return 0; if (init_relay_log_info(&mi->rli, slave_info_fname)) return 1; mi->rli.mi = mi; mi->ignore_stop_event=0; int fd,length,error; MY_STAT stat_area; char fname[FN_REFLEN+128]; const char *msg; fn_format(fname, master_info_fname, mysql_data_home, "", 4+32); // we need a mutex while we are changing master info parameters to // keep other threads from reading bogus info pthread_mutex_lock(&mi->data_lock); fd = mi->fd; // we do not want any messages if the file does not exist if (!my_stat(fname, &stat_area, MYF(0))) { // if someone removed the file from underneath our feet, just close // the old descriptor and re-create the old file if (fd >= 0) my_close(fd, MYF(MY_WME)); if ((fd = my_open(fname, O_CREAT|O_RDWR|O_BINARY, MYF(MY_WME))) < 0 || init_io_cache(&mi->file, fd, IO_SIZE*2, READ_CACHE, 0L,0, MYF(MY_WME))) { if(fd >= 0) my_close(fd, MYF(0)); mi->fd=-1; end_relay_log_info(&mi->rli); pthread_mutex_unlock(&mi->data_lock); return 1; } mi->master_log_name[0] = 0; mi->master_log_pos = 4; // skip magic number mi->fd = fd; if (master_host) strmake(mi->host, master_host, sizeof(mi->host) - 1); if (master_user) strmake(mi->user, master_user, sizeof(mi->user) - 1); if (master_password) strmake(mi->password, master_password, HASH_PASSWORD_LENGTH); mi->port = master_port; mi->connect_retry = master_connect_retry; } else // file exists { if(fd >= 0) reinit_io_cache(&mi->file, READ_CACHE, 0L,0,0); else if((fd = my_open(fname, O_RDWR|O_BINARY, MYF(MY_WME))) < 0 || init_io_cache(&mi->file, fd, IO_SIZE*2, READ_CACHE, 0L, 0, MYF(MY_WME))) { if(fd >= 0) my_close(fd, MYF(0)); mi->fd=-1; end_relay_log_info(&mi->rli); pthread_mutex_unlock(&mi->data_lock); return 1; } mi->fd = fd; if (init_strvar_from_file(mi->master_log_name, sizeof(mi->master_log_name), &mi->file, (char*)"") || init_intvar_from_file((int*)&mi->master_log_pos, &mi->file, 4) || init_strvar_from_file(mi->host, sizeof(mi->host), &mi->file, master_host) || init_strvar_from_file(mi->user, sizeof(mi->user), &mi->file, master_user) || init_strvar_from_file(mi->password, HASH_PASSWORD_LENGTH+1, &mi->file, master_password) || init_intvar_from_file((int*)&mi->port, &mi->file, master_port) || init_intvar_from_file((int*)&mi->connect_retry, &mi->file, master_connect_retry) ) { msg="Error reading master configuration"; goto err; } } mi->inited = 1; // now change the cache from READ to WRITE - must do this // before flush_master_info reinit_io_cache(&mi->file, WRITE_CACHE,0L,0,1); error=test(flush_master_info(mi)); pthread_mutex_unlock(&mi->data_lock); return error; err: sql_print_error(msg); end_io_cache(&mi->file); end_relay_log_info(&mi->rli); DBUG_ASSERT(fd>=0); my_close(fd, MYF(0)); mi->fd=-1; pthread_mutex_unlock(&mi->data_lock); return 1; } int register_slave_on_master(MYSQL* mysql) { String packet; char buf[4]; if (!report_host) return 0; int4store(buf, server_id); packet.append(buf, 4); net_store_data(&packet, report_host); if (report_user) net_store_data(&packet, report_user); else packet.append((char)0); if(report_password) net_store_data(&packet, report_user); else packet.append((char)0); int2store(buf, (uint16)report_port); packet.append(buf, 2); int4store(buf, rpl_recovery_rank); packet.append(buf, 4); int4store(buf, 0); /* tell the master will fill in master_id */ packet.append(buf, 4); if (mc_simple_command(mysql, COM_REGISTER_SLAVE, (char*)packet.ptr(), packet.length(), 0)) { sql_print_error("Error on COM_REGISTER_SLAVE: '%s'", mc_mysql_error(mysql)); return 1; } return 0; } int show_master_info(THD* thd, MASTER_INFO* mi) { // TODO: fix this for multi-master DBUG_ENTER("show_master_info"); List<Item> field_list; field_list.push_back(new Item_empty_string("Master_Host", sizeof(mi->host))); field_list.push_back(new Item_empty_string("Master_User", sizeof(mi->user))); field_list.push_back(new Item_empty_string("Master_Port", 6)); field_list.push_back(new Item_empty_string("Connect_retry", 6)); field_list.push_back(new Item_empty_string("Master_Log_File", FN_REFLEN)); field_list.push_back(new Item_empty_string("Read_Master_Log_Pos", 12)); field_list.push_back(new Item_empty_string("Relay_Log_File", FN_REFLEN)); field_list.push_back(new Item_empty_string("Relay_Log_Pos", 12)); field_list.push_back(new Item_empty_string("Relay_Master_Log_File", FN_REFLEN)); field_list.push_back(new Item_empty_string("Slave_IO_Running", 3)); field_list.push_back(new Item_empty_string("Slave_SQL_Running", 3)); field_list.push_back(new Item_empty_string("Replicate_do_db", 20)); field_list.push_back(new Item_empty_string("Replicate_ignore_db", 20)); field_list.push_back(new Item_empty_string("Last_errno", 4)); field_list.push_back(new Item_empty_string("Last_error", 20)); field_list.push_back(new Item_empty_string("Skip_counter", 12)); field_list.push_back(new Item_empty_string("Exec_master_log_pos", 12)); if(send_fields(thd, field_list, 1)) DBUG_RETURN(-1); String* packet = &thd->packet; packet->length(0); pthread_mutex_lock(&mi->data_lock); pthread_mutex_lock(&mi->rli.data_lock); net_store_data(packet, mi->host); net_store_data(packet, mi->user); net_store_data(packet, (uint32) mi->port); net_store_data(packet, (uint32) mi->connect_retry); net_store_data(packet, mi->master_log_name); net_store_data(packet, (longlong) mi->master_log_pos); net_store_data(packet, mi->rli.relay_log_name + dirname_length(mi->rli.relay_log_name)); net_store_data(packet, (longlong) mi->rli.relay_log_pos); net_store_data(packet, mi->rli.master_log_name); net_store_data(packet, mi->slave_running ? "Yes":"No"); net_store_data(packet, mi->rli.slave_running ? "Yes":"No"); net_store_data(packet, &replicate_do_db); net_store_data(packet, &replicate_ignore_db); net_store_data(packet, (uint32)mi->rli.last_slave_errno); net_store_data(packet, mi->rli.last_slave_error); net_store_data(packet, mi->rli.slave_skip_counter); net_store_data(packet, (longlong)mi->rli.master_log_pos); pthread_mutex_unlock(&mi->rli.data_lock); pthread_mutex_unlock(&mi->data_lock); if (my_net_write(&thd->net, (char*)thd->packet.ptr(), packet->length())) DBUG_RETURN(-1); send_eof(&thd->net); DBUG_RETURN(0); } int flush_master_info(MASTER_INFO* mi) { IO_CACHE* file = &mi->file; char lbuf[22]; my_b_seek(file, 0L); my_b_printf(file, "%s\n%s\n%s\n%s\n%s\n%d\n%d\n%d\n", mi->master_log_name, llstr(mi->master_log_pos, lbuf), mi->host, mi->user, mi->password, mi->port, mi->connect_retry ); flush_io_cache(file); return 0; } int st_relay_log_info::wait_for_pos(THD* thd, String* log_name, ulonglong log_pos) { if (!inited) return -1; bool pos_reached = 0; int event_count = 0; pthread_mutex_lock(&data_lock); while (!thd->killed) { int cmp_result; DBUG_ASSERT(*master_log_name || master_log_pos == 0); if (*master_log_name) { /* We should use dirname_length() here when we have a version of this that doesn't modify the argument */ char *basename = strrchr(master_log_name, FN_LIBCHAR); if (basename) ++basename; else basename = master_log_name; cmp_result = strncmp(basename, log_name->ptr(), log_name->length()); } else cmp_result = 0; pos_reached = ((!cmp_result && master_log_pos >= log_pos) || cmp_result > 0); if (pos_reached || thd->killed) break; const char* msg = thd->enter_cond(&data_cond, &data_lock, "Waiting for master update"); pthread_cond_wait(&data_cond, &data_lock); thd->exit_cond(msg); event_count++; } pthread_mutex_unlock(&data_lock); return thd->killed ? -1 : event_count; } static int init_slave_thread(THD* thd, SLAVE_THD_TYPE thd_type) { DBUG_ENTER("init_slave_thread"); thd->system_thread = thd->bootstrap = 1; thd->client_capabilities = 0; my_net_init(&thd->net, 0); thd->net.timeout = slave_net_timeout; thd->max_packet_length=thd->net.max_packet; thd->master_access= ~0; thd->priv_user = 0; thd->slave_thread = 1; thd->options = (((opt_log_slave_updates) ? OPTION_BIN_LOG:0) | OPTION_AUTO_IS_NULL) ; thd->system_thread = 1; thd->client_capabilities = CLIENT_LOCAL_FILES; thd->real_id=pthread_self(); pthread_mutex_lock(&LOCK_thread_count); thd->thread_id = thread_id++; pthread_mutex_unlock(&LOCK_thread_count); if (init_thr_lock() || my_pthread_setspecific_ptr(THR_THD, thd) || my_pthread_setspecific_ptr(THR_MALLOC, &thd->mem_root) || my_pthread_setspecific_ptr(THR_NET, &thd->net)) { end_thread(thd,0); DBUG_RETURN(-1); } thd->mysys_var=my_thread_var; thd->dbug_thread_id=my_thread_id(); #if !defined(__WIN__) && !defined(OS2) sigset_t set; VOID(sigemptyset(&set)); // Get mask in use VOID(pthread_sigmask(SIG_UNBLOCK,&set,&thd->block_signals)); #endif thd->mem_root.free=thd->mem_root.used=0; // Probably not needed if (thd->max_join_size == (ulong) ~0L) thd->options |= OPTION_BIG_SELECTS; if (thd_type == SLAVE_THD_SQL) { thd->proc_info = "Waiting for the next event in slave queue"; } else { thd->proc_info="Waiting for master update"; } thd->version=refresh_version; thd->set_time(); DBUG_RETURN(0); } static int safe_sleep(THD* thd, MASTER_INFO* mi, int sec) { thr_alarm_t alarmed; thr_alarm_init(&alarmed); time_t start_time= time((time_t*) 0); time_t end_time= start_time+sec; ALARM alarm_buff; while (start_time < end_time) { int nap_time = (int) (end_time - start_time); /* the only reason we are asking for alarm is so that we will be woken up in case of murder, so if we do not get killed, set the alarm so it goes off after we wake up naturally */ thr_alarm(&alarmed, 2 * nap_time,&alarm_buff); sleep(nap_time); // if we wake up before the alarm goes off, hit the button // so it will not wake up the wife and kids :-) if (thr_alarm_in_use(&alarmed)) thr_end_alarm(&alarmed); if (slave_killed(thd,mi)) return 1; start_time=time((time_t*) 0); } return 0; } static int request_dump(MYSQL* mysql, MASTER_INFO* mi) { char buf[FN_REFLEN + 10]; int len; int binlog_flags = 0; // for now char* logname = mi->master_log_name; int4store(buf, mi->master_log_pos); int2store(buf + 4, binlog_flags); int4store(buf + 6, server_id); len = (uint) strlen(logname); memcpy(buf + 10, logname,len); if (mc_simple_command(mysql, COM_BINLOG_DUMP, buf, len + 10, 1)) { // something went wrong, so we will just reconnect and retry later // in the future, we should do a better error analysis, but for // now we just fill up the error log :-) sql_print_error("Error on COM_BINLOG_DUMP: %s, will retry in %d secs", mc_mysql_error(mysql), master_connect_retry); return 1; } return 0; } static int request_table_dump(MYSQL* mysql, const char* db, const char* table) { char buf[1024]; char * p = buf; uint table_len = (uint) strlen(table); uint db_len = (uint) strlen(db); if(table_len + db_len > sizeof(buf) - 2) { sql_print_error("request_table_dump: Buffer overrun"); return 1; } *p++ = db_len; memcpy(p, db, db_len); p += db_len; *p++ = table_len; memcpy(p, table, table_len); if (mc_simple_command(mysql, COM_TABLE_DUMP, buf, p - buf + table_len, 1)) { sql_print_error("request_table_dump: Error sending the table dump \ command"); return 1; } return 0; } static ulong read_event(MYSQL* mysql, MASTER_INFO *mi) { ulong len = packet_error; // for convinience lets think we start by // being in the interrupted state :-) int read_errno = EINTR; // my_real_read() will time us out // we check if we were told to die, and if not, try reading again #ifndef DBUG_OFF if (disconnect_slave_event_count && !(events_till_disconnect--)) return packet_error; #endif while (!abort_loop && !mi->abort_slave && len == packet_error && read_errno == EINTR ) { len = mc_net_safe_read(mysql); read_errno = errno; } if (abort_loop || mi->abort_slave) return packet_error; if (len == packet_error || (long) len < 1) { sql_print_error("Error reading packet from server: %s (read_errno %d,\ server_errno=%d)", mc_mysql_error(mysql), read_errno, mc_mysql_errno(mysql)); return packet_error; } if (len == 1) { sql_print_error("Slave: received 0 length packet from server, apparent\ master shutdown: %s (%d)", mc_mysql_error(mysql), read_errno); return packet_error; } DBUG_PRINT("info",( "len=%u, net->read_pos[4] = %d\n", len, mysql->net.read_pos[4])); return len - 1; } int check_expected_error(THD* thd, RELAY_LOG_INFO* rli, int expected_error) { switch (expected_error) { case ER_NET_READ_ERROR: case ER_NET_ERROR_ON_WRITE: case ER_SERVER_SHUTDOWN: case ER_NEW_ABORTING_CONNECTION: my_snprintf(rli->last_slave_error, sizeof(rli->last_slave_error), "Slave: query '%s' partially completed on the master \ and was aborted. There is a chance that your master is inconsistent at this \ point. If you are sure that your master is ok, run this query manually on the\ slave and then restart the slave with SET SQL_SLAVE_SKIP_COUNTER=1;\ SLAVE START;", thd->query); rli->last_slave_errno = expected_error; sql_print_error("%s",rli->last_slave_error); return 1; default: return 0; } } static int exec_relay_log_event(THD* thd, RELAY_LOG_INFO* rli) { const char *error_msg; DBUG_ASSERT(rli->sql_thd==thd); Log_event * ev = next_event(rli); DBUG_ASSERT(rli->sql_thd==thd); if (slave_killed(thd,rli)) return 1; if (ev) { int type_code = ev->get_type_code(); int exec_res; pthread_mutex_lock(&rli->data_lock); if (ev->server_id == ::server_id || (rli->slave_skip_counter && type_code != ROTATE_EVENT)) { /* TODO: I/O thread must handle skipping file delivery for old load data infile events */ /* TODO: I/O thread should not even log events with the same server id */ rli->inc_pos(ev->get_event_len(), type_code != STOP_EVENT ? ev->log_pos : 0, 1/* skip lock*/); flush_relay_log_info(rli); if (rli->slave_skip_counter && /* protect against common user error of setting the counter to 1 instead of 2 while recovering from an failed auto-increment insert */ !((type_code == INTVAR_EVENT || type_code == STOP_EVENT) && rli->slave_skip_counter == 1)) --rli->slave_skip_counter; pthread_mutex_unlock(&rli->data_lock); delete ev; return 0; // avoid infinite update loops } pthread_mutex_unlock(&rli->data_lock); thd->server_id = ev->server_id; // use the original server id for logging thd->set_time(); // time the query if (!ev->when) ev->when = time(NULL); ev->thd = thd; thd->log_pos = ev->log_pos; exec_res = ev->exec_event(rli); DBUG_ASSERT(rli->sql_thd==thd); delete ev; return exec_res; } else { sql_print_error("\ Could not parse log event entry, check the master for binlog corruption\n\ This may also be a network problem, or just a bug in the master or slave code.\ "); return 1; } } /* slave I/O thread */ pthread_handler_decl(handle_slave_io,arg) { #ifndef DBUG_OFF slave_begin: #endif THD *thd; // needs to be first for thread_stack MYSQL *mysql = NULL ; MASTER_INFO* mi = (MASTER_INFO*)arg; char llbuff[22]; bool retried_once = 0; ulonglong last_failed_pos = 0; // TODO: see if last_failed_pos is needed DBUG_ASSERT(mi->inited); pthread_mutex_lock(&mi->run_lock); #ifndef DBUG_OFF mi->events_till_abort = abort_slave_event_count; #endif // needs to call my_thread_init(), otherwise we get a coredump in DBUG_ stuff my_thread_init(); thd = new THD; // note that contructor of THD uses DBUG_ ! DBUG_ENTER("handle_slave_io"); pthread_detach_this_thread(); if (init_slave_thread(thd, SLAVE_THD_IO)) { pthread_cond_broadcast(&mi->start_cond); pthread_mutex_unlock(&mi->run_lock); sql_print_error("Failed during slave I/O thread initialization"); goto err; } mi->io_thd = thd; thd->thread_stack = (char*)&thd; // remember where our stack is threads.append(thd); mi->slave_running = 1; mi->abort_slave = 0; pthread_cond_broadcast(&mi->start_cond); pthread_mutex_unlock(&mi->run_lock); DBUG_PRINT("info",("master info: log_file_name=%s, position=%s", mi->master_log_name, llstr(mi->master_log_pos,llbuff))); if (!(mysql = mc_mysql_init(NULL))) { sql_print_error("Slave I/O thread: error in mc_mysql_init()"); goto err; } thd->proc_info = "connecting to master"; #ifndef DBUG_OFF sql_print_error("Slave I/O thread initialized"); #endif // we can get killed during safe_connect if (!safe_connect(thd, mysql, mi)) sql_print_error("Slave I/O thread: connected to master '%s@%s:%d',\ replication started in log '%s' at position %s", mi->user, mi->host, mi->port, IO_RPL_LOG_NAME, llstr(mi->master_log_pos,llbuff)); else { sql_print_error("Slave I/O thread killed while connecting to master"); goto err; } connected: thd->slave_net = &mysql->net; thd->proc_info = "Checking master version"; if (check_master_version(mysql, mi)) { goto err; } if (!mi->old_format) { // register ourselves with the master // if fails, this is not fatal - we just print the error message and go // on with life thd->proc_info = "Registering slave on master"; if (register_slave_on_master(mysql) || update_slave_list(mysql)) goto err; } while (!slave_killed(thd,mi)) { thd->proc_info = "Requesting binlog dump"; if (request_dump(mysql, mi)) { sql_print_error("Failed on request_dump()"); if(slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed while requesting master \ dump"); goto err; } thd->proc_info = "Waiiting to reconnect after a failed dump request"; mc_end_server(mysql); // first time retry immediately, assuming that we can recover // right away - if first time fails, sleep between re-tries // hopefuly the admin can fix the problem sometime if (retried_once) safe_sleep(thd, mi, mi->connect_retry); else retried_once = 1; if (slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed while retrying master \ dump"); goto err; } thd->proc_info = "Reconnecting after a failed dump request"; sql_print_error("Slave I/O thread: failed dump request, \ reconnecting to try again, log '%s' at postion %s", IO_RPL_LOG_NAME, llstr(mi->master_log_pos,llbuff)); if (safe_reconnect(thd, mysql, mi) || slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed during or \ after reconnect"); goto err; } goto connected; } while (!slave_killed(thd,mi)) { thd->proc_info = "Reading master update"; ulong event_len = read_event(mysql, mi); if (slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed while reading event"); goto err; } if (event_len == packet_error) { if (mc_mysql_errno(mysql) == ER_NET_PACKET_TOO_LARGE) { sql_print_error("Log entry on master is longer than \ max_allowed_packet on slave. Slave thread will be aborted. If the entry is \ really supposed to be that long, restart the server with a higher value of \ max_allowed_packet. The current value is %ld", max_allowed_packet); goto err; } thd->proc_info = "Waiting to reconnect after a failed read"; mc_end_server(mysql); if (retried_once) // punish repeat offender with sleep safe_sleep(thd,mi,mi->connect_retry); else retried_once = 1; if (slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed while waiting to \ reconnect after a failed read"); goto err; } thd->proc_info = "Reconnecting after a failed read"; sql_print_error("Slave I/O thread: Failed reading log event, \ reconnecting to retry, log '%s' position %s", IO_RPL_LOG_NAME, llstr(mi->master_log_pos, llbuff)); if (safe_reconnect(thd, mysql, mi) || slave_killed(thd,mi)) { sql_print_error("Slave I/O thread killed during or after a \ reconnect done to recover from failed read"); goto err; } goto connected; } // if(event_len == packet_error) thd->proc_info = "Queueing event from master"; if (queue_event(mi,(const char*)mysql->net.read_pos + 1, (uint)event_len)) { sql_print_error("Slave I/O thread could not queue event \ from master"); goto err; } // TODO: check debugging abort code #ifndef DBUG_OFF if (abort_slave_event_count && !--events_till_abort) { sql_print_error("Slave I/O thread: debugging abort"); goto err; } #endif } // while(!slave_killed(thd,mi)) - read/exec loop } // while(!slave_killed(thd,mi)) - slave loop // error = 0; err: // print the current replication position sql_print_error("Slave I/O thread exiting, read up to log '%s', position %s", IO_RPL_LOG_NAME, llstr(mi->master_log_pos,llbuff)); thd->query = thd->db = 0; // extra safety if(mysql) mc_mysql_close(mysql); thd->proc_info = "Waiting for slave mutex on exit"; pthread_mutex_lock(&mi->run_lock); mi->slave_running = 0; mi->io_thd = 0; // TODO: make rpl_status part of MASTER_INFO change_rpl_status(RPL_ACTIVE_SLAVE,RPL_IDLE_SLAVE); mi->abort_slave = 0; // TODO: check if this is needed DBUG_ASSERT(thd->net.buff != 0); net_end(&thd->net); // destructor will not free it, because we are weird pthread_mutex_lock(&LOCK_thread_count); delete thd; pthread_mutex_unlock(&LOCK_thread_count); pthread_cond_broadcast(&mi->stop_cond); // tell the world we are done pthread_mutex_unlock(&mi->run_lock); my_thread_end(); #ifndef DBUG_OFF if(abort_slave_event_count && !events_till_abort) goto slave_begin; #endif pthread_exit(0); DBUG_RETURN(0); // Can't return anything here } /* slave SQL logic thread */ pthread_handler_decl(handle_slave_sql,arg) { #ifndef DBUG_OFF slave_begin: #endif THD *thd; /* needs to be first for thread_stack */ MYSQL *mysql = NULL ; bool retried_once = 0; ulonglong last_failed_pos = 0; // TODO: see if this can be removed char llbuff[22],llbuff1[22]; RELAY_LOG_INFO* rli = &((MASTER_INFO*)arg)->rli; const char* errmsg=0; DBUG_ASSERT(rli->inited); pthread_mutex_lock(&rli->run_lock); DBUG_ASSERT(!rli->slave_running); #ifndef DBUG_OFF rli->events_till_abort = abort_slave_event_count; #endif // needs to call my_thread_init(), otherwise we get a coredump in DBUG_ stuff my_thread_init(); thd = new THD; // note that contructor of THD uses DBUG_ ! DBUG_ENTER("handle_slave_sql"); pthread_detach_this_thread(); if (init_slave_thread(thd, SLAVE_THD_SQL)) { // TODO: this is currently broken - slave start and change master // will be stuck if we fail here pthread_cond_broadcast(&rli->start_cond); pthread_mutex_unlock(&rli->run_lock); sql_print_error("Failed during slave thread initialization"); goto err; } thd->thread_stack = (char*)&thd; // remember where our stack is thd->temporary_tables = rli->save_temporary_tables; // restore temp tables threads.append(thd); rli->sql_thd = thd; rli->slave_running = 1; rli->abort_slave = 0; pthread_cond_broadcast(&rli->start_cond); pthread_mutex_unlock(&rli->run_lock); rli->pending = 0; //this should always be set to 0 when the slave thread // is started if (init_relay_log_pos(rli,0,0,1/*need data lock*/,&errmsg)) { sql_print_error("Error initializing relay log position: %s", errmsg); goto err; } DBUG_ASSERT(rli->relay_log_pos >= 4); DBUG_ASSERT(my_b_tell(rli->cur_log) == rli->relay_log_pos); DBUG_PRINT("info",("master info: log_file_name=%s, position=%s", rli->master_log_name, llstr(rli->master_log_pos,llbuff))); DBUG_ASSERT(rli->sql_thd == thd); sql_print_error("Slave SQL thread initialized, starting replication in \ log '%s' at position %s,relay log: name='%s',pos='%s'", RPL_LOG_NAME, llstr(rli->master_log_pos,llbuff),rli->relay_log_name, llstr(rli->relay_log_pos,llbuff1)); while (!slave_killed(thd,rli)) { thd->proc_info = "Processing master log event"; DBUG_ASSERT(rli->sql_thd == thd); if (exec_relay_log_event(thd,rli)) { // do not scare the user if SQL thread was simply killed or stopped if (!slave_killed(thd,rli)) sql_print_error("\ Error running query, slave SQL thread aborted. Fix the problem, and restart \ the slave SQL thread with \"mysqladmin start-slave\". We stopped at log \ '%s' position %s", RPL_LOG_NAME, llstr(rli->master_log_pos, llbuff)); goto err; } } // while(!slave_killed(thd,rli)) - read/exec loop // error = 0; err: // print the current replication position sql_print_error("Slave SQL thread exiting, replication stopped in log \ '%s' at position %s", RPL_LOG_NAME, llstr(rli->master_log_pos,llbuff)); thd->query = thd->db = 0; // extra safety thd->proc_info = "Waiting for slave mutex on exit"; pthread_mutex_lock(&rli->run_lock); DBUG_ASSERT(rli->slave_running == 1); // tracking buffer overrun rli->slave_running = 0; rli->save_temporary_tables = thd->temporary_tables; //TODO: see if we can do this conditionally in next_event() instead // to avoid unneeded position re-init rli->log_pos_current=0; thd->temporary_tables = 0; // remove tempation from destructor to close them DBUG_ASSERT(thd->net.buff != 0); net_end(&thd->net); // destructor will not free it, because we are weird DBUG_ASSERT(rli->sql_thd == thd); rli->sql_thd = 0; pthread_mutex_lock(&LOCK_thread_count); delete thd; pthread_mutex_unlock(&LOCK_thread_count); pthread_cond_broadcast(&rli->stop_cond); // tell the world we are done pthread_mutex_unlock(&rli->run_lock); my_thread_end(); #ifndef DBUG_OFF // TODO: reconsider the code below if (abort_slave_event_count && !rli->events_till_abort) goto slave_begin; #endif pthread_exit(0); DBUG_RETURN(0); // Can't return anything here } // We assume we already locked mi->data_lock static int process_io_rotate(MASTER_INFO* mi, Rotate_log_event* rev) { if (!rev->is_valid()) return 1; DBUG_ASSERT(rev->ident_len<sizeof(mi->master_log_name)); memcpy(mi->master_log_name,rev->new_log_ident, rev->ident_len); mi->master_log_name[rev->ident_len] = 0; mi->master_log_pos = rev->pos; #ifndef DBUG_OFF /* if we do not do this, we will be getting the first rotate event forever, so we need to not disconnect after one */ if (disconnect_slave_event_count) events_till_disconnect++; #endif return 0; } // TODO: verify the issue with stop events, see if we need them at all // in the relay log // TODO: test this code before release - it has to be tested on a separte // setup with 3.23 master static int queue_old_event(MASTER_INFO* mi, const char* buf, uint event_len) { const char* errmsg = 0; bool inc_pos = 1; bool processed_stop_event = 0; Log_event* ev = Log_event::read_log_event(buf,event_len, &errmsg, 1/*old format*/); if (unlikely(!ev)) { sql_print_error("Read invalid event from master: '%s',\ master could be corrupt but a more likely cause of this is a bug", errmsg); return 1; } pthread_mutex_lock(&mi->data_lock); ev->log_pos = mi->master_log_pos; switch (ev->get_type_code()) { case ROTATE_EVENT: if (unlikely(process_io_rotate(mi,(Rotate_log_event*)ev))) { delete ev; pthread_mutex_unlock(&mi->data_lock); return 1; } mi->ignore_stop_event=1; inc_pos = 0; break; case STOP_EVENT: processed_stop_event=1; break; case LOAD_EVENT: // TODO: actually process it mi->master_log_pos += event_len; delete ev; pthread_mutex_unlock(&mi->data_lock); return 0; default: mi->ignore_stop_event=0; break; } if (likely(!processed_stop_event || !mi->ignore_stop_event)) { if (unlikely(mi->rli.relay_log.append(ev))) { delete ev; pthread_mutex_unlock(&mi->data_lock); return 1; } } delete ev; if (likely(inc_pos)) mi->master_log_pos += event_len; if (unlikely(processed_stop_event)) mi->ignore_stop_event=1; pthread_mutex_lock(&mi->data_lock); return 0; } // TODO: verify the issue with stop events, see if we need them at all // in the relay log int queue_event(MASTER_INFO* mi,const char* buf,uint event_len) { int error=0; bool inc_pos = 1; bool processed_stop_event = 0; if (mi->old_format) return queue_old_event(mi,buf,event_len); pthread_mutex_lock(&mi->data_lock); // TODO: figure out if other events in addition to Rotate // require special processing switch (buf[EVENT_TYPE_OFFSET]) { case STOP_EVENT: processed_stop_event=1; break; case ROTATE_EVENT: { Rotate_log_event rev(buf,event_len,0); if (unlikely(process_io_rotate(mi,&rev))) return 1; inc_pos=0; mi->ignore_stop_event=1; break; } default: mi->ignore_stop_event=0; break; } if (likely((!processed_stop_event || !mi->ignore_stop_event) && !(error = mi->rli.relay_log.appendv(buf,event_len,0)))) { if (likely(inc_pos)) mi->master_log_pos += event_len; } if (unlikely(processed_stop_event)) mi->ignore_stop_event=1; pthread_mutex_unlock(&mi->data_lock); return error; } void end_relay_log_info(RELAY_LOG_INFO* rli) { if (!rli->inited) return; if (rli->info_fd >= 0) { end_io_cache(&rli->info_file); (void)my_close(rli->info_fd, MYF(MY_WME)); rli->info_fd = -1; } if (rli->cur_log_fd >= 0) { end_io_cache(&rli->cache_buf); (void)my_close(rli->cur_log_fd, MYF(MY_WME)); rli->cur_log_fd = -1; } rli->inited = 0; rli->log_pos_current=0; rli->relay_log.close(1); } /* try to connect until successful or slave killed */ static int safe_connect(THD* thd, MYSQL* mysql, MASTER_INFO* mi) { return connect_to_master(thd, mysql, mi, 0); } /* Try to connect until successful or slave killed or we have retried master_retry_count times */ static int connect_to_master(THD* thd, MYSQL* mysql, MASTER_INFO* mi, bool reconnect) { int slave_was_killed; int last_errno= -2; // impossible error ulong err_count=0; char llbuff[22]; #ifndef DBUG_OFF events_till_disconnect = disconnect_slave_event_count; #endif while (!(slave_was_killed = slave_killed(thd,mi)) && (reconnect ? mc_mysql_reconnect(mysql) : !mc_mysql_connect(mysql, mi->host, mi->user, mi->password, 0, mi->port, 0, 0))) { /* Don't repeat last error */ if (mc_mysql_errno(mysql) != last_errno) { sql_print_error("Slave I/O thread: error connecting to master \ '%s@%s:%d': \ %s, last_errno=%d, retry in %d sec",mi->user,mi->host,mi->port, mc_mysql_error(mysql), last_errno=mc_mysql_errno(mysql), mi->connect_retry); } safe_sleep(thd,mi,mi->connect_retry); /* by default we try forever. The reason is that failure will trigger master election, so if the user did not set master_retry_count we do not want to have electioin triggered on the first failure to connect */ if (master_retry_count && err_count++ == master_retry_count) { slave_was_killed=1; if (reconnect) change_rpl_status(RPL_ACTIVE_SLAVE,RPL_LOST_SOLDIER); break; } } if (!slave_was_killed) { if (reconnect) sql_print_error("Slave: connected to master '%s@%s:%d',\ replication resumed in log '%s' at position %s", mi->user, mi->host, mi->port, IO_RPL_LOG_NAME, llstr(mi->master_log_pos,llbuff)); else { change_rpl_status(RPL_IDLE_SLAVE,RPL_ACTIVE_SLAVE); mysql_log.write(thd, COM_CONNECT_OUT, "%s@%s:%d", mi->user, mi->host, mi->port); } #ifdef SIGNAL_WITH_VIO_CLOSE thd->set_active_vio(mysql->net.vio); #endif } return slave_was_killed; } /* Try to connect until successful or slave killed or we have retried master_retry_count times */ static int safe_reconnect(THD* thd, MYSQL* mysql, MASTER_INFO* mi) { return connect_to_master(thd, mysql, mi, 1); } int flush_relay_log_info(RELAY_LOG_INFO* rli) { IO_CACHE* file = &rli->info_file; char lbuf[22],lbuf1[22]; my_b_seek(file, 0L); my_b_printf(file, "%s\n%s\n%s\n%s\n", rli->relay_log_name, llstr(rli->relay_log_pos, lbuf), rli->master_log_name, llstr(rli->master_log_pos, lbuf1) ); flush_io_cache(file); flush_io_cache(rli->cur_log); return 0; } IO_CACHE* reopen_relay_log(RELAY_LOG_INFO* rli, const char** errmsg) { DBUG_ASSERT(rli->cur_log != &rli->cache_buf); IO_CACHE* cur_log = rli->cur_log=&rli->cache_buf; DBUG_ASSERT(rli->cur_log_fd == -1); if ((rli->cur_log_fd=open_binlog(cur_log,rli->relay_log_name, errmsg))<0) return 0; my_b_seek(cur_log,rli->relay_log_pos); return cur_log; } Log_event* next_event(RELAY_LOG_INFO* rli) { Log_event* ev; IO_CACHE* cur_log = rli->cur_log; pthread_mutex_t *log_lock = rli->relay_log.get_log_lock(); const char* errmsg=0; THD* thd = rli->sql_thd; bool was_killed; DBUG_ASSERT(thd != 0); // For most operations we need to protect rli members with data_lock, // so we will hold it for the most of the loop below // However, we will release it whenever it is worth the hassle, // and in the cases when we go into a pthread_cond_wait() with the // non-data_lock mutex pthread_mutex_lock(&rli->data_lock); for (;!(was_killed=slave_killed(thd,rli));) { // we can have two kinds of log reading: // hot_log - rli->cur_log points at the IO_CACHE of relay_log, which // is actively being updated by the I/O thread. We need to be careful // in this case and make sure that we are not looking at a stale log that // has already been rotated. If it has been, we reopen the log // the other case is much simpler - we just have a read only log that // nobody else will be updating. bool hot_log; if ((hot_log = (cur_log != &rli->cache_buf))) { DBUG_ASSERT(rli->cur_log_fd == -1); // foreign descriptor pthread_mutex_lock(log_lock); // reading cur_log->init_count here is safe because the log will only // be rotated when we hold relay_log.LOCK_log if (cur_log->init_count != rli->cur_log_init_count) { if (!(cur_log=reopen_relay_log(rli,&errmsg))) { pthread_mutex_unlock(log_lock); goto err; } pthread_mutex_unlock(log_lock); hot_log=0; } } DBUG_ASSERT(my_b_tell(cur_log) >= 4); DBUG_ASSERT(my_b_tell(cur_log) == rli->relay_log_pos + rli->pending); if ((ev=Log_event::read_log_event(cur_log,0,rli->mi->old_format))) { DBUG_ASSERT(thd==rli->sql_thd); if (hot_log) pthread_mutex_unlock(log_lock); pthread_mutex_unlock(&rli->data_lock); return ev; } DBUG_ASSERT(thd==rli->sql_thd); if (!cur_log->error) /* EOF */ { // on a hot log, EOF means that there are no more updates to // process and we must block until I/O thread adds some and // signals us to continue if (hot_log) { DBUG_ASSERT(cur_log->init_count == rli->cur_log_init_count); //we can, and should release data_lock while we are waiting for // update. If we do not, show slave status will block pthread_mutex_unlock(&rli->data_lock); // IMPORTANT: note that wait_for_update will unlock LOCK_log, but // expects the caller to lock it rli->relay_log.wait_for_update(rli->sql_thd); // re-acquire data lock since we released it earlier pthread_mutex_lock(&rli->data_lock); continue; } // if the log was not hot, we need to move to the next log in // sequence. The next log could be hot or cold, we deal with both // cases separately after doing some common initialization else { end_io_cache(cur_log); DBUG_ASSERT(rli->cur_log_fd >= 0); my_close(rli->cur_log_fd, MYF(MY_WME)); rli->cur_log_fd = -1; int error; // purge_first_log will properly set up relay log coordinates in rli if (rli->relay_log.purge_first_log(rli)) { errmsg = "Error purging processed log"; goto err; } // next log is hot if (rli->relay_log.is_active(rli->linfo.log_file_name)) { #ifdef EXTRA_DEBUG sql_print_error("next log '%s' is currently active", rli->linfo.log_file_name); #endif rli->cur_log = cur_log = rli->relay_log.get_log_file(); rli->cur_log_init_count = cur_log->init_count; DBUG_ASSERT(rli->cur_log_fd == -1); // read pointer has to be at the start since we are the only // reader if (check_binlog_magic(cur_log,&errmsg)) goto err; continue; } // if we get here, the log was not hot, so we will have to // open it ourselves #ifdef EXTRA_DEBUG sql_print_error("next log '%s' is not active", rli->linfo.log_file_name); #endif // open_binlog() will check the magic header if ((rli->cur_log_fd=open_binlog(cur_log,rli->linfo.log_file_name, &errmsg))<0) goto err; } } else // read failed with a non-EOF error { // TODO: come up with something better to handle this error sql_print_error("Slave SQL thread: I/O error reading \ event(errno=%d,cur_log->error=%d)", my_errno,cur_log->error); // no need to hog the mutex while we sleep pthread_mutex_unlock(&rli->data_lock); safe_sleep(rli->sql_thd,rli->mi,1); pthread_mutex_lock(&rli->data_lock); } } if (!errmsg && was_killed) errmsg = "slave SQL thread was killed"; err: pthread_mutex_unlock(&rli->data_lock); sql_print_error("Error reading relay log event: %s", errmsg); return 0; } #ifdef __GNUC__ template class I_List_iterator<i_string>; template class I_List_iterator<i_string_pair>; #endif