vitess
vitess copied to clipboard
Published
20 hours ago •
vitessio
vitessio
Flaky TestBackupRestore
Overview of the Issue
2023-12-12T15:28:08.2806203Z --- FAIL: TestBackupRestore (0.04s)
2023-12-12T15:28:08.2806974Z vtctl_pipe.go:105: I1212 15:25:00.800937 backup.go:110] I1212 15:25:00.800673 backup.go:150] Starting backup 2023-12-12.152500.cell1-0000000001
2023-12-12T15:28:08.2808567Z vtctl_pipe.go:105: I1212 15:25:00.800966 backup.go:110] I1212 15:25:00.800702 builtinbackupengine.go:208] Executing Backup at 2023-12-12 15:25:00.800115983 +0000 UTC m=+0.060419668 for keyspace/shard test_keyspace/0 on tablet cell1-0000000001, concurrency: 4, compress: true, incrementalFromPos:
2023-12-12T15:28:08.2809242Z vtctl_pipe.go:105: I1212 15:25:00.800981 backup.go:110] I1212 15:25:00.800751 builtinbackupengine.go:390] getting current replication status
2023-12-12T15:28:08.2810003Z vtctl_pipe.go:105: I1212 15:25:00.800996 backup.go:110] I1212 15:25:00.800791 builtinbackupengine.go:445] using replication position: 2-123-457
2023-12-12T15:28:08.2810603Z vtctl_pipe.go:105: I1212 15:25:00.806541 backup.go:110] I1212 15:25:00.801007 builtinbackupengine.go:579] found 3 files to backup
2023-12-12T15:28:08.2811221Z vtctl_pipe.go:105: I1212 15:25:00.806566 backup.go:110] I1212 15:25:00.801124 builtinbackupengine.go:797] Backing up file: vt_db/db.opt
2023-12-12T15:28:08.2811858Z vtctl_pipe.go:105: I1212 15:25:00.806580 backup.go:110] I1212 15:25:00.801248 builtinbackupengine.go:797] Backing up file: innodb_data_1
2023-12-12T15:28:08.2812497Z vtctl_pipe.go:105: I1212 15:25:00.806596 backup.go:110] I1212 15:25:00.801282 compression.go:259] Compressing backup using engine "pargzip"
2023-12-12T15:28:08.2813138Z vtctl_pipe.go:105: I1212 15:25:00.806610 backup.go:110] I1212 15:25:00.801688 compression.go:259] Compressing backup using engine "pargzip"
2023-12-12T15:28:08.2813805Z vtctl_pipe.go:105: I1212 15:25:00.806622 backup.go:110] I1212 15:25:00.803138 builtinbackupengine.go:797] Backing up file: #innodb_redo/#ib_redo1
2023-12-12T15:28:08.2814444Z vtctl_pipe.go:105: I1212 15:25:00.806635 backup.go:110] I1212 15:25:00.805620 compression.go:259] Compressing backup using engine "pargzip"
2023-12-12T15:28:08.2815013Z vtctl_pipe.go:105: I1212 15:25:00.806649 backup.go:110] I1212 15:25:00.806049 builtinbackupengine.go:846] closing compressor
2023-12-12T15:28:08.2815672Z vtctl_pipe.go:105: I1212 15:25:00.806662 backup.go:110] I1212 15:25:00.806347 builtinbackupengine.go:846] closing compressor
2023-12-12T15:28:08.2816380Z vtctl_pipe.go:105: I1212 15:25:00.807548 backup.go:110] I1212 15:25:00.807085 builtinbackupengine.go:753] Done taking Backup "innodb_data_1"
2023-12-12T15:28:08.2816948Z vtctl_pipe.go:105: I1212 15:25:00.807828 backup.go:110] I1212 15:25:00.807170 builtinbackupengine.go:846] closing compressor
2023-12-12T15:28:08.2817596Z vtctl_pipe.go:105: I1212 15:25:00.807856 backup.go:110] I1212 15:25:00.807450 builtinbackupengine.go:753] Done taking Backup "vt_db/db.opt"
2023-12-12T15:28:08.2818377Z vtctl_pipe.go:105: I1212 15:25:00.810656 backup.go:110] I1212 15:25:00.808878 builtinbackupengine.go:488] resetting mysqld super_read_only to false
2023-12-12T15:28:08.2819002Z vtctl_pipe.go:105: I1212 15:25:00.810708 backup.go:110] I1212 15:25:00.808894 builtinbackupengine.go:505] restarting mysql replication
2023-12-12T15:28:08.2820026Z vtctl_pipe.go:105: E1212 15:25:00.810737 backup.go:110] E1212 15:25:00.809216 rpc_backup.go:145] Failed to set replication source, error: wrong query for ExecuteSuperQueryList: expected START SLAVE got FAKE SET MASTER
2023-12-12T15:28:08.2820173Z backup_test.go:213:
2023-12-12T15:28:08.2820774Z Error Trace: /home/runner/work/vitess/vitess/go/vt/wrangler/testlib/backup_test.go:213
2023-12-12T15:28:08.2821510Z /home/runner/work/vitess/vitess/go/vt/wrangler/testlib/backup_test.go:61
2023-12-12T15:28:08.2821729Z Error: Received unexpected error:
2023-12-12T15:28:08.2824302Z rpc error: code = Unknown desc = TabletManager.Backup on cell1-0000000001 error: cannot restart replica: wrong query for ExecuteSuperQueryList: expected FAKE SET MASTER got START SLAVE: cannot restart replica: wrong query for ExecuteSuperQueryList: expected FAKE SET MASTER got START SLAVE
2023-12-12T15:28:08.2824484Z Test: TestBackupRestore
