"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSum|TestDirMove|TestPurge|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$'" - Starting (try 4/5) 2025/01/22 03:16:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-geyotif1tufu" 2025/01/22 03:16:27 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 03:16:27 DEBUG : Starting OpenDrive session with ID: 153b91143482106f1804a5c706c4d18749a930db3b908a98e64522229b880661 2025/01/22 03:16:27 DEBUG : Creating backend with remote "/tmp/rclone1869105762" === RUN TestCheckSum run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:16:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-geyotif1tufu/data" 2025/01/22 03:16:28 DEBUG : Starting OpenDrive session with ID: 2c9e8c99bd4812266e4e6a0f5c694a4f48f52ea7a1502ce86d23f39bb397fa61 2025/01/22 03:16:30 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/01/22 03:16:32 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest1 2025/01/22 03:16:36 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/01/22 03:16:38 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0 === RUN TestCheckSum/subtest2 2025/01/22 03:16:41 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest3 2025/01/22 03:16:43 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest4 2025/01/22 03:16:47 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest5 2025/01/22 03:16:50 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0 === RUN TestCheckSum/subtest6 2025/01/22 03:16:53 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0 2025/01/22 03:16:54 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0 2025/01/22 03:16:56 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0 === RUN TestCheckSum/subtest7 --- PASS: TestCheckSum (33.64s) --- PASS: TestCheckSum/subtest1 (1.30s) --- PASS: TestCheckSum/subtest2 (0.66s) --- PASS: TestCheckSum/subtest3 (0.64s) --- PASS: TestCheckSum/subtest4 (0.57s) --- PASS: TestCheckSum/subtest5 (0.60s) --- PASS: TestCheckSum/subtest6 (0.63s) --- PASS: TestCheckSum/subtest7 (0.55s) === RUN TestPurge 2025/01/22 03:17:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-ceqikos5vori" 2025/01/22 03:17:01 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/01/22 03:17:02 DEBUG : Starting OpenDrive session with ID: 92a43934daa1b714a37b3331467f65c138a39c5e646ca0054522a945f25b92f1 2025/01/22 03:17:02 DEBUG : Creating backend with remote "/tmp/rclone61754373" run.go:180: Remote "OpenDrive root 'rclone-test-ceqikos5vori'", Local "Local file system at /tmp/rclone61754373", Modify Window "1s" 2025/01/22 03:17:05 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0 2025/01/22 03:17:07 INFO : A2: Making directory 2025/01/22 03:17:07 INFO : A1/B2: Making directory 2025/01/22 03:17:08 INFO : A1/B2/C2: Making directory 2025/01/22 03:17:08 INFO : A1/B1/C3: Making directory 2025/01/22 03:17:08 INFO : A3: Making directory 2025/01/22 03:17:09 INFO : A3/B3: Making directory 2025/01/22 03:17:09 INFO : A3/B3/C4: Making directory 2025/01/22 03:17:11 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/01/22 03:17:16 ERROR : : error listing: directory not found 2025/01/22 03:17:16 DEBUG : OpenDrive root 'rclone-test-ceqikos5vori': Purge remote 2025/01/22 03:17:16 NOTICE: purge failed: directory not found --- PASS: TestPurge (14.70s) === RUN TestRmdirsLeaveRoot run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:17:16 INFO : A1: Making directory 2025/01/22 03:17:16 INFO : A1/B1: Making directory operations_test.go:747: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:747 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestRmdirsLeaveRoot 2025/01/22 03:17:17 ERROR : A1: error listing: failed to get folder list: Directory doesn't exist (Error 404) run.go:164: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:164 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1175 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1353 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1683 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/runtime/panic.go:626 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.22.0.linux-amd64/src/testing/testing.go:1005 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:747 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestRmdirsLeaveRoot --- FAIL: TestRmdirsLeaveRoot (0.87s) === RUN TestRmdirsWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:17:17 INFO : A1: Making directory 2025/01/22 03:17:17 INFO : A1/B1: Making directory operations_test.go:786: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:786 Error: Received unexpected error: failed to get folder list: Directory doesn't exist (Error 404) Test: TestRmdirsWithFilter --- FAIL: TestRmdirsWithFilter (1.16s) === RUN TestDirMove run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:17:19 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0 2025/01/22 03:17:21 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0 2025/01/22 03:17:23 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0 2025/01/22 03:17:26 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0 2025/01/22 03:17:28 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0 2025/01/22 03:17:29 INFO : A1/B2: Making directory 2025/01/22 03:17:30 INFO : A1/B1/C3: Making directory fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure 2025/01/22 03:17:40 DEBUG : A2: same parent dir (ODFfNzk4ODY0MV9JSHI1eA) - using folder/rename instead of move_copy 2025/01/22 03:17:45 INFO : A2/two: Moved (server-side) to: A3/two 2025/01/22 03:17:45 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2025/01/22 03:17:45 INFO : A2/one: Moved (server-side) to: A3/one 2025/01/22 03:17:45 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2025/01/22 03:17:46 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure 2025/01/22 03:17:57 INFO : OpenDrive root 'rclone-test-geyotif1tufu': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes operations_test.go:1433: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1433 Error: Received unexpected error: RenameDir mkdir: failed to get folder list: Directory doesn't exist (Error 404) Test: TestDirMove fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /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 A3/B1/C1/four (4), A3/B1/C2/five (4), A3/B1/three (5), A3/one (3), A3/two (3) fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: Unexpected file "A3/one" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: Unexpected file "A3/two" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: Unexpected file "A3/B1/three" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: Unexpected file "A3/B1/C2/five" fstest.go:185: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:185 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Should be true Test: TestDirMove Messages: Unexpected file "A3/B1/C1/four" fstest.go:197: Not found "A4/B1/C2/five" fstest.go:197: Not found "A4/one" fstest.go:197: Not found "A4/two" fstest.go:197: Not found "A4/B1/three" fstest.go:197: Not found "A4/B1/C1/four" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1439 Error: Not equal: expected: 0 actual : 5 Test: TestDirMove Messages: 5 objects not found fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /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{"A3", "A3/B1", "A3/B1/C1", "A3/B1/C2", "A3/B1/C3", "A3/B2", "A4"} Diff: --- Expected +++ Actual @@ -1,8 +1,9 @@ -([]string) (len=6) { - (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" +([]string) (len=7) { + (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", + (string) (len=2) "A4" } Test: TestDirMove Messages: directories --- FAIL: TestDirMove (55.56s) === RUN TestTouchDir run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:18:14 DEBUG : potato2: Uploading chunk 0, size=60, remain=0 2025/01/22 03:18:17 DEBUG : empty space: Uploading chunk 0, size=1, remain=0 2025/01/22 03:18:19 DEBUG : sub dir/potato3: Uploading chunk 0, size=5, remain=0 2025/01/22 03:18:22 DEBUG : OpenDrive root 'rclone-test-geyotif1tufu': Touching "empty space" 2025/01/22 03:18:22 DEBUG : OpenDrive root 'rclone-test-geyotif1tufu': Touching "potato2" 2025/01/22 03:18:22 DEBUG : OpenDrive root 'rclone-test-geyotif1tufu': Touching "sub dir/potato3" --- PASS: TestTouchDir (10.85s) === RUN TestRemoveExisting run.go:180: Remote "OpenDrive root 'rclone-test-geyotif1tufu'", Local "Local file system at /tmp/rclone1869105762", Modify Window "1s" 2025/01/22 03:18:26 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0 2025/01/22 03:18:28 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0 2025/01/22 03:18:31 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.kebuyuj3" before starting 2025/01/22 03:18:31 DEBUG : sub dir/test remove existing: same parent dir (ODFfNzk4ODY3NV81Yll0Zw) - using file/rename instead of move_copy for sub dir/test remove existing.kebuyuj3 2025/01/22 03:18:32 DEBUG : sub dir/test remove existing.kebuyuj3: TEST: removing renamed existing file after operation 2025/01/22 03:18:33 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.vuwigof6" before starting 2025/01/22 03:18:33 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfNzk4ODY3NV81Yll0Zw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.vuwigof6 operations_test.go:1935: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1935 Error: " 21 sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789\n" should not contain " 21 sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789\n" Test: TestRemoveExisting 2025/01/22 03:18:33 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.vuwigof6: TEST: renaming existing back after failed operation 2025/01/22 03:18:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.vuwigof6: same parent dir (ODFfNzk4ODY3NV81Yll0Zw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789 2025/01/22 03:18:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fijukid5" before starting 2025/01/22 03:18:34 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfNzk4ODY3NV81Yll0Zw) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fijukid5 2025/01/22 03:18:35 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.fijukid5: TEST: removing renamed existing file after operation --- FAIL: TestRemoveExisting (12.22s) FAIL 2025/01/22 03:18:36 DEBUG : OpenDrive root 'rclone-test-geyotif1tufu': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckSum|TestDirMove|TestPurge|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsWithFilter|TestTouchDir)$'" - Finished ERROR in 2m10.429927506s (try 4/5): exit status 1: Failed [TestRmdirsLeaveRoot TestRmdirsWithFilter TestDirMove TestRemoveExisting]