Dive into MySQL Error - Speaker Deck

Slide 1

Slide 1 text

Dive into MySQL Error 第二雑学研究部、略してダイブ 2018/09/21 yoku0825 db tech showcase 2018

Slide 2

Slide 2 text

おはようございます 1/81

Slide 3

Slide 3 text

ついに最終日を迎えたdb tech showcase Tokyo 2018 2/81

Slide 4

Slide 4 text

楽しんでますか？ 3/81

Slide 5

Slide 5 text

楽しいですよね :) 4/81

Slide 6

Slide 6 text

いいはなしいっぱい 5/81

Slide 7

Slide 7 text

でもそろそろみなさん 6/81

Slide 8

Slide 8 text

コードが恋しくなってきた頃なんじゃないかと思います 7/81

Slide 9

Slide 9 text

データベース 8/81

Slide 10

Slide 10 text

の 9/81

Slide 11

Slide 11 text

コードが 10/81

Slide 12

Slide 12 text

恋しいですよね :D 11/81

Slide 13

Slide 13 text

って言っておいてほとんどソースコード出てきません :-P 12/81

Slide 14

Slide 14 text

ところで 13/81

Slide 15

Slide 15 text

昨日までは確かに 14/81

Slide 16

Slide 16 text

　 15/81

Slide 17

Slide 17 text

(つд⊂)ｺﾞｼｺﾞｼ 16/81

Slide 18

Slide 18 text

　 17/81

Slide 19

Slide 19 text

　　_, ._ （；ﾟ Дﾟ） …！？ 18/81

Slide 20

Slide 20 text

1日目、懇親会後石川さん「おっ久しぶり。今年は何かしゃべらないの？」おれ「今年はお呼ばれしてないんですよー＞＜」石川さん「あれ？確かまだ枠あるよね」おれ「えっ」石川さん「3日目の朝イチ、やる？」おれ「やります」 19/81

Slide 21

Slide 21 text

インサイトテクノロジー石川さん、松尾さん本当にありがとうございますm(＿＿)m 20/81

Slide 22

Slide 22 text

Be Crazy!! 21/81

Slide 23

Slide 23 text

＼おはようございます／ yoku0825＠とある企業のDBA オラクれない ‐ ポスグれない ‐ マイエスキューエる ‐ 生息域 Twitter: @yoku0825 ‐ Blog: 日々の覚書 ‐ 日本MySQLユーザ会副代表 ‐ MySQL Casual ‐ 22/81

Slide 24

Slide 24 text

Dive into MySQL Error 23/81

Slide 25

Slide 25 text

MySQLのエラーの話をします 24/81

Slide 26

Slide 26 text

MySQLのエラーってこんなやつ ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/tmp/mysql.sock' (2) 25/81

Slide 27

Slide 27 text

MySQLのエラーってこんなやつ 1130 - SQLSTATE[HY000] [1130] Host ‘172.19.133.50’ is not allow ed to connect to this MySQL server in /usr/local/../connection.ph p on line 100 26/81

Slide 28

Slide 28 text

まずは大分類 1000番台、3000番台, 10000超はサーバーのエラーコード 3000番台は5.7から、10000超は8.0で導入された ‐ 2000番台はクライアント（libmysqlclient.so）のエラーコードよって2000番台はどのクライアントライブラリーを使うかで変わる ‐ 27/81

Slide 29

Slide 29 text

最初につけられるアタリクライアントサイドのエラーなら、そもそもサーバーに到達していないか可能性があるネットワーク経路的な問題が一番多い気がする ‐ クライアントサイドエラーでも、サーバーがエラーに絡んでいることもあるクライアントライブラリーの互換性の問題 ‐ サーバー側からのコネクション強制切断 ‐ 28/81

Slide 30

Slide 30 text

2002: CR_CONNECTION_ERROR ERROR 2002 (HY000): Can't connect to local MySQL server through socket '/tmp/mysql.sock' (2) 29/81

Slide 31

Slide 31 text

2000番台エラーの特徴サーバーから切られた以外の理由の場合、OSエラーコードが載っていることが多い例えばLinuxなら #define ENOENT 2 /* No such file or directory */ #define ENOSPC 28 /* No space left on device */ #define ETIMEDOUT 110 /* Connection timed out */ #define ECONNREFUSED 111 /* Connection refused */ #define EHOSTUNREACH 113 /* No route to host */ 30/81

Slide 32

Slide 32 text

