Skip to content

Instantly share code, notes, and snippets.

@josephglanville
Last active July 30, 2016 08:17
Show Gist options
  • Save josephglanville/4337240e648e77ce7020ebaa1fb8d6b0 to your computer and use it in GitHub Desktop.
Save josephglanville/4337240e648e77ce7020ebaa1fb8d6b0 to your computer and use it in GitHub Desktop.
MariaDB semi-sync hang

The files are prefixed with the server they originate from, either master or slave.

2016-07-30 7:51:38 140604322687744 [Note] Start binlog_dump to slave_server(1684287243), pos(, 4)
2016-07-30 7:51:38 140604322687744 [Note] Start semi-sync binlog_dump to slave (server_id: 1684287243), pos(, 4)
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx:insert_tranx_node: insert (mariadb-bin.000004, 1541) in entry(197)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait pos (mariadb-bin.000004, 1541), repl(1)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: init wait position (mariadb-bin.000004, 1541),
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait 18446744073709551615 ms for binlog sent (mariadb-bin.000004, 1541)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1393) sync(0), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 1541) in entry(197)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1541) sync(1), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Wait for replica's reply
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Got reply (mariadb-bin.000004, 1541)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::::clear_active_tranx_nodes: cleared all nodes
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: Got reply at (mariadb-bin.000004, 1541)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: signal all waiting threads.
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: Binlog reply is ahead (mariadb-bin.000004, 1541),
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 1541) in entry(197)
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx:insert_tranx_node: insert (mariadb-bin.000004, 2094) in entry(98)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait pos (mariadb-bin.000004, 2094), repl(1)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: init wait position (mariadb-bin.000004, 2094),
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait 18446744073709551615 ms for binlog sent (mariadb-bin.000004, 2094)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1579) sync(0), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2094) in entry(98)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2094) sync(1), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Wait for replica's reply
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Got reply (mariadb-bin.000004, 2094)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::::clear_active_tranx_nodes: cleared all nodes
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: Got reply at (mariadb-bin.000004, 2094)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: signal all waiting threads.
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: Binlog reply is ahead (mariadb-bin.000004, 2094),
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2094) in entry(98)
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx:insert_tranx_node: insert (mariadb-bin.000004, 2270) in entry(74)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait pos (mariadb-bin.000004, 2270), repl(1)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: init wait position (mariadb-bin.000004, 2270),
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait 18446744073709551615 ms for binlog sent (mariadb-bin.000004, 2270)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2132) sync(0), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2270) in entry(74)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2270) sync(1), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Wait for replica's reply
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Got reply (mariadb-bin.000004, 2270)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::::clear_active_tranx_nodes: cleared all nodes
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: Got reply at (mariadb-bin.000004, 2270)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: signal all waiting threads.
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: Binlog reply is ahead (mariadb-bin.000004, 2270),
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2270) in entry(74)
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx:insert_tranx_node: insert (mariadb-bin.000004, 1019424) in entry(71)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait pos (mariadb-bin.000004, 1019424), repl(1)
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: init wait position (mariadb-bin.000004, 1019424),
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait 18446744073709551615 ms for binlog sent (mariadb-bin.000004, 1019424)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2308) sync(0), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1019397) sync(0), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 1019424) in entry(71)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1019424) sync(1), repl(1)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Wait for replica's reply
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Got reply (mariadb-bin.000004, 1019424)
2016-07-30 8:01:26 140604322687744 [Note] ActiveTranx::::clear_active_tranx_nodes: cleared all nodes
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: Got reply at (mariadb-bin.000004, 1019424)
2016-07-30 8:01:26 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: signal all waiting threads.
2016-07-30 8:01:26 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: Binlog reply is ahead (mariadb-bin.000004, 1019424),
2016-07-30 8:01:26 140604322384640 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 1019424) in entry(71)
2016-07-30 8:01:27 140604322384640 [Note] ActiveTranx:insert_tranx_node: insert (mariadb-bin.000004, 2039896) in entry(71)
2016-07-30 8:01:27 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 1019462) in entry(123)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 1019462) sync(0), repl(1)
2016-07-30 8:01:27 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2039869) in entry(121)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2039869) sync(0), repl(1)
2016-07-30 8:01:27 140604322687744 [Note] ActiveTranx::is_tranx_end_pos: probe (mariadb-bin.000004, 2039896) in entry(71)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2039896) sync(1), repl(1)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Wait for replica's reply
2016-07-30 8:01:27 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait pos (mariadb-bin.000005, 2039896), repl(1)
2016-07-30 8:01:27 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: init wait position (mariadb-bin.000005, 2039896),
2016-07-30 8:01:27 140604322384640 [Note] ReplSemiSyncMaster::commitTrx: wait 18446744073709551615 ms for binlog sent (mariadb-bin.000005, 2039896)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::readSlaveReply: Got reply (mariadb-bin.000004, 2039896)
2016-07-30 8:01:27 140604322687744 [Note] ActiveTranx::::clear_active_tranx_nodes: cleared all nodes
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::reportReplyBinlog: Got reply at (mariadb-bin.000004, 2039896)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000004, 2039941) sync(0), repl(1)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000005, 288) sync(0), repl(1)
2016-07-30 8:01:27 140604322687744 [Note] ReplSemiSyncMaster::updateSyncHeader: server(1684287243), (mariadb-bin.000005, 329) sync(0), repl(1)
This file has been truncated, but you can view the full file.
View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

View raw

(Sorry about that, but we can’t show files that are this big right now.)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment