mysql:11329
From: Ryuji Matsushita <Ryuji Matsushita <maturyu@xxxxxxxxxx>>
Date: Fri, 15 Apr 2005 15:32:28 +0900
Subject: [mysql 11329] Re: レプリケーションについて
松下です インラインにてコメントさせて頂きます。 正常にシャットダウンが出来ていなくてリカバリー作業中に書込みが発生してバイナリログの不整合 が発生しているのではないでしょうか? マスター側スレーブ側ともにクリーンシャットダウンが出来ていません コマンドが間違っていませんか? 正常にシャットダウンした場合は下記のログが出ます。 > 050415 2:10:01 InnoDB: Starting shutdown... > 050415 2:10:04 InnoDB: Shutdown completed > 050415 2:10:04 /usr/sbin/mysqld: Shutdown Complete 私なら マスター側をクリーンシャットダウンし OSも一度再起動します。 ディスクスペース等OS側の環境をチェックし DB再起動 バイナリーログがちゃんととれていることを確認 マスター側のスナップショットを取直す をします。 高トランザクション系のDBでしたらログから追っかけるのはしんどいでしょうから 再度スナップショットをとってレプリケーションを張直した方が良い感じがします。 もう少し切り分けるなら マスター側で SQL>SHOW MASTER STATUS; スレーブ側で SQL>SHOW SLAVE STATUS; で表示されるメッセージを読んでみてはいかがでしょうか? > > > マスターは正常にシャットダウンさせました。 ↑シャットダウンのログが無いけど… > > レプリケーションできなくなった経緯は以下の通りです。 > > 21:05 マスターを再起動 > 以下はマスターのログです。 > 050414 21:05:57 mysqld started > 050414 21:05:57 InnoDB: Database was not shut down normally. ↑正常にシャットダウン出来ていないとのメッセージ > InnoDB: Starting recovery from log files... > InnoDB: Starting log scan based on checkpoint at ↑リカバリーを使って起動をしている > InnoDB: log sequence number 0 3111453 > InnoDB: Doing recovery: scanned up to log sequence number 0 3111453 > InnoDB: Last MySQL binlog file position 0 628221, file name ./マスターのホス > ト名-bin.003 > 050414 21:05:58 InnoDB: Flushing modified pages from the buffer pool... > 050414 21:05:58 InnoDB: Started > 050414 21:05:58 Could not use マスターのホスト名-bin for logging (error 13). > Turning logging off for the whole duration of th ↑バイナリーログが追いついていない > e MySQL server process. To turn it on again: fix the cause, shutdown the > MySQL server and restart it. > /usr/local/mysql/libexec/mysqld: ready for connections. > Version: '4.0.24-max-log' socket: '/tmp/mysql.sock' port: 3306 Source > distribution ↑もう一度クリーンシャットダウンしてくださいって > > 21:11 この時点でレプリケーションできなくなっていた。 > 仕方がなかったのでこんどはスレーブのmysqlを再起動 > 以下はスレーブのログです。 > 050414 21:11:49 mysqld started > 050414 21:11:49 InnoDB: Database was not shut down normally. > InnoDB: Starting recovery from log files... > InnoDB: Starting log scan based on checkpoint at > InnoDB: log sequence number 0 3173059 > InnoDB: Doing recovery: scanned up to log sequence number 0 3173059 > InnoDB: In a MySQL replication slave the last master binlog file > InnoDB: position 0 628159, file name ichthyosaurus-bin.003 > 050414 21:11:50 InnoDB: Flushing modified pages from the buffer pool... > 050414 21:11:50 InnoDB: Started > 050414 21:11:50 Slave SQL thread initialized, starting replication in log ' > マスターのホスト名-bin.003' at position 628221, re > lay log './スレーブのホスト名-relay-bin.004' position: 628317 > 050414 21:11:50 Slave I/O thread: connected to master > 'repl@xxxxxxxxxx:3306', replication started in log 'マスターのホスト > 名-bin.003' at position 628221 > 050414 21:11:50 Error reading packet from server: Binary log is not open > (server_errno=1236) > 050414 21:11:50 Got fatal error 1236: 'Binary log is not open' from master > when reading data from binary log > 050414 21:11:50 Slave I/O thread exiting, read up to log 'マスターのホスト > 名-bin.003', position 628221 > /usr/local/mysql/libexec/mysqld: ready for connections. > Version: '4.0.24-max-log' socket: '/tmp/mysql.sock' port: 3306 Source > distribution > > 簡単に書くとこのような感じです。 > 助言おねがいします。 > > 以上です。 > > ----- Original Message ----- > From: "Ryuji Matsushita" <maturyu@xxxxxxxxxx> > To: <ml@xxxxxxxxxx> > Sent: Friday, April 15, 2005 9:34 AM > Subject: [mysql 11326] Re: レプリケーションについて > > > > 松下です > > > > もう少し状況をお知らせください。 > > 下記のログはスレーブ側のログですよね。 > > マスター側のログはどのようになっていますか? > > マスターがダウンとは正常にシャットダウンをした場合でしょうか? > > ダウン時の挙動をマスター側、スレーブ側で時系列に整理してください。 > > > > マスター側のバイナリーログがOPEN出来ないようですが > > DBの設定とは異なるところに原因があるのではないでしょうか? > > > > 私のところでマスター側を再起動した時のログを貼付けます > > > > > > > 050415 9:25:42 Slave: received 0 length packet from server, apparent > master shutdown: > > ↑ここでマスター側をシャットダウン > > > > > 050415 9:25:42 Slave I/O thread: Failed reading log event, > reconnecting to retry, log 'ホスト名-bin.001' position XXXXXXX > > ↑当然バイナリーログが読めない > > > > > 050415 9:25:42 Slave I/O thread: error reconnecting to master 'ユー > ザー名@マスター側ホスト名:3306': Error: 'Lost connection to MySQL server > during query' errno: 2013 retry-time: 60 retries: 86400 > > ↑スレーブ側で設定したマスタ側へのI/Oのユーザーが接続できない。再接続待 > ち。 > > > > > 050415 9:26:42 Slave: connected to master 'ユーザー名@マスター側ホスト > 名:3306',replication resumed in log 'マスター側ホスト名-bin.001' at position > XXXXXXXX > > ↑再接続完了 > > > > > > > > > 050414 22:49:01 Error reading packet from server: Binary log is not open > > > (server_errno=1236) > > > 050414 22:49:01 Got fatal error 1236: 'Binary log is not open' from > master > > > when reading data from binary log > > > 050414 22:49:01 Slave I/O thread exiting, read up to log 'ホスト > 名-bin.003', > > > position 628221 > > > /usr/local/mysql/libexec/mysqld: ready for connections. > > > Version: '4.0.24-max-log' socket: '/tmp/mysql.sock' port: 3306 Source > > > distribution > > > > +++++++++++++++++++++++++++++++++++++++++++++++++++ > > Sony Digital Network Applications Inc.(SDNA) > > Navigation Track Information System > > 松下 龍士(Ryuji Matsushita) maturyu@xxxxxxxxxx > > +++++++++++++++++++++++++++++++++++++++++++++++++++ > > > > > > +++++++++++++++++++++++++++++++++++++++++++++++++++ Sony Digital Network Applications Inc.(SDNA) Navigation Track Information System 松下 龍士(Ryuji Matsushita) maturyu@xxxxxxxxxx +++++++++++++++++++++++++++++++++++++++++++++++++++
11324 2005-04-14 22:59 [柴垣 大樹 <sibagaki] レプリケーションについて 11326 2005-04-15 09:34 ┗[Ryuji Matsushita <ma] 11328 2005-04-15 14:12 ┗[柴垣 大樹 <sibagaki] -> 11329 2005-04-15 15:32 ┗[Ryuji Matsushita <ma] 11330 2005-04-15 16:22 ┗[柴垣 大樹 <sibagaki] 11331 2005-04-15 17:25 ┗[Ryuji Matsushita <ma] 11333 2005-04-18 14:08 ┗[柴垣 大樹 <sibagaki]