2002: CR_CONNECTION_ERROR ERROR 2002 (HY000): Can't connect to local MySQL server through s ocket '/tmp/mysql.sock' (2) $ perror 2 OS error code 2: No such file or directory MySQL error code MY-000002: Error reading file '%s' (OS errno %d - %s) 31/81

Slide 33

Slide 33 text

2000番台エラーの特徴 perror コマンドでは調べられないパッケージ的にサーバーの付属ユーティリティーだから、だと思う ‐ MySQL :: MySQL 8.0 Reference Manual :: 4.8.2 perror — Explain Error Codes ‐ OSエラーコードは perror コマンドで引ける ‐ OSエラーコードはたまにMySQLサーバーのエラーコードとカブるので見失わないように… ‐ perror についてはあとで詳しく ‐ 32/81

Slide 34

Slide 34 text

2003: CR_CONN_HOST_ERROR $ mysql -h10.0.0.1 ERROR 2003 (HY000): Can't connect to MySQL server on '10.0.0.1' (110) $ perror 2003 110 Illegal error code: 2003 OS error code 110: Connection timed out 33/81

Slide 35

Slide 35 text

同じ2003のタイムアウトでもどっちがタイムアウトさせるかによって違ったり mysql_options(mysql, MYSQL_OPT_READ_TIMEOUT, 1); mysql_real_connect(mysql); 2003: Can't connect to MySQL server on '192.168.225.201' (4) $ perror 4 OS error code 4: Interrupted system call MySQL error code MY-000004: Error on close of '%s' (OS errno %d - %s) 34/81

Slide 36

Slide 36 text

2000番台でもサーバーの都合も含むものサーバー側のアイドルタイマー（wait_timeout）に引っかかってサーバー側から一方的にコネクションを切断されたり KILLステートメントで切断されたり mysql80 11> SELECT NOW(); ERROR 2006 (HY000): MySQL server has gone away mysql80 13> SELECT NOW(); ERROR 2013 (HY000): Lost connection to MySQL server during query 35/81

Slide 37

Slide 37 text

2006: CR_SERVER_GONE_ERROR クエリー投げようと思ったら既にTCP接続が死んでいたパターン mysql-client mysqld waiting querying running receiving 36/81

Slide 38

Slide 38 text

2013: CR_SERVER_LOST querying .. receivingの間にTCP接続が死んだパターン mysql-client mysqld waiting querying running receiving 37/81

Slide 39

Slide 39 text

2000番台でもサーバーの都合も含むもの MySQL 8.0でみんなよくググるコレ ERROR 2059 (HY000): Authentication plugin 'caching_sha2_password' cannot be loaded: /usr/mysql/5.6.41/lib/plugin/caching_sha2_pass word.so: cannot open shared object file: No such file or director y 38/81

Slide 40

Slide 40 text

2059: CR_AUTH_PLUGIN_CANNOT_LOAD クライアントが要求されたプラグインを見つけられないエラー。 mysql-client mysqld TCP Handshake Greeting Message User Negotiation Check user Lookup plugin Serv ... User ... auth ... 39/81

Slide 41

Slide 41 text

実はもう1種類あるクライアントから送られてきたクライアントケーパビリティーで「要求する認証プラグインにクライアントが対応していないことがわかっちゃった」のでサーバーから接続を拒否。 ERROR 1251 (08004): Client does not support authentication protoc ol requested by server; consider upgrading MySQL client 40/81

Slide 42

Slide 42 text

1251: ER_NOT_SUPPORTED_AUTH_MODE mysql-client mysqld TCP Handshake Greeting Message User Negotiation Check user Lookup plugin Serv ... User ... auth ... 41/81

Slide 43

Slide 43 text

2000番台エラーの応用（？）レプリケーションスレーブ（のI/Oスレッド）はマスターから見るとクライアントしかも必ずlibmysqlclient.so実装のクライアント ‐ Last_IO_Error に2000番台のエラーが出ることがまれにだがよくあるここで1000番台、2000番台の切り分けをスムーズにこなすとデキる MySQLerっぽい ‐ 42/81

Slide 44

Slide 44 text

Last_IO_ErrorはOSエラーコードが出てくれないのがちょっと痛い少なくとも認証失敗（1000番台）でないことはわかる Last_IO_Errno: 2003 Last_IO_Error: error connecting to master 'root@1 27.0.0.1:64057' - retry-time: 60 retries: 1 クライアントサイドエラーコードの一覧は include/ errmsg.h に 43/81

Slide 45

Slide 45 text

