"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload)$'" - Starting (try 3/5) 2025/07/13 03:35:12 DEBUG : Creating backend with remote "TestPikPak:rclone-test-siruwoh1pigo" 2025/07/13 03:35:12 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/07/13 03:35:14 DEBUG : Creating backend with remote "/tmp/rclone3026040970" === RUN TestCheckSumDownload run.go:180: Remote "PikPak root 'rclone-test-siruwoh1pigo'", Local "Local file system at /tmp/rclone3026040970", Modify Window "876000h0m0s" 2025/07/13 03:35:14 DEBUG : Creating backend with remote "TestPikPak:rclone-test-siruwoh1pigo/data" 2025/07/13 03:35:17 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/07/13 03:35:17 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/07/13 03:35:19 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:35:19 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 === RUN TestCheckSumDownload/subtest1 2025/07/13 03:35:38 DEBUG : pacer: Reducing sleep to 100ms 2025/07/13 03:35:38 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/07/13 03:35:38 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/07/13 03:35:40 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:35:40 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 === RUN TestCheckSumDownload/subtest2 2025/07/13 03:36:47 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/07/13 03:36:47 DEBUG : pacer: Reducing sleep to 843.75ms 2025/07/13 03:36:47 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:36:47 DEBUG : test.sum: gcid = f3a0ff69df1baee079fa197e53142fe40231e82b 2025/07/13 03:36:49 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/07/13 03:36:49 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/07/13 03:36:50 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/07/13 03:36:50 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/07/13 03:36:51 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/07/13 03:36:51 DEBUG : pacer: Reducing sleep to 150.169371ms === RUN TestCheckSumDownload/subtest3 === NAME TestCheckSumDownload check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 0 actual : 1 Test: TestCheckSumDownload Messages: wrong error count in run 3 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "= banana", "= potato"} actual : []string{"", "! potato", "= banana"} Diff: --- Expected +++ Actual @@ -2,4 +2,4 @@ (string) "", - (string) (len=8) "= banana", - (string) (len=8) "= potato" + (string) (len=8) "! potato", + (string) (len=8) "= banana" } Test: TestCheckSumDownload Messages: wrong combined result in run 3 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana", "potato"} actual : []string{"", "banana"} Diff: --- Expected +++ Actual @@ -1,5 +1,4 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=6) "banana", - (string) (len=6) "potato" + (string) (len=6) "banana" } Test: TestCheckSumDownload Messages: wrong match result in run 3 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:422 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{} actual : []string{"", "potato"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) "", + (string) (len=6) "potato" } Test: TestCheckSumDownload Messages: wrong error result in run 3 2025/07/13 03:38:28 DEBUG : pacer: Reducing sleep to 760.232439ms 2025/07/13 03:38:28 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:38:28 DEBUG : test.sum: gcid = 4432a75a15f9e866fc8b0361de0580e4ab920bea 2025/07/13 03:38:30 DEBUG : pacer: Reducing sleep to 570.174329ms 2025/07/13 03:38:30 DEBUG : pacer: Reducing sleep to 427.630746ms 2025/07/13 03:38:31 DEBUG : pacer: Reducing sleep to 320.723059ms 2025/07/13 03:38:31 DEBUG : pacer: Reducing sleep to 240.542294ms 2025/07/13 03:38:31 DEBUG : pacer: Reducing sleep to 180.40672ms 2025/07/13 03:38:32 DEBUG : pacer: Reducing sleep to 135.30504ms === RUN TestCheckSumDownload/subtest4 2025/07/13 03:39:10 DEBUG : pacer: Reducing sleep to 100ms 2025/07/13 03:39:10 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:39:10 DEBUG : test.sum: gcid = c2c6d29ce2f4e1f6e3e830802ef4a15c32af89c4 === RUN TestCheckSumDownload/subtest5 2025/07/13 03:40:00 DEBUG : pacer: Reducing sleep to 168.75ms 2025/07/13 03:40:00 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:40:00 DEBUG : test.sum: gcid = 903d92580da389428c3eace6c6dce692463e75e8 2025/07/13 03:40:01 DEBUG : pacer: Reducing sleep to 126.5625ms 2025/07/13 03:40:01 DEBUG : pacer: Reducing sleep to 100ms === RUN TestCheckSumDownload/subtest6 2025/07/13 03:41:08 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/07/13 03:41:08 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/07/13 03:41:11 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/07/13 03:41:11 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/07/13 03:41:15 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/07/13 03:41:15 DEBUG : test.sum: gcid = 551b7ddca65803810ea668343a66646180a0c0b7 === RUN TestCheckSumDownload/subtest7 2025/07/13 03:42:09 DEBUG : pacer: Reducing sleep to 337.5ms 2025/07/13 03:42:09 DEBUG : pacer: Reducing sleep to 253.125ms 2025/07/13 03:42:10 DEBUG : pacer: Reducing sleep to 189.84375ms 2025/07/13 03:42:10 DEBUG : pacer: Reducing sleep to 142.382812ms 2025/07/13 03:42:10 DEBUG : pacer: Reducing sleep to 106.787109ms 2025/07/13 03:42:11 DEBUG : pacer: Reducing sleep to 100ms 2025/07/13 03:42:13 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/07/13 03:42:13 DEBUG : data: Rmdir: contains trashed file: "banana" 2025/07/13 03:42:13 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/07/13 03:42:13 DEBUG : data: Rmdir: contains trashed file: "banana" --- FAIL: TestCheckSumDownload (420.20s) --- PASS: TestCheckSumDownload/subtest1 (17.12s) --- PASS: TestCheckSumDownload/subtest2 (62.49s) --- PASS: TestCheckSumDownload/subtest3 (96.16s) --- PASS: TestCheckSumDownload/subtest4 (38.24s) --- PASS: TestCheckSumDownload/subtest5 (45.53s) --- PASS: TestCheckSumDownload/subtest6 (63.79s) --- PASS: TestCheckSumDownload/subtest7 (50.30s) === RUN TestCat run.go:180: Remote "PikPak root 'rclone-test-siruwoh1pigo'", Local "Local file system at /tmp/rclone3026040970", Modify Window "876000h0m0s" 2025/07/13 03:42:15 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/07/13 03:42:15 DEBUG : file1: gcid = 4d8356ed1af6fd7e9537777328c6d176effe38a0 2025/07/13 03:42:16 DEBUG : file2: calculating gcid: failed to unwrap object from src: file2 2025/07/13 03:42:16 DEBUG : file2: gcid = 9e3614f5e363b3d1060c1e4d6738e7c26804ae41 2025/07/13 03:42:27 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:42:27 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/07/13 03:42:33 DEBUG : pacer: low level retry 2/10 (error no link) 2025/07/13 03:42:33 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/07/13 03:42:40 DEBUG : pacer: low level retry 3/10 (error no link) 2025/07/13 03:42:40 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/07/13 03:42:46 DEBUG : pacer: low level retry 4/10 (error no link) 2025/07/13 03:42:46 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/07/13 03:42:47 DEBUG : pacer: Reducing sleep to 1.2s 2025/07/13 03:42:49 DEBUG : pacer: Reducing sleep to 900ms 2025/07/13 03:42:49 DEBUG : pacer: Reducing sleep to 675ms 2025/07/13 03:42:56 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:42:56 DEBUG : pacer: Rate limited, increasing sleep to 1.35s 2025/07/13 03:43:02 DEBUG : pacer: low level retry 2/10 (error no link) 2025/07/13 03:43:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/07/13 03:43:09 DEBUG : pacer: low level retry 3/10 (error no link) 2025/07/13 03:43:15 DEBUG : pacer: low level retry 4/10 (error no link) 2025/07/13 03:43:21 DEBUG : pacer: low level retry 5/10 (error no link) 2025/07/13 03:43:22 DEBUG : pacer: Reducing sleep to 1.5s 2025/07/13 03:43:24 DEBUG : pacer: Reducing sleep to 1.125s 2025/07/13 03:43:31 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:43:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/07/13 03:43:32 DEBUG : pacer: Reducing sleep to 1.5s 2025/07/13 03:43:34 DEBUG : pacer: Reducing sleep to 1.125s 2025/07/13 03:43:35 DEBUG : pacer: Reducing sleep to 843.75ms 2025/07/13 03:43:42 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:43:42 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2025/07/13 03:43:49 DEBUG : pacer: low level retry 2/10 (error no link) 2025/07/13 03:43:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/07/13 03:43:55 DEBUG : pacer: low level retry 3/10 (error no link) 2025/07/13 03:44:01 DEBUG : pacer: low level retry 4/10 (error no link) 2025/07/13 03:44:02 DEBUG : pacer: Reducing sleep to 1.5s 2025/07/13 03:44:04 DEBUG : pacer: Reducing sleep to 1.125s 2025/07/13 03:44:11 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:44:11 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/07/13 03:44:12 DEBUG : pacer: Reducing sleep to 1.5s 2025/07/13 03:44:14 DEBUG : pacer: Reducing sleep to 1.125s 2025/07/13 03:44:15 DEBUG : pacer: Reducing sleep to 843.75ms 2025/07/13 03:44:17 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/07/13 03:44:18 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/07/13 03:44:19 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/07/13 03:44:20 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/07/13 03:44:20 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/07/13 03:44:21 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/07/13 03:44:22 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/07/13 03:44:29 DEBUG : pacer: low level retry 1/10 (error no link) 2025/07/13 03:44:29 DEBUG : pacer: Rate limited, increasing sleep to 225.254056ms 2025/07/13 03:44:35 DEBUG : pacer: low level retry 2/10 (error no link) 2025/07/13 03:44:35 DEBUG : pacer: Rate limited, increasing sleep to 450.508112ms 2025/07/13 03:44:41 DEBUG : pacer: low level retry 3/10 (error no link) 2025/07/13 03:44:41 DEBUG : pacer: Rate limited, increasing sleep to 901.016224ms 2025/07/13 03:44:48 DEBUG : pacer: low level retry 4/10 (error no link) 2025/07/13 03:44:48 DEBUG : pacer: Rate limited, increasing sleep to 1.802032448s 2025/07/13 03:44:48 DEBUG : pacer: Reducing sleep to 1.351524336s 2025/07/13 03:44:50 DEBUG : pacer: Reducing sleep to 1.013643252s 2025/07/13 03:44:51 DEBUG : pacer: Reducing sleep to 760.232439ms 2025/07/13 03:44:52 DEBUG : pacer: Reducing sleep to 570.174329ms 2025/07/13 03:44:53 DEBUG : pacer: Reducing sleep to 427.630746ms 2025/07/13 03:44:53 DEBUG : pacer: Reducing sleep to 320.723059ms 2025/07/13 03:44:54 DEBUG : pacer: Reducing sleep to 240.542294ms 2025/07/13 03:44:55 DEBUG : pacer: Reducing sleep to 180.40672ms --- PASS: TestCat (160.32s) FAIL 2025/07/13 03:44:55 DEBUG : PikPak root 'rclone-test-siruwoh1pigo': Purge remote 2025/07/13 03:44:55 DEBUG : pacer: Reducing sleep to 135.30504ms 2025/07/13 03:44:56 DEBUG : pacer: Reducing sleep to 101.47878ms "./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload)$'" - Finished ERROR in 9m43.266901326s (try 3/5): exit status 1: Failed [TestCheckSumDownload]