"./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCheckSum|TestDeduplicateNewestByHash|TestDirMove|TestRcatSize|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$|^TestHashSums$/^Md5$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5) 2025/06/18 02:02:09 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-muteqil1suzi" 2025/06/18 02:02:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/18 02:02:09 DEBUG : Creating backend with remote "/tmp/rclone1607727335" === RUN TestCheckSum run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:02:09 DEBUG : Creating backend with remote "TestFilesCom:rclone-test-muteqil1suzi/data" === RUN TestCheckSum/subtest1 === RUN TestCheckSum/subtest2 === RUN TestCheckSum/subtest3 === RUN TestCheckSum/subtest4 === NAME TestCheckSum check_test.go:407: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:407 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: An error is expected but got nil. Test: TestCheckSum Messages: no expected error in run 4 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: 1 actual : 0 Test: TestCheckSum Messages: wrong error count in run 4 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{"", "= banana", "= potato"} Diff: --- Expected +++ Actual @@ -2,3 +2,3 @@ (string) "", - (string) (len=8) "* banana", + (string) (len=8) "= banana", (string) (len=8) "= potato" Test: TestCheckSum Messages: wrong combined result in run 4 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{"", "potato"} actual : []string{"", "banana", "potato"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", + (string) (len=6) "banana", (string) (len=6) "potato" Test: TestCheckSum Messages: wrong match result in run 4 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:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 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: TestCheckSum Messages: wrong differ result in run 4 === RUN TestCheckSum/subtest5 === RUN TestCheckSum/subtest6 === NAME TestCheckSum 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: 2 actual : 1 Test: TestCheckSum Messages: wrong error count in run 6 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{"", "* potato", "+ orange", "= banana"} actual : []string{"", "+ orange", "= banana", "= potato"} Diff: --- Expected +++ Actual @@ -2,5 +2,5 @@ (string) "", - (string) (len=8) "* potato", (string) (len=8) "+ orange", - (string) (len=8) "= banana" + (string) (len=8) "= banana", + (string) (len=8) "= potato" } Test: TestCheckSum Messages: wrong combined result in run 6 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"} actual : []string{"", "banana", "potato"} Diff: --- Expected +++ Actual @@ -1,4 +1,5 @@ -([]string) (len=2) { +([]string) (len=3) { (string) "", - (string) (len=6) "banana" + (string) (len=6) "banana", + (string) (len=6) "potato" } Test: TestCheckSum Messages: wrong match result in run 6 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:421 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 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: TestCheckSum Messages: wrong differ result in run 6 === RUN TestCheckSum/subtest7 --- FAIL: TestCheckSum (21.68s) --- PASS: TestCheckSum/subtest1 (0.71s) --- PASS: TestCheckSum/subtest2 (0.70s) --- PASS: TestCheckSum/subtest3 (0.70s) --- PASS: TestCheckSum/subtest4 (0.68s) --- PASS: TestCheckSum/subtest5 (0.67s) --- PASS: TestCheckSum/subtest6 (0.68s) --- PASS: TestCheckSum/subtest7 (0.69s) === RUN TestDeduplicateNewestByHash run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:02:35 INFO : files root 'rclone-test-muteqil1suzi': Looking for duplicate md5 hashes using newest mode. fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/dedupe_test.go:164 Error: Should be true Test: TestDeduplicateNewestByHash Messages: listing wrong, want another (100), not-one (5) got also/one (100), another (100), not-one (5), one (100) fstest.go:188: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:188 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/dedupe_test.go:164 Error: Should be true Test: TestDeduplicateNewestByHash Messages: Unexpected file "one" fstest.go:188: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:188 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/dedupe_test.go:164 Error: Should be true Test: TestDeduplicateNewestByHash Messages: Unexpected file "also/one" --- FAIL: TestDeduplicateNewestByHash (13.71s) === RUN TestHashSums run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" === RUN TestHashSums/Md5 operations_test.go:280: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:280 Error: " empty space\n potato2\n" does not contain "336d5ebc5436534e61d16e63ddfca327 empty space\n" Test: TestHashSums/Md5 operations_test.go:280: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:280 Error: " empty space\n potato2\n" does not contain "d6548b156ea68a4e003e786df99eee76 potato2\n" Test: TestHashSums/Md5 --- FAIL: TestHashSums (2.66s) --- FAIL: TestHashSums/Md5 (0.14s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:02:48 INFO : A1: Making directory 2025/06/18 02:02:48 INFO : A1/B1: Making directory 2025/06/18 02:02:48 INFO : A1/B1/C1: Making directory 2025/06/18 02:02:49 DEBUG : removing 1 level 3 directories 2025/06/18 02:02:49 INFO : A1/B1/C1: Removing directory 2025/06/18 02:02:49 DEBUG : removing 1 level 2 directories 2025/06/18 02:02:49 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (2.86s) === RUN TestRmdirsWithFilter run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:02:51 INFO : A1: Making directory 2025/06/18 02:02:51 INFO : A1/B1: Making directory 2025/06/18 02:02:51 INFO : A1/B1/C1: Making directory fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:789 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) (len=2) "A1", - (string) (len=5) "A1/B1", - (string) (len=8) "A1/B1/C1" +([]string) { } Test: TestRmdirsWithFilter Messages: directories fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:803 Error: Not equal: expected: []string{"A1"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,3 +1,2 @@ -([]string) (len=1) { - (string) (len=2) "A1" +([]string) { } Test: TestRmdirsWithFilter Messages: directories --- FAIL: TestRmdirsWithFilter (16.85s) === RUN TestDirMove run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:03:12 INFO : A1/B2: Making directory 2025/06/18 02:03:12 INFO : A1/B1/C3: Making directory fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1365 Error: Should be true Test: TestDirMove Messages: listing wrong, want A1/B1/C1/four (4), A1/B1/C2/five (4), A1/B1/three (5), A1/one (3), A1/two (3) got A1/one (3), A1/two (3) fstest.go:200: Not found "A1/B1/three" fstest.go:200: Not found "A1/B1/C1/four" fstest.go:200: Not found "A1/B1/C2/five" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1365 Error: Not equal: expected: 0 actual : 3 Test: TestDirMove Messages: 3 objects not found fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1365 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1", "A1/B1/C2", "A1/B1/C3", "A1/B2"} actual : []string{"A1", "A1/B2"} Diff: --- Expected +++ Actual @@ -1,7 +1,3 @@ -([]string) (len=6) { +([]string) (len=2) { (string) (len=2) "A1", - (string) (len=5) "A1/B1", - (string) (len=8) "A1/B1/C1", - (string) (len=8) "A1/B1/C2", - (string) (len=8) "A1/B1/C3", (string) (len=5) "A1/B2" Test: TestDirMove Messages: directories fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1386 Error: Should be true Test: TestDirMove Messages: listing wrong, want A2/B1/C1/four (4), A2/B1/C2/five (4), A2/B1/three (5), A2/one (3), A2/two (3) got A2/one (3), A2/two (3) fstest.go:200: Not found "A2/B1/three" fstest.go:200: Not found "A2/B1/C1/four" fstest.go:200: Not found "A2/B1/C2/five" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1386 Error: Not equal: expected: 0 actual : 3 Test: TestDirMove Messages: 3 objects not found fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1386 Error: Not equal: expected: []string{"A2", "A2/B1", "A2/B1/C1", "A2/B1/C2", "A2/B1/C3", "A2/B2"} actual : []string{"A2", "A2/B2"} Diff: --- Expected +++ Actual @@ -1,7 +1,3 @@ -([]string) (len=6) { +([]string) (len=2) { (string) (len=2) "A2", - (string) (len=5) "A2/B1", - (string) (len=8) "A2/B1/C1", - (string) (len=8) "A2/B1/C2", - (string) (len=8) "A2/B1/C3", (string) (len=5) "A2/B2" Test: TestDirMove Messages: directories 2025/06/18 02:03:31 INFO : A2/two: Moved (server-side) to: A3/two 2025/06/18 02:03:31 INFO : A2/one: Moved (server-side) to: A3/one 2025/06/18 02:03:31 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A2 not empty`) 2025/06/18 02:03:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/18 02:03:32 DEBUG : pacer: Reducing sleep to 15ms 2025/06/18 02:03:32 DEBUG : pacer: Reducing sleep to 11.25ms 2025/06/18 02:03:32 DEBUG : pacer: Reducing sleep to 10ms fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1411 Error: Should be true Test: TestDirMove Messages: listing wrong, want A3/B1/C1/four (4), A3/B1/C2/five (4), A3/B1/three (5), A3/one (3), A3/two (3) got A3/one (3), A3/two (3) fstest.go:200: Not found "A3/B1/C1/four" fstest.go:200: Not found "A3/B1/C2/five" fstest.go:200: Not found "A3/B1/three" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1411 Error: Not equal: expected: 0 actual : 3 Test: TestDirMove Messages: 3 objects not found fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1411 Error: Not equal: expected: []string{"A3", "A3/B1", "A3/B1/C1", "A3/B1/C2", "A3/B1/C3", "A3/B2"} actual : []string{"A3", "A3/B2"} Diff: --- Expected +++ Actual @@ -1,7 +1,3 @@ -([]string) (len=6) { +([]string) (len=2) { (string) (len=2) "A3", - (string) (len=5) "A3/B1", - (string) (len=8) "A3/B1/C1", - (string) (len=8) "A3/B1/C2", - (string) (len=8) "A3/B1/C3", (string) (len=5) "A3/B2" Test: TestDirMove Messages: directories 2025/06/18 02:03:40 INFO : files root 'rclone-test-muteqil1suzi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2025/06/18 02:03:42 INFO : A3/one: Moved (server-side) to: A4/one 2025/06/18 02:03:42 INFO : A3/two: Moved (server-side) to: A4/two 2025/06/18 02:03:42 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:42 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/06/18 02:03:42 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:42 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/06/18 02:03:43 DEBUG : pacer: low level retry 3/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:43 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/06/18 02:03:43 DEBUG : pacer: low level retry 4/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:43 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/06/18 02:03:43 DEBUG : pacer: low level retry 5/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:43 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/06/18 02:03:43 DEBUG : pacer: low level retry 6/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:43 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/06/18 02:03:43 DEBUG : pacer: low level retry 7/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A3 not empty`) 2025/06/18 02:03:43 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2025/06/18 02:03:44 DEBUG : pacer: Reducing sleep to 960ms 2025/06/18 02:03:45 DEBUG : pacer: Reducing sleep to 720ms 2025/06/18 02:03:46 DEBUG : pacer: Reducing sleep to 540ms 2025/06/18 02:03:47 DEBUG : pacer: Reducing sleep to 405ms fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 2025/06/18 02:03:48 DEBUG : pacer: Reducing sleep to 303.75ms 2025/06/18 02:03:48 DEBUG : pacer: Reducing sleep to 227.8125ms 2025/06/18 02:03:49 DEBUG : pacer: Reducing sleep to 170.859375ms fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 2025/06/18 02:03:51 DEBUG : pacer: Reducing sleep to 128.144531ms 2025/06/18 02:03:51 DEBUG : pacer: Reducing sleep to 96.108398ms 2025/06/18 02:03:51 DEBUG : pacer: Reducing sleep to 72.081298ms fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: listing wrong, want A4/B1/C1/four (4), A4/B1/C2/five (4), A4/B1/three (5), A4/one (3), A4/two (3) got A4/one (3), A4/two (3) fstest.go:200: Not found "A4/B1/three" fstest.go:200: Not found "A4/B1/C1/four" fstest.go:200: Not found "A4/B1/C2/five" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Not equal: expected: 0 actual : 3 Test: TestDirMove Messages: 3 objects not found fstest.go:324: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:324 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Not equal: expected: []string{"A4", "A4/B1", "A4/B1/C1", "A4/B1/C2", "A4/B1/C3", "A4/B2"} actual : []string{"A4", "A4/B2"} Diff: --- Expected +++ Actual @@ -1,7 +1,3 @@ -([]string) (len=6) { +([]string) (len=2) { (string) (len=2) "A4", - (string) (len=5) "A4/B1", - (string) (len=8) "A4/B1/C1", - (string) (len=8) "A4/B1/C2", - (string) (len=8) "A4/B1/C3", (string) (len=5) "A4/B2" Test: TestDirMove Messages: directories 2025/06/18 02:03:55 DEBUG : pacer: Reducing sleep to 54.060973ms 2025/06/18 02:03:56 DEBUG : pacer: Reducing sleep to 40.545729ms 2025/06/18 02:03:56 DEBUG : pacer: Reducing sleep to 30.409296ms 2025/06/18 02:03:57 DEBUG : pacer: Reducing sleep to 22.806972ms 2025/06/18 02:03:57 DEBUG : pacer: Reducing sleep to 17.105229ms 2025/06/18 02:03:57 DEBUG : pacer: Reducing sleep to 12.828921ms 2025/06/18 02:03:57 DEBUG : pacer: low level retry 1/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A4 not empty`) 2025/06/18 02:03:57 DEBUG : pacer: Rate limited, increasing sleep to 25.657842ms 2025/06/18 02:03:58 DEBUG : pacer: low level retry 2/10 (error Folder Not Empty - `Folder rclone-test-muteqil1suzi/A4 not empty`) 2025/06/18 02:03:58 DEBUG : pacer: Rate limited, increasing sleep to 51.315684ms 2025/06/18 02:03:58 DEBUG : pacer: Reducing sleep to 38.486763ms 2025/06/18 02:03:58 DEBUG : pacer: Reducing sleep to 28.865072ms --- FAIL: TestDirMove (50.75s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:03:58 DEBUG : pacer: Reducing sleep to 21.648804ms 2025/06/18 02:03:58 DEBUG : files root 'rclone-test-muteqil1suzi': File to upload is small (34 bytes), uploading instead of streaming 2025/06/18 02:03:59 DEBUG : pacer: Reducing sleep to 16.236603ms 2025/06/18 02:03:59 DEBUG : pacer: Reducing sleep to 12.177452ms 2025/06/18 02:03:59 DEBUG : no_checksum_small_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:03:59 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/06/18 02:04:00 DEBUG : pacer: Reducing sleep to 10ms 2025/06/18 02:04:00 DEBUG : no_checksum_big_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:04:00 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:01 DEBUG : files root 'rclone-test-muteqil1suzi': File to upload is small (34 bytes), uploading instead of streaming 2025/06/18 02:04:02 DEBUG : with_checksum_small_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:04:02 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/06/18 02:04:03 DEBUG : with_checksum_big_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:04:03 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:04 DEBUG : files root 'rclone-test-muteqil1suzi': File to upload is small (34 bytes), uploading instead of streaming 2025/06/18 02:04:05 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/18 02:04:06 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:07 DEBUG : files root 'rclone-test-muteqil1suzi': File to upload is small (34 bytes), uploading instead of streaming 2025/06/18 02:04:08 DEBUG : ignore_checksum_small_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:04:08 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check 2025/06/18 02:04:08 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2025/06/18 02:04:09 DEBUG : ignore_checksum_big_file_from_pipe: Dst hash empty - aborting Src hash check 2025/06/18 02:04:09 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check 2025/06/18 02:04:09 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (11.49s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (3.12s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (2.85s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (2.75s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (2.78s) === RUN TestRcatSize run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:10 DEBUG : files root 'rclone-test-muteqil1suzi': File to upload is small (60 bytes), uploading instead of streaming 2025/06/18 02:04:11 DEBUG : potato2: Dst hash empty - aborting Src hash check 2025/06/18 02:04:11 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (2.25s) === RUN TestTouchDir run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:15 DEBUG : files root 'rclone-test-muteqil1suzi': Touching "empty space" 2025/06/18 02:04:15 DEBUG : files root 'rclone-test-muteqil1suzi': Touching "potato2" 2025/06/18 02:04:15 DEBUG : files root 'rclone-test-muteqil1suzi': Touching "sub dir/potato3" --- PASS: TestTouchDir (4.43s) === RUN TestRemoveExisting run.go:180: Remote "files root 'rclone-test-muteqil1suzi'", Local "Local file system at /tmp/rclone1607727335", Modify Window "1s" 2025/06/18 02:04:19 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.kazojac7" before starting 2025/06/18 02:04:20 DEBUG : sub dir/test remove existing.kazojac7: TEST: removing renamed existing file after operation 2025/06/18 02:04:21 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sirepaj3" before starting 2025/06/18 02:04:21 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.sirepaj3: TEST: renaming existing back after failed operation 2025/06/18 02:04:22 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.rekifig7" before starting 2025/06/18 02:04:23 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.rekifig7: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (7.81s) FAIL 2025/06/18 02:04:24 DEBUG : files root 'rclone-test-muteqil1suzi': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestFilesCom: -verbose -test.run '^(TestCheckSum|TestDeduplicateNewestByHash|TestDirMove|TestRcatSize|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$|^TestHashSums$/^Md5$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 2m15.576861023s (try 2/5): exit status 1: Failed [TestCheckSum TestDeduplicateNewestByHash TestHashSums/Md5 TestRmdirsWithFilter TestDirMove]