おまけ: Connector/J “Communication link failure” って邪悪なやつがいて src/com/mysql/jdbc/ConnectionImpl.java ‐ src/com/mysql/jdbc/ConnectionImpl.java ‐ 更にすごいことには src/com/mysql/jdbc/SQLError.java ‐ 44/81

Slide 46

Slide 46 text

ひどい XD 45/81

Slide 47

Slide 47 text

まずは大分類(again) 1000番台、3000番台, 10000超はサーバーのエラーコード 3000番台は5.7から、10000超は8.0で導入された ‐ 2000番台はクライアント（libmysqlclient.so）のエラーコードよって2000番台はどのクライアントライブラリーを使うかで変わる ‐ 46/81

Slide 48

Slide 48 text

次ににつけられるアタリ 1000番台、3000番台、10000超ならサーバーが明示的に「エラー応答」を返している ERRパケットの中には「サーバーサイドエラーコード」「SQLステート」「エラーメッセージ」が入っている 47/81

Slide 49

Slide 49 text

ERRパケット MySQL :: MySQL Internals Manual :: 14.1.3.2 ERR_Packet 48/81

Slide 50

Slide 50 text

たとえばエラーコード1251 SQLステート08004 エラーメッセージが “Client does not ..” ERROR 1251 (08004): Client does not support authentication protoc ol requested by server; consider upgrading MySQL client 49/81

Slide 51

Slide 51 text

SQLステートサーバーサイドエラーコードとSQLステートは1対多で結びつくなので、積極的にSQLステートを使うクライアントでなければエラーコードの方が追いやすい ‐ Wikipediaさん後は頼んだ SQLSTATE - Wikipedia ‐ 50/81

Slide 52

Slide 52 text

エラーメッセージ豆だけど実は日本語にできる lc_messages=ja ‐ MySQL :: MySQL 5.6 リファレンスマニュアル :: 10.2 エラーメッセージ言語の設定 ‐ 51/81

Slide 53

Slide 53 text

誰得 mysql80 32> SELECT 1 FROM t1; ERROR 1046 (3D000): No database selected mysql80 32> SET lc_messages= 'ja_JP'; Query OK, 0 rows affected (0.01 sec) mysql80 32> SELECT 1 FROM t1; ERROR 1046 (3D000): データベースが選択されていません。 share/errmsg-utf8.txt から “jpn” で始まってる行を探すと出てきます最近増えたエラーコードは網羅されていないのでコントリビュートチャンスですね :D ‐ 52/81

Slide 54

Slide 54 text

perrorコマンド $ perror 1046 MySQL error code MY-001046 (ER_NO_DB_ERROR): No database selected $ perror ER_NO_DB_ERROR MySQL error code MY-001046 (ER_NO_DB_ERROR): No database selected 53/81

Slide 55

Slide 55 text

perrorコマンドさっきから何度か出てきている perror コマンドパッケージ的には mysql-server 系に付属している引数にエラーコードを受けて、エラーメッセージのフォーマットを出力するエラー番号からだけでもエラーメッセージを推測できる ‐ エラー番号からマクロ名が取り出せるのでgrepが捗る ‐ マクロ名しか書いてない（ことはまずないと思うけど）からエラー番号とメッセージが取れる ‐ MySQL :: MySQL 5.6 リファレンスマニュアル :: 4.8.1 perror — エラーコードの説明 54/81

Slide 56

Slide 56 text

perrorコマンド一番（？）嬉しいのは、どこまでが決め打ちメッセージなのかがわかるフォーマットを見られることたとえば 1593: ER_SLAVE_FATAL_ERROR は Slave I/O: The slave I/O thread stops because master and slave ha ve equal MySQL server ids; these ids must be different for replic ation to work (or the --replicate-same-server-id option must be u sed on slave but this does not always make sense; please check th e manual before using it), Error_code: 1593 Slave I/O: The slave I/O thread stops because SET @master_heartbe at_period on master failed. Error: , Error_code: 1593 Slave SQL: Fatal error: Failed to initialize the master info stru cture, Error_code: 1593 55/81

Slide 57

Slide 57 text

1593: ER_SLAVE_FATAL_ERROR 8.0ではER_BINLOG_FATAL_ERRORになってる…: (；ﾞﾟ’ωﾟ’): perrorで引くとほとんどが可変部分で、このエラーコードが示すものは “Fatal error” しかない（あとは全部状況で変わる） $ /usr/mysql/8.0.12/bin/perror 1593 MySQL error code MY-001593 (ER_BINLOG_FATAL_ERROR): Fatal error: %s $ /usr/mysql/5.7.23/bin/perror 1593 MySQL error code 1593 (ER_SLAVE_FATAL_ERROR): Fatal error: %s 56/81

