github / github/gh-ost

The table cut failed and caused the slave library to block

Open
#1,233 2 comments 0 reactions 0 assignees View on GitHub
Dominant language
Go
Stars
13.6k
Forks
1.4k
Avg merge
2h 31m
Merged PRs (30d)
4

Description

Excuse me, I would like to ask a question.
When using gh-ost to execute ddl yesterday, an error occurred in the main library, which caused the table to fail to switch successfully. But there are two errors, and I am not sure which error caused the table to fail to switch successfully.

2022/12/19 16:33:11 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 root false false }
2022/12/19 16:33:11 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000196, 1004574218)
2022/12/19 16:33:11 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:33:11 binlogsyncer.go:573: [info] rotate to (mysql-bin.000196, 1004574218)
Migrating `customer3`.`customer_company`; Ghost table is `customer3`.`_customer_company_gho`
Migrating rdsdbn3-sirius-customer-online:3306; inspecting rdsdbn3-sirius-customer-online:3306; executing on rdsdbn3-sirius-customer-online
Migration started at Mon Dec 19 16:33:11 +0800 2022
chunk-size: 1000; max-lag-millis: 1500ms; dml-batch-size: 10; max-load: Threads_running=100; critical-load: Threads_running=500; nice-ratio: 0.000000
throttle-additional-flag-file: /tmp/gh-ost.throttle
Serving on unix socket: /tmp/gh-ost.customer3.customer_company.sock
Serving on TCP port: 13316
Copy: 0/821093 0.0%; Applied: 0; Backlog: 0/1000; Time: 0s(total), 0s(copy); streamer: mysql-bin.000196:1004589539; State: migrating; ETA: N/A
Copy: 0/821093 0.0%; Applied: 0; Backlog: 0/1000; Time: 1s(total), 1s(copy); streamer: mysql-bin.000196:1004965536; State: migrating; ETA: N/A
Copy: 9000/821093 1.1%; Applied: 0; Backlog: 0/1000; Time: 2s(total), 2s(copy); streamer: mysql-bin.000196:1007404907; State: migrating; ETA: 3m0s
Copy: 18000/821093 2.2%; Applied: 0; Backlog: 0/1000; Time: 3s(total), 3s(copy); streamer: mysql-bin.000196:1010115047; State: migrating; ETA: 2m13s
...
...
...
2022/12/19 16:35:14 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:35:17 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:17 ERROR 2022-12-19 16:35:17 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:19 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:19 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:19 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:19 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:35:21 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m10s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:35:21 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:21 ERROR 2022-12-19 16:35:21 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:26 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:26 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:26 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:26 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m15s(total), 2m0s(copy); streamer: mysql-bin.000197:3096692; State: migrating; ETA: due
2022-12-19 16:35:27 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:27 ERROR 2022-12-19 16:35:27 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:28 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m20s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:35:31 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:31 ERROR 2022-12-19 16:35:31 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:33 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:33 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:33 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:33 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:35:35 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m25s(total), 2m0s(copy); streamer: mysql-bin.000197:21536965; State: migrating; ETA: due
2022-12-19 16:35:37 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:37 ERROR 2022-12-19 16:35:37 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:40 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:40 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:40 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:40 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m30s(total), 2m0s(copy); streamer: mysql-bin.000197:10420122; State: migrating; ETA: due
2022/12/19 16:35:43 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:35:43 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:43 ERROR 2022-12-19 16:35:43 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:35:48 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:48 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:48 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:48 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:35:50 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:50 ERROR 2022-12-19 16:35:50 ERROR Timeout while waiting for events up to lock
2022/12/19 16:35:50 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m40s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:35:55 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:35:55 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:35:55 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:35:55 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:35:56 ERROR Timeout while waiting for events up to lock
2022-12-19 16:35:56 ERROR 2022-12-19 16:35:56 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m45s(total), 2m0s(copy); streamer: mysql-bin.000197:9353085; State: migrating; ETA: due
2022/12/19 16:35:58 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m50s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:36:02 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:02 ERROR 2022-12-19 16:36:02 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:03 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:03 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:03 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:03 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:36:05 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 2m55s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:36:08 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:08 ERROR 2022-12-19 16:36:08 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:10 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:10 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:10 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:10 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m0s(total), 2m0s(copy); streamer: mysql-bin.000197:17516759; State: migrating; ETA: due
2022/12/19 16:36:12 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:36:12 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:12 ERROR 2022-12-19 16:36:12 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:16 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:16 ERROR 2022-12-19 16:36:16 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21608867; State: migrating; ETA: due
2022/12/19 16:36:17 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:17 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:17 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:17 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:36:18 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m10s(total), 2m0s(copy); streamer: mysql-bin.000197:21584851; State: migrating; ETA: due
2022-12-19 16:36:22 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:22 ERROR 2022-12-19 16:36:22 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:23 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:23 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:23 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:23 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:36:25 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:36:26 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:26 ERROR 2022-12-19 16:36:26 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m15s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:36:30 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:30 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:30 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:30 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m20s(total), 2m0s(copy); streamer: mysql-bin.000197:12833146; State: migrating; ETA: due
2022/12/19 16:36:32 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:36:32 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:32 ERROR 2022-12-19 16:36:32 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m25s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:36:37 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:37 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:37 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:37 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:36:38 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:38 ERROR 2022-12-19 16:36:38 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:38 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m30s(total), 2m0s(copy); streamer: mysql-bin.000197:21569140; State: migrating; ETA: due
2022/12/19 16:36:43 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:43 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:43 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:43 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:36:44 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:44 ERROR 2022-12-19 16:36:44 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:45 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:36:50 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:50 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:50 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:50 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:36:51 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:51 ERROR 2022-12-19 16:36:51 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m40s(total), 2m0s(copy); streamer: mysql-bin.000197:18875201; State: migrating; ETA: due
2022/12/19 16:36:51 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m45s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:36:56 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:36:56 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:36:56 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:36:56 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:36:57 ERROR Timeout while waiting for events up to lock
2022-12-19 16:36:57 ERROR 2022-12-19 16:36:57 ERROR Timeout while waiting for events up to lock
2022/12/19 16:36:58 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m50s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:37:03 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:03 ERROR 2022-12-19 16:37:03 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:03 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:03 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:03 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:03 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:37:05 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 3m55s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:37:09 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:09 ERROR 2022-12-19 16:37:09 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:10 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:10 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:10 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:10 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m0s(total), 2m0s(copy); streamer: mysql-bin.000197:15550736; State: migrating; ETA: due
2022/12/19 16:37:12 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:37:16 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:16 ERROR 2022-12-19 16:37:16 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:37:17 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:17 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:17 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:17 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:37:18 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m10s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:37:22 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:22 ERROR 2022-12-19 16:37:22 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:23 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:23 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:23 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:23 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:37:25 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m15s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:37:28 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:28 ERROR 2022-12-19 16:37:28 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:30 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:30 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:30 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:30 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m20s(total), 2m0s(copy); streamer: mysql-bin.000197:8319580; State: migrating; ETA: due
2022/12/19 16:37:32 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:37:34 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:34 ERROR 2022-12-19 16:37:34 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m25s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:37:37 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:37 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:37 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:37 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:37:39 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:37:40 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:40 ERROR 2022-12-19 16:37:40 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m30s(total), 2m0s(copy); streamer: mysql-bin.000197:21624860; State: migrating; ETA: due
2022/12/19 16:37:44 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:44 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:44 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:44 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:37:46 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21584851; State: migrating; ETA: due
2022-12-19 16:37:47 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:47 ERROR 2022-12-19 16:37:47 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:51 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:51 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:51 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:51 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m40s(total), 2m0s(copy); streamer: mysql-bin.000197:3346793; State: migrating; ETA: due
2022-12-19 16:37:53 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:53 ERROR 2022-12-19 16:37:53 ERROR Timeout while waiting for events up to lock
2022/12/19 16:37:53 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m45s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:37:58 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:37:58 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:37:58 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:37:58 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:37:59 ERROR Timeout while waiting for events up to lock
2022-12-19 16:37:59 ERROR 2022-12-19 16:37:59 ERROR Timeout while waiting for events up to lock
2022/12/19 16:38:00 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m50s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:38:05 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:05 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:05 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:05 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:38:05 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:05 ERROR 2022-12-19 16:38:05 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 4m55s(total), 2m0s(copy); streamer: mysql-bin.000197:8519807; State: migrating; ETA: due
2022/12/19 16:38:07 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m0s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:38:12 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:12 ERROR 2022-12-19 16:38:12 ERROR Timeout while waiting for events up to lock
2022/12/19 16:38:12 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:12 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:12 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:12 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:38:16 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:38:18 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:18 ERROR 2022-12-19 16:38:18 ERROR Timeout while waiting for events up to lock
2022/12/19 16:38:21 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:21 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:21 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:21 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m10s(total), 2m0s(copy); streamer: mysql-bin.000197:2841618; State: migrating; ETA: due
2022/12/19 16:38:24 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:38:25 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:25 ERROR 2022-12-19 16:38:25 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m15s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:38:29 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:29 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:29 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:29 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:38:31 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:38:31 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:31 ERROR 2022-12-19 16:38:31 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m20s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:38:36 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:36 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:36 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:36 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m25s(total), 2m0s(copy); streamer: mysql-bin.000197:4548675; State: migrating; ETA: due
2022-12-19 16:38:37 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:37 ERROR 2022-12-19 16:38:37 ERROR Timeout while waiting for events up to lock
2022/12/19 16:38:37 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m30s(total), 2m0s(copy); streamer: mysql-bin.000197:21569140; State: migrating; ETA: due
2022/12/19 16:38:42 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:42 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:42 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:42 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:38:43 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:43 ERROR 2022-12-19 16:38:43 ERROR Timeout while waiting for events up to lock
2022/12/19 16:38:44 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21561394; State: migrating; ETA: due
2022/12/19 16:38:49 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:49 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:49 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:49 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:38:49 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:49 ERROR 2022-12-19 16:38:49 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m40s(total), 2m0s(copy); streamer: mysql-bin.000197:20796315; State: migrating; ETA: due
2022/12/19 16:38:51 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:38:55 ERROR Timeout while waiting for events up to lock
2022-12-19 16:38:55 ERROR 2022-12-19 16:38:55 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m45s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:38:56 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:38:56 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:38:56 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:38:56 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:38:58 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m50s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:39:02 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:02 ERROR 2022-12-19 16:39:02 ERROR Timeout while waiting for events up to lock
2022/12/19 16:39:03 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:03 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:03 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:03 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:39:05 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 5m55s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:39:08 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:08 ERROR 2022-12-19 16:39:08 ERROR Timeout while waiting for events up to lock
2022/12/19 16:39:10 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:10 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:10 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:10 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m0s(total), 2m0s(copy); streamer: mysql-bin.000197:15982497; State: migrating; ETA: due
2022/12/19 16:39:12 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:39:14 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:14 ERROR 2022-12-19 16:39:14 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21616782; State: migrating; ETA: due
2022/12/19 16:39:17 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:17 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:17 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:17 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:39:19 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:39:20 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:20 ERROR 2022-12-19 16:39:20 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m10s(total), 2m0s(copy); streamer: mysql-bin.000197:21569140; State: migrating; ETA: due
2022/12/19 16:39:24 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:24 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:24 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:24 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:39:25 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:39:26 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:26 ERROR 2022-12-19 16:39:26 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m15s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:39:30 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:30 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:30 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:30 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m20s(total), 2m0s(copy); streamer: mysql-bin.000197:15536690; State: migrating; ETA: due
2022/12/19 16:39:32 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:39:32 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:32 ERROR 2022-12-19 16:39:32 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m25s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:39:37 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:37 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:37 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:37 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:39:38 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:38 ERROR 2022-12-19 16:39:38 ERROR Timeout while waiting for events up to lock
2022/12/19 16:39:38 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m30s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:39:43 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:43 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:43 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:43 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:39:45 ERROR Error 1205: Lock wait timeout exceeded; try restarting transaction
2022-12-19 16:39:45 ERROR Error 1205: Lock wait timeout exceeded; try restarting transaction
2022/12/19 16:39:45 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:39:50 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:50 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:50 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:50 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m40s(total), 2m0s(copy); streamer: mysql-bin.000197:7727416; State: migrating; ETA: due
2022/12/19 16:39:52 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:39:53 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:53 ERROR 2022-12-19 16:39:53 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m45s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:39:57 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:39:57 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:39:57 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:39:57 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:39:59 ERROR Timeout while waiting for events up to lock
2022-12-19 16:39:59 ERROR 2022-12-19 16:39:59 ERROR Timeout while waiting for events up to lock
2022/12/19 16:39:59 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m50s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:40:04 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:04 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:04 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:04 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:40:05 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:05 ERROR 2022-12-19 16:40:05 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 6m55s(total), 2m0s(copy); streamer: mysql-bin.000197:19093954; State: migrating; ETA: due
2022/12/19 16:40:07 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:40:11 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:11 ERROR 2022-12-19 16:40:11 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m0s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:40:12 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:12 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:12 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:12 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:40:13 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m5s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:40:17 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:17 ERROR 2022-12-19 16:40:17 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:18 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:18 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:18 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:18 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022/12/19 16:40:20 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m10s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:40:23 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:23 ERROR 2022-12-19 16:40:23 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:25 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:25 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:25 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:25 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m15s(total), 2m0s(copy); streamer: mysql-bin.000197:6506231; State: migrating; ETA: due
2022-12-19 16:40:27 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:27 ERROR 2022-12-19 16:40:27 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:29 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m20s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:40:31 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:31 ERROR 2022-12-19 16:40:31 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:34 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:34 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:34 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:34 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:40:35 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:35 ERROR 2022-12-19 16:40:35 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:36 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m25s(total), 2m0s(copy); streamer: mysql-bin.000197:21553308; State: migrating; ETA: due
2022-12-19 16:40:39 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:39 ERROR 2022-12-19 16:40:39 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:41 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:41 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:41 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:41 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m30s(total), 2m0s(copy); streamer: mysql-bin.000197:8687216; State: migrating; ETA: due
2022/12/19 16:40:42 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
2022-12-19 16:40:43 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:43 ERROR 2022-12-19 16:40:43 ERROR Timeout while waiting for events up to lock
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m35s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022/12/19 16:40:47 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:47 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:47 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:47 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:40:47 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:47 ERROR 2022-12-19 16:40:47 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:49 binlogstreamer.go:47: [error] close sync with err: data len 310 < expected 2179
Copy: 769329/769329 100.0%; Applied: 0; Backlog: 0/1000; Time: 7m40s(total), 2m0s(copy); streamer: mysql-bin.000197:21632791; State: migrating; ETA: due
2022-12-19 16:40:51 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:51 ERROR 2022-12-19 16:40:51 ERROR Timeout while waiting for events up to lock
2022/12/19 16:40:54 binlogsyncer.go:79: [info] create BinlogSyncer with config {99999 mysql localhost 3306 rdsadmin false false }
2022/12/19 16:40:54 binlogsyncer.go:246: [info] begin to sync binlog from position (mysql-bin.000197, 4)
2022/12/19 16:40:54 binlogsyncer.go:139: [info] register slave for master server localhost:3306
2022/12/19 16:40:54 binlogsyncer.go:573: [info] rotate to (mysql-bin.000197, 4)
2022-12-19 16:40:55 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:55 ERROR 2022-12-19 16:40:55 ERROR Timeout while waiting for events up to lock
2022-12-19 16:40:56 FATAL 2022-12-19 16:40:55 ERROR Timeout while waiting for events up to lock

There are two errors here:
1、 [error] close sync with err: data len 310 < expected 2179
_The trigger reason for this error is not detailed,i don't know what caused it._

2、 ERROR Timeout while waiting for events up to lock
_Here I think that due to the existence of large transactions, multiple acquisitions of metadata locks failed, resulting in timeouts for acquiring metadata locks._

In addition, the execution failure on the main library will also cause the replication of the slave library to be stuck, and the drop table and multi-threaded replication will cause lock conflicts.
![image](https://user-images.githubusercontent.com/8543659/208568600-8be8389d-4702-4084-9490-afebfbcab0cf.png)

Contributor guide

Open the contributing guide

Assessment

This issue has not been assessed yet.

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.