"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload)$'" - Starting (try 2/5) 2025/04/30 16:51:10 DEBUG : Creating backend with remote "TestPikPak:rclone-test-seqeyoy5zipu" 2025/04/30 16:51:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/30 16:51:12 DEBUG : Creating backend with remote "/tmp/rclone2944125055" === RUN TestCheckSumDownload run.go:180: Remote "PikPak root 'rclone-test-seqeyoy5zipu'", Local "Local file system at /tmp/rclone2944125055", Modify Window "876000h0m0s" 2025/04/30 16:51:12 DEBUG : Creating backend with remote "TestPikPak:rclone-test-seqeyoy5zipu/data" 2025/04/30 16:51:15 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/04/30 16:51:15 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/04/30 16:51:16 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:51:16 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 === RUN TestCheckSumDownload/subtest1 === NAME TestCheckSumDownload check_test.go:408: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:408 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Received unexpected error: failed to parse sum file: can't download: no link Test: TestCheckSumDownload Messages: unexpected error in run 1 check_test.go:417: Error Trace: /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:432 Error: "2025/04/30 16:51:20 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:51:20 DEBUG : pacer: Rate limited, increasing sleep to 200ms\n2025/04/30 16:51:21 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:51:21 DEBUG : pacer: Rate limited, increasing sleep to 400ms\n2025/04/30 16:51:22 DEBUG : pacer: low level retry 3/10 (error no link)\n2025/04/30 16:51:22 DEBUG : pacer: Rate limited, increasing sleep to 800ms\n2025/04/30 16:51:24 DEBUG : pacer: low level retry 4/10 (error no link)\n2025/04/30 16:51:24 DEBUG : pacer: Rate limited, increasing sleep to 1.6s\n2025/04/30 16:51:25 DEBUG : pacer: low level retry 5/10 (error no link)\n2025/04/30 16:51:25 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:51:27 DEBUG : pacer: low level retry 6/10 (error no link)\n2025/04/30 16:51:29 DEBUG : pacer: low level retry 7/10 (error no link)\n2025/04/30 16:51:31 DEBUG : pacer: low level retry 8/10 (error no link)\n2025/04/30 16:51:33 DEBUG : pacer: low level retry 9/10 (error no link)\n2025/04/30 16:51:35 DEBUG : pacer: low level retry 10/10 (error no link)\n" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 1 check_test.go:419: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:419 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 1 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "= banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=8) "= banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 1 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 1 2025/04/30 16:51:36 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:51:36 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/04/30 16:51:36 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/04/30 16:51:38 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:51:39 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:51:39 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:51:39 DEBUG : test.sum: gcid = a5968cd7981dde739b5ed764a5ed354ef42c42b2 2025/04/30 16:51:41 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:51:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:51:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/04/30 16:51:43 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/04/30 16:51:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/04/30 16:51:43 DEBUG : pacer: Reducing sleep to 150.169371ms === RUN TestCheckSumDownload/subtest2 === NAME TestCheckSumDownload check_test.go:413: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:413 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 2 check_test.go:417: Error Trace: /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:432 Error: "2025/04/30 16:51:44 DEBUG : pacer: Reducing sleep to 112.627028ms\n2025/04/30 16:51:45 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:51:45 DEBUG : pacer: Rate limited, increasing sleep to 225.254056ms\n2025/04/30 16:51:46 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:51:46 DEBUG : pacer: Rate limited, increasing sleep to 450.508112ms\n2025/04/30 16:51:48 DEBUG : pacer: low level retry 3/10 (error no link)\n2025/04/30 16:51:48 DEBUG : pacer: Rate limited, increasing sleep to 901.016224ms\n2025/04/30 16:51:49 DEBUG : pacer: low level retry 4/10 (error no link)\n2025/04/30 16:51:49 DEBUG : pacer: Rate limited, increasing sleep to 1.802032448s\n2025/04/30 16:51:50 DEBUG : pacer: low level retry 5/10 (error no link)\n2025/04/30 16:51:50 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:51:52 DEBUG : pacer: low level retry 6/10 (error no link)\n2025/04/30 16:51:54 DEBUG : pacer: low level retry 7/10 (error no link)\n2025/04/30 16:51:56 DEBUG : pacer: low level retry 8/10 (error no link)\n2025/04/30 16:51:58 DEBUG : pacer: low level retry 9/10 (error no link)\n2025/04/30 16:52:00 DEBUG : pacer: low level retry 10/10 (error no link)\n" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 2 check_test.go:419: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:419 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 2 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "- potato", "= banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=8) "- potato", - (string) (len=8) "= banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 2 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /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:432 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong missingonsrc result in run 2 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 2 2025/04/30 16:52:01 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:52:01 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:52:01 DEBUG : test.sum: gcid = f3a0ff69df1baee079fa197e53142fe40231e82b 2025/04/30 16:52:04 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:52:05 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:52:06 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:52:07 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:52:07 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/04/30 16:52:08 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestCheckSumDownload/subtest3 2025/04/30 16:52:27 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:52:27 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:52:27 DEBUG : test.sum: gcid = 4432a75a15f9e866fc8b0361de0580e4ab920bea 2025/04/30 16:52:29 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:52:29 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:52:30 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/04/30 16:52:31 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/04/30 16:52:31 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/04/30 16:52:31 DEBUG : pacer: Reducing sleep to 150.169371ms === RUN TestCheckSumDownload/subtest4 === NAME TestCheckSumDownload check_test.go:413: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:413 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 1 actual : 2 Test: TestCheckSumDownload Messages: wrong error count in run 4 check_test.go:417: Error Trace: /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:432 Error: "2025/04/30 16:52:32 DEBUG : pacer: Reducing sleep to 112.627028ms\n2025/04/30 16:52:33 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:52:33 DEBUG : pacer: Rate limited, increasing sleep to 225.254056ms\n2025/04/30 16:52:34 DEBUG : pacer: Reducing sleep to 168.940542ms\n2025/04/30 16:52:36 DEBUG : pacer: Reducing sleep to 126.705406ms\n2025/04/30 16:52:37 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:52:37 DEBUG : pacer: Rate limited, increasing sleep to 200ms\n2025/04/30 16:52:37 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:52:37 DEBUG : pacer: Rate limited, increasing sleep to 400ms\n2025/04/30 16:52:38 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:52:38 DEBUG : pacer: Rate limited, increasing sleep to 800ms\n2025/04/30 16:52:38 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:52:38 DEBUG : pacer: Rate limited, increasing sleep to 1.6s\n2025/04/30 16:52:39 DEBUG : pacer: low level retry 3/10 (error no link)\n2025/04/30 16:52:39 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:52:40 DEBUG : pacer: Reducing sleep to 1.5s\n2025/04/30 16:52:42 DEBUG : pacer: low level retry 4/10 (error no link)\n2025/04/30 16:52:42 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:52:43 DEBUG : pacer: Reducing sleep to 1.5s\n2025/04/30 16:52:43 DEBUG : md5 = 87396e030ef3f5b35bbf85c0a09a4fb3 (sum)\n2025/04/30 16:52:43 DEBUG : banana: md5 = 65a8e27d8879283831b664bd8b7f0ad4 (PikPak root 'rclone-test-seqeyoy5zipu/data')\n2025/04/30 16:52:43 ERROR : banana: files differ\n2025/04/30 16:52:45 DEBUG : pacer: low level retry 5/10 (error no link)\n2025/04/30 16:52:45 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:52:47 DEBUG : pacer: low level retry 6/10 (error no link)\n2025/04/30 16:52:49 DEBUG : pacer: low level retry 7/10 (error no link)\n2025/04/30 16:52:51 DEBUG : pacer: low level retry 8/10 (error no link)\n2025/04/30 16:52:53 DEBUG : pacer: low level retry 9/10 (error no link)\n2025/04/30 16:52:55 DEBUG : pacer: low level retry 10/10 (error no link)\n2025/04/30 16:52:55 ERROR : potato: Failed to calculate hash: can't download: no link\n2025/04/30 16:52:55 NOTICE: PikPak root 'rclone-test-seqeyoy5zipu/data': 2 differences found\n2025/04/30 16:52:55 NOTICE: PikPak root 'rclone-test-seqeyoy5zipu/data': 2 errors while checking\n" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 4 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 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 4 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 4 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:426 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 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 4 2025/04/30 16:52:56 DEBUG : pacer: Reducing sleep to 100ms 2025/04/30 16:52:56 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:52:56 DEBUG : test.sum: gcid = c2c6d29ce2f4e1f6e3e830802ef4a15c32af89c4 === RUN TestCheckSumDownload/subtest5 === NAME TestCheckSumDownload check_test.go:413: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:413 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 5 check_test.go:417: Error Trace: /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:432 Error: "2025/04/30 16:53:01 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:53:01 DEBUG : pacer: Rate limited, increasing sleep to 200ms\n2025/04/30 16:53:02 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:53:02 DEBUG : pacer: Rate limited, increasing sleep to 400ms\n2025/04/30 16:53:03 DEBUG : pacer: low level retry 3/10 (error no link)\n2025/04/30 16:53:03 DEBUG : pacer: Rate limited, increasing sleep to 800ms\n2025/04/30 16:53:05 DEBUG : pacer: low level retry 4/10 (error no link)\n2025/04/30 16:53:05 DEBUG : pacer: Rate limited, increasing sleep to 1.6s\n2025/04/30 16:53:06 DEBUG : pacer: low level retry 5/10 (error no link)\n2025/04/30 16:53:06 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:53:08 DEBUG : pacer: low level retry 6/10 (error no link)\n2025/04/30 16:53:10 DEBUG : pacer: low level retry 7/10 (error no link)\n2025/04/30 16:53:12 DEBUG : pacer: low level retry 8/10 (error no link)\n2025/04/30 16:53:14 DEBUG : pacer: low level retry 9/10 (error no link)\n2025/04/30 16:53:16 DEBUG : pacer: low level retry 10/10 (error no link)\n" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 5 check_test.go:419: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:419 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 5 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "+ orange", "= banana", "= potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,6 +1,2 @@ -([]string) (len=4) { - (string) "", - (string) (len=8) "+ orange", - (string) (len=8) "= banana", - (string) (len=8) "= potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 5 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:423 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "orange"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "orange" +([]string) { } Test: TestCheckSumDownload Messages: wrong missingondst result in run 5 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "banana", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=6) "banana", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 5 2025/04/30 16:53:17 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:53:17 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:53:17 DEBUG : test.sum: gcid = 903d92580da389428c3eace6c6dce692463e75e8 2025/04/30 16:53:20 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:53:20 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:53:21 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:53:22 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:53:23 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/04/30 16:53:23 DEBUG : pacer: Reducing sleep to 266.967773ms === RUN TestCheckSumDownload/subtest6 2025/04/30 16:54:01 DEBUG : pacer: Reducing sleep to 675.762172ms 2025/04/30 16:54:01 DEBUG : data/banana: calculating gcid: failed to unwrap object from src: data/banana 2025/04/30 16:54:01 DEBUG : data/banana: gcid = 5a20dd2421f633d21da85b8911f9f37677f212d5 2025/04/30 16:54:02 DEBUG : pacer: Reducing sleep to 506.821629ms 2025/04/30 16:54:02 DEBUG : pacer: Reducing sleep to 380.116221ms 2025/04/30 16:54:03 DEBUG : pacer: Reducing sleep to 285.087165ms 2025/04/30 16:54:03 DEBUG : pacer: Reducing sleep to 213.815373ms 2025/04/30 16:54:04 DEBUG : pacer: Reducing sleep to 160.361529ms 2025/04/30 16:54:04 DEBUG : data/potato: calculating gcid: failed to unwrap object from src: data/potato 2025/04/30 16:54:04 DEBUG : data/potato: gcid = 7a3c15f717358c93392def5ff0639db84c0d363b 2025/04/30 16:54:05 DEBUG : pacer: Reducing sleep to 120.271146ms 2025/04/30 16:54:05 DEBUG : pacer: Reducing sleep to 100ms 2025/04/30 16:54:06 DEBUG : test.sum: calculating gcid: failed to unwrap object from src: test.sum 2025/04/30 16:54:06 DEBUG : test.sum: gcid = 551b7ddca65803810ea668343a66646180a0c0b7 === RUN TestCheckSumDownload/subtest7 === NAME TestCheckSumDownload check_test.go:408: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:408 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Received unexpected error: failed to parse sum file: can't download: no link Test: TestCheckSumDownload Messages: unexpected error in run 7 check_test.go:417: Error Trace: /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:432 Error: "2025/04/30 16:54:11 DEBUG : pacer: low level retry 1/10 (error no link)\n2025/04/30 16:54:11 DEBUG : pacer: Rate limited, increasing sleep to 200ms\n2025/04/30 16:54:12 DEBUG : pacer: low level retry 2/10 (error no link)\n2025/04/30 16:54:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms\n2025/04/30 16:54:13 DEBUG : pacer: low level retry 3/10 (error no link)\n2025/04/30 16:54:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms\n2025/04/30 16:54:14 DEBUG : pacer: low level retry 4/10 (error no link)\n2025/04/30 16:54:14 DEBUG : pacer: Rate limited, increasing sleep to 1.6s\n2025/04/30 16:54:16 DEBUG : pacer: low level retry 5/10 (error no link)\n2025/04/30 16:54:16 DEBUG : pacer: Rate limited, increasing sleep to 2s\n2025/04/30 16:54:17 DEBUG : pacer: low level retry 6/10 (error no link)\n2025/04/30 16:54:19 DEBUG : pacer: low level retry 7/10 (error no link)\n2025/04/30 16:54:21 DEBUG : pacer: low level retry 8/10 (error no link)\n2025/04/30 16:54:23 DEBUG : pacer: low level retry 9/10 (error no link)\n2025/04/30 16:54:25 DEBUG : pacer: low level retry 10/10 (error no link)\n" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 7 check_test.go:419: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:419 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 7 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "= banana", "= potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=8) "= banana", - (string) (len=8) "= potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 7 check_test.go:387: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:387 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:424 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:432 Error: Not equal: expected: []string{"", "banana", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=6) "banana", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 7 2025/04/30 16:54:26 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:54:28 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:54:30 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:54:31 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:54:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:54:32 DEBUG : pacer: Reducing sleep to 355.957031ms 2025/04/30 16:54:33 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/04/30 16:54:33 DEBUG : pacer: Reducing sleep to 200.225829ms 2025/04/30 16:54:34 DEBUG : pacer: Reducing sleep to 150.169371ms 2025/04/30 16:54:34 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/04/30 16:54:34 DEBUG : data: Rmdir: contains trashed file: "banana" 2025/04/30 16:54:34 DEBUG : data: Rmdir: contains trashed file: "potato" 2025/04/30 16:54:34 DEBUG : data: Rmdir: contains trashed file: "banana" 2025/04/30 16:54:34 DEBUG : pacer: Reducing sleep to 112.627028ms 2025/04/30 16:54:35 DEBUG : pacer: Reducing sleep to 100ms --- FAIL: TestCheckSumDownload (203.10s) --- PASS: TestCheckSumDownload/subtest1 (16.54s) --- PASS: TestCheckSumDownload/subtest2 (16.73s) --- PASS: TestCheckSumDownload/subtest3 (19.41s) --- PASS: TestCheckSumDownload/subtest4 (23.44s) --- PASS: TestCheckSumDownload/subtest5 (16.56s) --- PASS: TestCheckSumDownload/subtest6 (36.68s) --- PASS: TestCheckSumDownload/subtest7 (16.02s) === RUN TestCat run.go:180: Remote "PikPak root 'rclone-test-seqeyoy5zipu'", Local "Local file system at /tmp/rclone2944125055", Modify Window "876000h0m0s" 2025/04/30 16:54:35 DEBUG : file1: calculating gcid: failed to unwrap object from src: file1 2025/04/30 16:54:35 DEBUG : file1: gcid = 4d8356ed1af6fd7e9537777328c6d176effe38a0 2025/04/30 16:54:37 DEBUG : file2: calculating gcid: failed to unwrap object from src: file2 2025/04/30 16:54:37 DEBUG : file2: gcid = 9e3614f5e363b3d1060c1e4d6738e7c26804ae41 2025/04/30 16:54:39 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:54:39 DEBUG : pacer: Rate limited, increasing sleep to 200ms 2025/04/30 16:54:41 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:54:41 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2025/04/30 16:54:42 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:54:42 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2025/04/30 16:54:43 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:54:43 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2025/04/30 16:54:44 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:54:44 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:54:46 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:54:48 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:54:50 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:54:52 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:54:53 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:54:53 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:54:55 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:54:57 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:54:59 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:55:01 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:55:03 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:55:05 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:55:07 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:55:09 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:55:11 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:55:11 ERROR : file2: Failed to open: can't download: no link operations_test.go:566: Incorrect output from Cat(0,-1,): "ABCDEFGHIJ" 2025/04/30 16:55:12 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:55:14 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:55:16 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:55:18 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:55:18 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2025/04/30 16:55:19 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:55:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:55:20 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:55:22 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:55:24 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:55:25 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:55:27 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:55:27 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2025/04/30 16:55:28 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:55:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:55:30 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:55:32 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:55:34 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:55:36 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:55:38 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:55:40 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file 2025/04/30 16:55:41 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:55:42 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:55:44 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:55:44 ERROR : file1: Failed to open: can't download: no link 2025/04/30 16:55:46 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:55:48 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:55:50 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:55:52 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:55:54 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:55:56 DEBUG : pacer: Reducing sleep to 1.125s operations_test.go:566: Incorrect output from Cat(-3,-1,): "678" 2025/04/30 16:55:57 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:55:59 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:55:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6875s 2025/04/30 16:56:00 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:56:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:56:02 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:56:04 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:56:06 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:56:08 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:56:10 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:56:12 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:56:14 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:56:16 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:56:16 ERROR : file1: Failed to open: can't download: no link 2025/04/30 16:56:18 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:56:20 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:56:22 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:56:24 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:56:26 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:56:28 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:56:30 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:56:32 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:56:34 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:56:36 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:56:36 ERROR : file2: Failed to open: can't download: no link operations_test.go:566: Incorrect output from Cat(1,3,): "" 2025/04/30 16:56:37 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:56:40 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:56:40 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/04/30 16:56:41 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:56:43 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:56:45 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:56:47 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:56:49 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:56:51 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:56:53 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:56:55 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:56:57 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:56:57 ERROR : file1: Failed to open: can't download: no link 2025/04/30 16:56:59 DEBUG : pacer: low level retry 1/10 (error no link) 2025/04/30 16:57:01 DEBUG : pacer: low level retry 2/10 (error no link) 2025/04/30 16:57:03 DEBUG : pacer: low level retry 3/10 (error no link) 2025/04/30 16:57:05 DEBUG : pacer: low level retry 4/10 (error no link) 2025/04/30 16:57:07 DEBUG : pacer: low level retry 5/10 (error no link) 2025/04/30 16:57:09 DEBUG : pacer: low level retry 6/10 (error no link) 2025/04/30 16:57:11 DEBUG : pacer: low level retry 7/10 (error no link) 2025/04/30 16:57:13 DEBUG : pacer: low level retry 8/10 (error no link) 2025/04/30 16:57:15 DEBUG : pacer: low level retry 9/10 (error no link) 2025/04/30 16:57:17 DEBUG : pacer: low level retry 10/10 (error no link) 2025/04/30 16:57:17 ERROR : file2: Failed to open: can't download: no link operations_test.go:566: Incorrect output from Cat(0,-1, ): "" 2025/04/30 16:57:18 DEBUG : pacer: Reducing sleep to 1.5s 2025/04/30 16:57:20 DEBUG : pacer: Reducing sleep to 1.125s 2025/04/30 16:57:22 DEBUG : pacer: Reducing sleep to 843.75ms 2025/04/30 16:57:23 DEBUG : pacer: Reducing sleep to 632.8125ms 2025/04/30 16:57:24 DEBUG : pacer: Reducing sleep to 474.609375ms 2025/04/30 16:57:25 DEBUG : pacer: Reducing sleep to 355.957031ms --- FAIL: TestCat (169.71s) FAIL 2025/04/30 16:57:25 DEBUG : PikPak root 'rclone-test-seqeyoy5zipu': Purge remote 2025/04/30 16:57:25 DEBUG : pacer: Reducing sleep to 266.967773ms 2025/04/30 16:57:26 DEBUG : pacer: Reducing sleep to 200.225829ms "./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestCat|TestCheckSumDownload)$'" - Finished ERROR in 6m15.547216824s (try 2/5): exit status 1: Failed [TestCheckSumDownload TestCat]