Slide 58

Slide 58 text

じゃあどこでこのバリエーションが生まれるのか？エラーマクロが分かってればgrepは簡単 $ grep -r -A1 ER_SLAVE_FATAL_ERROR . ... ./sql/log_event.cc: rli->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/log_event.cc: ER_THD(thd, ER_SLAVE_FATAL_ERROR), ./sql/log_event.cc- "Invalid character set for User var event"); -- ./sql/rpl_slave.cc: mi->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/rpl_slave.cc: ER(ER_SLAVE_FATAL_ERROR), ./sql/rpl_slave.cc- "Failed to flush master info."); -- ./sql/log_event.cc: rli->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/log_event.cc: ER_THD(thd, ER_SLAVE_FATAL_ERROR), ./sql/log_event.cc- "Binlog version not supported"); ... 57/81

Slide 59

Slide 59 text

慣れてくると mi は master_info のことでI/Oスレッド関連 rli は relay_log_info のことでSQLスレッド関連それぞれ report は SHOW SLAVE STATUS で表示されるようなバッファ（とエラーログ）に記録するためのメソッド $ grep -r -A1 ER_SLAVE_FATAL_ERROR . ... ./sql/log_event.cc: rli->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/log_event.cc: ER_THD(thd, ER_SLAVE_FATAL_ERROR), ./sql/log_event.cc- "Invalid character set for User var event"); -- ./sql/rpl_slave.cc: mi->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/rpl_slave.cc: ER(ER_SLAVE_FATAL_ERROR), ./sql/rpl_slave.cc- "Failed to flush master info."); -- ./sql/log_event.cc: rli->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ./sql/log_event.cc: ER_THD(thd, ER_SLAVE_FATAL_ERROR), ./sql/log_event.cc- "Binlog version not supported"); ... 58/81

Slide 60

Slide 60 text

プログラミング言語全く読めない方はいますか？ 59/81

Slide 61

Slide 61 text

