env:
pika 3.4.0 Oct 12 master branch
centos 7.8
master log pika.INFO
I1124 15:38:02.599153 13796 pika_server.cc:1186] Partition: db0 RSync Send Files Success
I1124 15:38:02.987164 23083 pika_repl_server_conn.cc:108] Receive Trysync, Slave ip: xx.xx.xx.245, Slave port:9221, Partition: db0, filenum: 108128, pro_offset: 16589588
I1124 15:38:02.987440 23083 pika_repl_server_conn.cc:192] Partition: db0 TrySync Success, Session: 224
W1124 15:41:49.478492 23082 pika_slave_node.cc:32] Ack offset Start: filenum: 108128 offset: 104856077 term: 0 index: 42319875412 binglog size: 0 acked: 0End: filenum: 108128 offset: 104862926 term: 0 index: 660307 binglog size: 0 acked: 0 not found in binlog controller window.
window status
Size: 53
Begin_item: filenum: 108128 offset: 104856077 term: 0 index: 42319875412 binglog size: 94 acked: 0
End_item: filenum: 108129 offset: 5198 term: 0 index: 42319875464 binglog size: 118 acked: 0
W1124 15:41:49.478677 23082 pika_rm.cc:203] (db0:0)Corruption: UpdateAckedInfo failed
W1124 15:41:49.478691 23082 pika_repl_server_conn.cc:483] Update binlog ack failed db0 0 Corruption: UpdateAckedInfo failed
I1124 15:41:49.478791 23081 pika_repl_server_thread.cc:29] ServerThread Close Slave Conn, fd: 1864, ip_port: xx.xx.xx.245:41037
I1124 15:41:49.478897 23081 pika_server.cc:740] Delete Slave Success, ip_port: xx.xx.xx.245:9221
I1124 15:41:49.478926 23081 pika_rm.cc:90] Remove Slave Node, Partition: (db0:0), ip_port: xx.xx.xx.245:9221
I1124 15:41:54.239161 23137 pika_stable_log.cc:151] ./log/log_db0/ Success purge 1 binlog file
I1124 15:41:59.032140 23083 pika_repl_server_conn.cc:42] Receive MetaSync, Slave ip: xx.xx.xx.245, Slave port:9221
I1124 15:41:59.032279 23083 pika_server.cc:843] Add New Slave, xx.xx.xx.245:9221
I1124 15:41:59.172390 23084 pika_repl_server_conn.cc:108] Receive Trysync, Slave ip: xx.xx.xx.245, Slave port:9221, Partition: db0, filenum: 108128, pro_offset: 104862926
I1124 15:41:59.172577 23084 pika_rm.cc:79] Add Slave Node, partition: (db0:0), ip_port: xx.xx.xx.245:9221
I1124 15:41:59.172596 23084 pika_repl_server_conn.cc:181] Partition: db0 TrySync Success, Session: 225
W1124 15:41:59.214401 23083 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=225 binlog reader init failed
I1124 15:41:59.214589 23081 pika_repl_server_thread.cc:29] ServerThread Close Slave Conn, fd: 816, ip_port: xx.xx.xx.245:41109
master side log, pika.WARNING
W1124 15:41:49.478492 23082 pika_slave_node.cc:32] Ack offset Start: filenum: 108128 offset: 104856077 term: 0 index: 42319875412 binglog size: 0 acked: 0End: filenum: 108128 offset: 104862926 term: 0 index: 660307 binglog size: 0 acked: 0 not found in binlog controller window.
window status
Size: 53
Begin_item: filenum: 108128 offset: 104856077 term: 0 index: 42319875412 binglog size: 94 acked: 0
End_item: filenum: 108129 offset: 5198 term: 0 index: 42319875464 binglog size: 118 acked: 0
W1124 15:41:49.478677 23082 pika_rm.cc:203] (db0:0)Corruption: UpdateAckedInfo failed
W1124 15:41:49.478691 23082 pika_repl_server_conn.cc:483] Update binlog ack failed db0 0 Corruption: UpdateAckedInfo failed
W1124 15:41:59.214401 23083 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=225 binlog reader init failed
W1124 15:42:09.223461 23082 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=226 binlog reader init failed
E1124 15:42:11.377209 23102 pika_client_conn.cc:160] ip_port: xx.xx.xx.248:41503, table: db0, command: "INFO", command_size: 4, arguments: 1, start_time(s): 1637739731, duration(us): 10893, do_duration_(us): 0
W1124 15:42:19.234411 23083 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=227 binlog reader init failed
W1124 15:42:29.245422 23082 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=228 binlog reader init failed
E1124 15:42:35.230178 23105 pika_client_conn.cc:160] ip_port: xx.xx.xx.248:36931, table: db0, command: "info", command_size: 4, arguments: 1, start_time(s): 1637739755, duration(us): 10758, do_duration_(us): 0
W1124 15:42:39.256443 23083 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=229 binlog reader init failed
W1124 15:42:49.267314 23084 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=230 binlog reader init failed
E1124 15:42:51.380913 23105 pika_client_conn.cc:160] ip_port: 10.10.59.236:38280, table: db0, command: "INFO", command_size: 4, arguments: 1, start_time(s): 1637739771, duration(us): 10969, do_duration_(us): 0
W1124 15:42:59.279383 23082 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=231 binlog reader init failed
E1124 15:43:06.643637 23112 pika_client_conn.cc:160] ip_port: xx.xx.xx.248:36965, table: db0, command: "info", command_size: 4, arguments: 1, start_time(s): 1637739786, duration(us): 10818, do_duration_(us): 0
W1124 15:43:09.189357 23082 pika_repl_server_conn.cc:466] Activate Binlog Sync failed partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=0 Corruption: Init binlog file reader failedCorruption: partition=db0_0,ip_port=xx.xx.xx.245:9221,session id=232 binlog reader init failed
W1124 15:43:09.218698 23136 pika_rm.cc:780] send binlog to xx.xx.xx.245:9221 failed, NotFound: The xx.xx.xx.245:9221 fd cannot be found
W1124 15:43:09.219070 23136 pika_rm.cc:780] send binlog to xx.xx.xx.245:9221 failed, NotFound: The xx.xx.xx.245:9221 fd cannot be found
slave side log, pika.INFO
Log file created at: 2021/11/24 15:36:01
Running on machine: pika-03
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
I1124 15:36:01.708359 6538 pika.cc:193] Server at: ../conf/pika.conf
I1124 15:36:01.708884 6538 pika_server.cc:176] Using Networker Interface: eth0
I1124 15:36:01.709555 6538 pika_server.cc:219] host: xx.xx.xx.245 port: 9221
I1124 15:36:01.709585 6538 pika_server.cc:95] Worker queue limit is 1350
I1124 15:36:01.710130 6538 pika_binlog.cc:94] Binlog: Manifest file not exist, we create a new one.
I1124 15:36:02.767280 6538 pika_partition.cc:87] db0 DB Success
I1124 15:36:02.774467 6538 pika_server.cc:273] Pika Server going to start
I1124 15:36:02.774777 6668 pika_repl_client.cc:146] Try Send Meta Sync Request to Master (xx.xx.xx.248:9221)
I1124 15:36:02.775851 6556 pika_server.cc:618] Mark try connect finish
I1124 15:36:02.775871 6556 pika_repl_client_conn.cc:146] Finish to handle meta sync response
I1124 15:36:02.916692 6557 pika_repl_client_conn.cc:261] Partition: db0 Need To Try DBSync
I1124 15:36:03.016842 6559 pika_repl_client_conn.cc:182] Partition: db0 Need Wait To Sync
I1124 15:38:02.628144 6668 pika_partition.cc:236] Partition: db0 Information from dbsync info, master_ip: xx.xx.xx.248, master_port: 9221, filenum: 108128, offset: 16589588, term: 0, index: 0
I1124 15:38:02.628228 6668 pika_partition.cc:293] Partition: db0, Prepare change db from: ./db/db0_bak
I1124 15:38:02.875358 6668 pika_partition.cc:314] Partition: db0, Change db success
I1124 15:38:02.987746 6560 pika_repl_client_conn.cc:258] Partition: db0 TrySync Ok
I1124 15:41:49.478904 6555 pika_repl_client_thread.cc:21] ReplClient Close conn, fd=124, ip_port=xx.xx.xx.248:11221
W1124 15:41:49.478972 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
I1124 15:41:59.031689 6668 pika_repl_client.cc:146] Try Send Meta Sync Request to Master (xx.xx.xx.248:9221)
I1124 15:41:59.032572 6561 pika_server.cc:618] Mark try connect finish
I1124 15:41:59.032599 6561 pika_repl_client_conn.cc:146] Finish to handle meta sync response
I1124 15:41:59.172834 6562 pika_repl_client_conn.cc:258] Partition: db0 TrySync Ok
I1124 15:41:59.214607 6555 pika_repl_client_thread.cc:21] ReplClient Close conn, fd=688, ip_port=xx.xx.xx.248:11221
W1124 15:41:59.214651 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
slave side pika.WARNING
Log file created at: 2021/11/24 15:38:02
Running on machine: pika-03
Log line format: [IWEF]mmdd hh:mm:ss.uuuuuu threadid file:line] msg
W1124 15:41:49.478972 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
W1124 15:41:59.214651 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
W1124 15:42:09.223732 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
W1124 15:42:19.234695 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
W1124 15:42:29.245780 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
W1124 15:42:39.256779 6555 pika_repl_client_thread.cc:31] Master conn disconnect : xx.xx.xx.248:11221 try reconnect
我这里能稳定重现这个问题,请问有谁遇到过相似的问题吗?