"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCheckDownload$/^(4|5|6|7)$|^TestHashSums$/^Md5Download$'" - Starting (try 5/5) 2024/05/06 02:48:25 DEBUG : Creating backend with remote "TestPikPak:rclone-test-nipiyew9neletad9desutay1" 2024/05/06 02:48:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/05/06 02:48:27 DEBUG : Creating backend with remote "/tmp/rclone2378627232" === RUN TestCheckDownload run.go:180: Remote "PikPak root 'rclone-test-nipiyew9neletad9desutay1'", Local "Local file system at /tmp/rclone2378627232", Modify Window "876000h0m0s" 2024/05/06 02:48:31 DEBUG : pacer: low level retry 1/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 02:48:31 DEBUG : pacer: low level retry 2/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:31 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 02:48:31 DEBUG : pacer: low level retry 3/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:31 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/05/06 02:48:31 DEBUG : pacer: low level retry 4/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:31 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/05/06 02:48:32 DEBUG : pacer: low level retry 5/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:32 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/05/06 02:48:32 DEBUG : pacer: low level retry 6/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:32 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/05/06 02:48:32 DEBUG : pacer: low level retry 7/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:32 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/05/06 02:48:33 DEBUG : pacer: low level retry 8/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/05/06 02:48:34 DEBUG : pacer: Reducing sleep to 1.5s 2024/05/06 02:48:36 DEBUG : pacer: Reducing sleep to 1.125s 2024/05/06 02:48:38 DEBUG : pacer: Reducing sleep to 843.75ms 2024/05/06 02:48:39 DEBUG : pacer: Reducing sleep to 632.8125ms 2024/05/06 02:48:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2024/05/06 02:48:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2024/05/06 02:48:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2024/05/06 02:48:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2024/05/06 02:48:41 DEBUG : pacer: Reducing sleep to 150.169371ms 2024/05/06 02:48:42 DEBUG : pacer: Reducing sleep to 112.627028ms === RUN TestCheckDownload/4 check_test.go:89: 4: Expecting 1 errors but got 2 === NAME TestCheckDownload check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "- empty space", "= potato2", "= rutabaga"} actual : []string{"", "* potato2", "- empty space", "= rutabaga"} Diff: --- Expected +++ Actual @@ -2,4 +2,4 @@ (string) "", + (string) (len=9) "* potato2", (string) (len=13) "- empty space", - (string) (len=9) "= potato2", (string) (len=10) "= rutabaga" Test: TestCheckDownload Messages: combined check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:60 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "potato2", "rutabaga"} actual : []string{"", "rutabaga"} Diff: --- Expected +++ Actual @@ -1,4 +1,3 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=7) "potato2", (string) (len=8) "rutabaga" Test: TestCheckDownload Messages: match check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:61 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{} actual : []string{"", "potato2"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) "", + (string) (len=7) "potato2" } Test: TestCheckDownload Messages: differ === RUN TestCheckDownload/5 check_test.go:89: 5: Expecting 1 errors but got 2 === NAME TestCheckDownload check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "* empty space", "= potato2", "= rutabaga"} actual : []string{"", "* empty space", "* potato2", "= rutabaga"} Diff: --- Expected +++ Actual @@ -3,3 +3,3 @@ (string) (len=13) "* empty space", - (string) (len=9) "= potato2", + (string) (len=9) "* potato2", (string) (len=10) "= rutabaga" Test: TestCheckDownload Messages: combined check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:60 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "potato2", "rutabaga"} actual : []string{"", "rutabaga"} Diff: --- Expected +++ Actual @@ -1,4 +1,3 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=7) "potato2", (string) (len=8) "rutabaga" Test: TestCheckDownload Messages: match check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:61 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "empty space"} actual : []string{"", "empty space", "potato2"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=11) "empty space" + (string) (len=11) "empty space", + (string) (len=7) "potato2" } Test: TestCheckDownload Messages: differ === RUN TestCheckDownload/6 check_test.go:89: 6: Expecting 2 errors but got 3 === NAME TestCheckDownload check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "* empty space", "- remotepotato", "= potato2", "= rutabaga"} actual : []string{"", "* empty space", "* potato2", "- remotepotato", "= rutabaga"} Diff: --- Expected +++ Actual @@ -3,4 +3,4 @@ (string) (len=13) "* empty space", + (string) (len=9) "* potato2", (string) (len=14) "- remotepotato", - (string) (len=9) "= potato2", (string) (len=10) "= rutabaga" Test: TestCheckDownload Messages: combined check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:60 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "potato2", "rutabaga"} actual : []string{"", "rutabaga"} Diff: --- Expected +++ Actual @@ -1,4 +1,3 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=7) "potato2", (string) (len=8) "rutabaga" Test: TestCheckDownload Messages: match check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:61 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "empty space"} actual : []string{"", "empty space", "potato2"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=11) "empty space" + (string) (len=11) "empty space", + (string) (len=7) "potato2" } Test: TestCheckDownload Messages: differ === RUN TestCheckDownload/7 check_test.go:89: 7: Expecting 1 errors but got 2 === NAME TestCheckDownload check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:57 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "* empty space", "= potato2", "= rutabaga"} actual : []string{"", "* empty space", "* potato2", "= rutabaga"} Diff: --- Expected +++ Actual @@ -3,3 +3,3 @@ (string) (len=13) "* empty space", - (string) (len=9) "= potato2", + (string) (len=9) "* potato2", (string) (len=10) "= rutabaga" Test: TestCheckDownload Messages: combined check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:60 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "potato2", "rutabaga"} actual : []string{"", "rutabaga"} Diff: --- Expected +++ Actual @@ -1,4 +1,3 @@ -([]string) (len=3) { +([]string) (len=2) { (string) "", - (string) (len=7) "potato2", (string) (len=8) "rutabaga" Test: TestCheckDownload Messages: match check_test.go:53: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:53 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:61 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:97 Error: Not equal: expected: []string{"", "empty space"} actual : []string{"", "empty space", "potato2"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=11) "empty space" + (string) (len=11) "empty space", + (string) (len=7) "potato2" } Test: TestCheckDownload Messages: differ --- FAIL: TestCheckDownload (25.90s) --- FAIL: TestCheckDownload/4 (2.61s) --- FAIL: TestCheckDownload/5 (1.97s) --- FAIL: TestCheckDownload/6 (2.02s) --- FAIL: TestCheckDownload/7 (2.61s) === RUN TestHashSums run.go:180: Remote "PikPak root 'rclone-test-nipiyew9neletad9desutay1'", Local "Local file system at /tmp/rclone2378627232", Modify Window "876000h0m0s" 2024/05/06 02:48:55 DEBUG : pacer: low level retry 1/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/05/06 02:48:55 DEBUG : pacer: low level retry 2/10 (error not PHASE_TYPE_COMPLETE) 2024/05/06 02:48:55 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/05/06 02:48:55 DEBUG : pacer: Reducing sleep to 30ms 2024/05/06 02:48:56 DEBUG : pacer: Reducing sleep to 22.5ms === RUN TestHashSums/Md5Download 2024/05/06 02:48:56 DEBUG : pacer: Reducing sleep to 16.875ms 2024/05/06 02:48:56 DEBUG : pacer: Reducing sleep to 12.65625ms 2024/05/06 02:48:56 DEBUG : pacer: Reducing sleep to 10ms operations_test.go:279: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:279 Error: "d41d8cd98f00b204e9800998ecf8427e potato2\n336d5ebc5436534e61d16e63ddfca327 empty space\n" does not contain "d6548b156ea68a4e003e786df99eee76 potato2\n" Test: TestHashSums/Md5Download --- FAIL: TestHashSums (4.42s) --- FAIL: TestHashSums/Md5Download (0.95s) FAIL 2024/05/06 02:48:58 DEBUG : PikPak root 'rclone-test-nipiyew9neletad9desutay1': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCheckDownload$/^(4|5|6|7)$|^TestHashSums$/^Md5Download$'" - Finished ERROR in 32.618099404s (try 5/5): exit status 1: Failed [TestCheckDownload/4 TestCheckDownload/5 TestCheckDownload/6 TestCheckDownload/7 TestHashSums/Md5Download]