C怖くないよ 2496 char query_buf[]= "SELECT @@GLOBAL.SERVER_UUID"; .. 2525 if (!mysql_real_query(mysql, STRING_WITH_LEN(query_buf)) && 2526 (master_res= mysql_store_result(mysql)) && 2527 (master_row= mysql_fetch_row(master_res))) 2528 { 2529 if (!strcmp(::server_uuid, master_row[0]) && 2530 !mi->rli->replicate_same_server_id) 2531 { 2532 errmsg= "The slave I/O thread stops because master and slave have equ al " 2533 "MySQL server UUIDs; these UUIDs must be different for " 2534 "replication to work."; 2535 mi->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ER(ER_SLAVE_FATAL_ERROR ), 2536 errmsg); 2537 // Fatal error 2538 ret= 1; 2539 } sql/rpl_slave.cc 60/81

Slide 62

Slide 62 text

なんとなくわかりません？ 2496 char query_buf[]= "SELECT @@GLOBAL.SERVER_UUID"; .. 2525 if (!mysql_real_query(mysql, STRING_WITH_LEN(query_buf)) && 2526 (master_res= mysql_store_result(mysql)) && 2527 (master_row= mysql_fetch_row(master_res))) 2528 { 2529 if (!strcmp(::server_uuid, master_row[0]) && 2530 !mi->rli->replicate_same_server_id) 2531 { 2532 errmsg= "The slave I/O thread stops because master and slave have equ al " 2533 "MySQL server UUIDs; these UUIDs must be different for " 2534 "replication to work."; 2535 mi->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ER(ER_SLAVE_FATAL_ERROR ), 2536 errmsg); 2537 // Fatal error 2538 ret= 1; 2539 } sql/rpl_slave.cc 61/81

Slide 63

Slide 63 text

もう1つの視点で可変部分は呼び出し元から文字列で渡されるので、1593なら “Fatal error:” の他の部分はソースコードから探しやすいそれに対して1046は可変部分がないのでエラーマクロで探すしかない $ perror 1046 MySQL error code MY-001046 (ER_NO_DB_ERROR): No database selected $ perror 1593 MySQL error code MY-001593 (ER_BINLOG_FATAL_ERROR): Fatal error: %s 62/81

Slide 64

Slide 64 text

もう1つの視点これは grep -r "UUIDs must be different" . でもたどり着ける 2496 char query_buf[]= "SELECT @@GLOBAL.SERVER_UUID"; .. 2525 if (!mysql_real_query(mysql, STRING_WITH_LEN(query_buf)) && 2526 (master_res= mysql_store_result(mysql)) && 2527 (master_row= mysql_fetch_row(master_res))) 2528 { 2529 if (!strcmp(::server_uuid, master_row[0]) && 2530 !mi->rli->replicate_same_server_id) 2531 { 2532 errmsg= "The slave I/O thread stops because master and slave have equal " 2533 "MySQL server UUIDs; these UUIDs must be different for " 2534 "replication to work."; 2535 mi->report(ERROR_LEVEL, ER_SLAVE_FATAL_ERROR, ER(ER_SLAVE_FATAL_ERROR), 2536 errmsg); 2537 // Fatal error 2538 ret= 1; 2539 } sql/rpl_slave.cc 63/81

Slide 65

Slide 65 text

もう1つの視点 grep -r "No database selected" . だとテストケースとマクロの定義部分しか出てこないそれはそれで楽しいんですけどね :D ‐ ... ./include/mysqld_ername.h:{ "ER_NO_DB_ERROR", 1046, "No database selected" }, ./mysql-test/r/alter_table.result:ERROR 3D000: No database select ed ./mysql-test/r/create.result:ERROR 3D000: No database selected ./mysql-test/t/information_schema.test:# Bug#38916 Select from I_ S.ROUTINES results in "No database selected" error ./mysql-test/t/sp.test:# Bug#28551 "The warning 'No database sele cted' is reported when calling ... 64/81

Slide 66

Slide 66 text

エラーに触れるついでにエラーログについても触れておこう 65/81

Slide 67

Slide 67 text

MySQLのエラーログとは実は freopen を使ってstderrとstdoutを向けているだけ 1772 do { 1773 errors = 0; 1774 if (!my_freopen(filename, "a", stderr)) errors++; 1775 if (!my_freopen(filename, "a", stdout)) errors++; 1776 } while (retries-- && errors); 1777 sql/log.cc 66/81

Slide 68

Slide 68 text

lsofで開いているファイルディスクリプターを見るだけでもわかったりする $ lsof -p $(pidof mysqld) .. mysqld 20980 yoku0825 0r CHR 1,3 0t0 1028 /dev/null mysqld 20980 yoku0825 1w REG 253,0 52083 137048897 /usr/mysql/8.0.12/data/error.log mysqld 20980 yoku0825 2w REG 253,0 52083 137048897 /usr/mysql/8.0.12/data/error.log mysqld 20980 yoku0825 3u REG 253,0 65 149633590 /usr/mysql/8.0.12/data/bin.index mysqld 20980 yoku0825 4u REG 253,0 4077 149596931 /usr/mysql/8.0.12/data/innodb_status.20980 .. 67/81

Slide 69

Slide 69 text

stdoutとstderrが繋がっているだけなのでプラグインの中で fprintf(stderr, ..) とかやるとフツーにエラーログに出る printデバッグする機会があれば憶えておいて損はないはず ;) ‐ InnoDB Monitor( CREATE TABLE innodb_monitor とか SET GLOBAL innodb_status_output = 1 )がなんでデフォルトでエラーログに書かれるのか… InnoDBからするとエラーログに書いてるつもりはなくて、単に標準エラー出力に書いているだけ ‐ ロガープロセスみたいなのがいるわけではない 68/81

Slide 70

Slide 70 text

stdoutとstderrが繋がっているだけなのでおもむろにエラーログの書式をガン無視したInnoDBのログに遭遇したことってありません？タイムスタンプとかログレベルとか一切ないやつ ‐ 180731 13:29:08 [Note] InnoDB: 5.5.61 started; log sequence numbe r 1625977 InnoDB: Error: page 1234 log sequence number InnoDB: is in the future! Current system log sequence number 1625 977 InnoDB: Your database may be corrupt or you may have copied the I nnoDB InnoDB: tablespace but not the InnoDB log files. See" InnoDB: https://dev.mysql.com/../forcing-innodb-recovery.html InnoDB: for more information. 69/81

Slide 71

Slide 71 text

stdoutとstderrが繋がっているだけなので 553 fprintf(stderr, 554 " InnoDB: Error: page %lu log sequence number" 555 " %llu\n" 556 "InnoDB: is in the future! Current system " 557 "log sequence number %llu.\n" 558 "InnoDB: Your database may be corrupt or " 559 "you may have copied the InnoDB\n" 560 "InnoDB: tablespace but not the InnoDB " 561 "log files. See\n" 562 "InnoDB: " REFMAN "forcing-innodb-recovery.html\n" 563 "InnoDB: for more information.\n", 564 (ulong) mach_read_from_4(read_buf 565 + FIL_PAGE_OFFSET), 566 mach_read_from_8(read_buf + FIL_PAGE_LSN), 567 current_lsn); 5 storage/innobase/buf/buf0buf.c 70/81

Slide 72

Slide 72 text

最近のはちゃんとエラーログに書くための関数を呼んでるので優しくなった 71/81

Slide 73

Slide 73 text

エラーログでついでに思い出した 72/81

Slide 74

Slide 74 text

有名なアレ mysqld got signal 11; This could be because you hit a bug. It is also possible that this binary or one of the libraries it was linked against is corrupt, improperly built, or misconfigured. This error can also be caused by malfunctioning hardware. We will try our best to scrape up some info that will hopefully help diagnose the problem, but since we have already crashed, something is definitely wrong and this may fail. key_buffer_size=67108864 read_buffer_size=2093056 max_used_connections=0 max_connections=3000 threads_connected=0 It is possible that mysqld could use up to key_buffer_size + (read_buffer_size + sort_buffer_size)*max_connections = 30773512 K bytes of memory Hope that's ok; if not, decrease some variables in the equation. 73/81

Slide 75

Slide 75 text

実はこのメッセージはハードコード 115 my_safe_printf_stderr("%s", 116 "This could be because you hit a bug. It is also " 117 "possible that this binary\n" 118 "or one of the libraries it was linke d against is " 119 "corrupt, improperly built,\n" 120 "or misconfigured. This error can als o be caused by " 121 "malfunctioning hardware.\n"); sql/signal_handler.cc 74/81

Slide 76

Slide 76 text

未だに key_buffer_size がどうこう言うのも単にハードコードされてるのを直してないだけ innodb_buffer_pool_size が出てこないのも単に直してないだけ ‐ 130 my_safe_printf_stderr("key_buffer_size=%lu\n", 131 (ulong)dflt_key_cache->key_cache_mem_ size); sql/signal_handler.cc 75/81

Slide 77

Slide 77 text

handle_fatal_signal に対する雑な所感 signal 11(SIGSEGV)だと本物の（？）バグっぽさが上がる signal 6(SIGABRT)はAssertionに引っかかってるケース多くの場合、InnoDBのページ破損とかになるんじゃなかろうか ‐ この場合はバグじゃないからあの固定メッセージ出さなくて良い気が… ‐ 76/81

Slide 78

Slide 78 text

あと、何かの名残 5.1, 5.5, 5.6にはこんな分岐があった未実装のまま5.7からは消えてる ‐ 1154 switch (error_log_printer) { 1155 case LOG_FILE: 1156 error_log_handler_list[0]= file_log_handler; 1157 error_log_handler_list[1]= 0; 1158 break; 1159 /* these two are disabled for now */ 1160 case LOG_TABLE: 1161 DBUG_ASSERT(0); 1162 break; 1163 case LOG_TABLE|LOG_FILE: 1164 DBUG_ASSERT(0); 1165 break; 1166 } sql/log.cc 77/81

Slide 79

Slide 79 text

それを言い出すとmysqld_safeのエラーログこれもシェルスクリプトの中で各コマンドの標準出力と標準エラー出力をリダイレクトでファイルに向けているだけ mysqldのログがsyslogに吐けるようになったのは最近だけどmysqld_safeのログが昔っから吐けたのは単にloggerコマンドを使えば良かったから mysqldの方はログを書き込む関数を呼ばずにあちこちまちまちに書いてたのでそれを統合するところから始まったんだと思う ‐ そんなのもあってログ用の関数はメジャーバージョンのたびにガリガリ書き換わってるので、ストレージエンジンとかで真面目にそこを呼んでいるお行儀の良いやつはマクロで大変な気がする ‐ 78/81

Slide 80

Slide 80 text

おっと何の話だ 79/81

Slide 81

Slide 81 text

まとめエラーにはクライアントサイドエラーとサーバーサイドエラーがあるサーバーサイドエラーはものによって汎用的すぎるエラーがあったり、特化しすぎたエラーがあったりでグレッパビリティーが変わるエラーログ駆動でソースコードを読むのは楽しい See also, エラーログドリブンソースコードリーディング(ただし MySQLに限る) | GMOメディアエンジニアブログ ‐ 80/81

Slide 82

Slide 82 text

Any Questions and/or Suggestions? 81/81