"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5) 2025/11/21 01:29:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-peyeler6ciyu" 2025/11/21 01:29:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/21 01:29:29 DEBUG : Starting OpenDrive session with ID: 9b8da129732def0b288aad20ffc81fe4ad3a6a697e98ead95ba2f1771dbcdca6 2025/11/21 01:29:29 DEBUG : Creating backend with remote "/tmp/rclone1184197542" === RUN TestServerSideCopy run.go:185: Remote "OpenDrive root 'rclone-test-peyeler6ciyu'", Local "Local file system at /tmp/rclone1184197542", Modify Window "1s" 2025/11/21 01:29:31 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/11/21 01:29:32 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vesopiz0sisu" 2025/11/21 01:29:33 DEBUG : Starting OpenDrive session with ID: bbe130f7d82eb489ae8813d079778801232f5b766102873556507c32a8805c39 sync_test.go:620: Server side copy (if possible) OpenDrive root 'rclone-test-peyeler6ciyu' -> OpenDrive root 'rclone-test-vesopiz0sisu' 2025/11/21 01:29:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/11/21 01:29:33 DEBUG : OpenDrive root 'rclone-test-vesopiz0sisu': Waiting for checks to finish 2025/11/21 01:29:33 DEBUG : OpenDrive root 'rclone-test-vesopiz0sisu': Waiting for transfers to finish 2025/11/21 01:29:34 DEBUG : sub dir/hello world: size = 11 OK 2025/11/21 01:29:34 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/11/21 01:29:34 INFO : sub dir/hello world: Copied (server-side copy) 2025/11/21 01:29:35 DEBUG : OpenDrive root 'rclone-test-vesopiz0sisu': Purge remote fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure --- PASS: TestServerSideCopy (14.34s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "OpenDrive root 'rclone-test-peyeler6ciyu'", Local "Local file system at /tmp/rclone1184197542", Modify Window "1s" 2025/11/21 01:29:45 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/11/21 01:29:46 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/11/21 01:29:48 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/11/21 01:29:50 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-peyeler6ciyu/dst" 2025/11/21 01:29:50 DEBUG : Starting OpenDrive session with ID: fc33fbd3ebc0ce86fefaea5342ce04b6f67af564d8ae11f1baa8c490398a9b82 2025/11/21 01:29:51 DEBUG : one: size = 4 (Local file system at /tmp/rclone1184197542) 2025/11/21 01:29:51 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-peyeler6ciyu/dst') 2025/11/21 01:29:51 DEBUG : one: Sizes differ 2025/11/21 01:29:51 DEBUG : one: same parent dir (ODFfOTYwNjk1MV9JcVNKdQ) - using file/rename instead of move_copy for one-2019-01-01 2025/11/21 01:29:51 INFO : one: Moved (server-side) to: one-2019-01-01 2025/11/21 01:29:52 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/11/21 01:29:53 DEBUG : one: size = 4 OK 2025/11/21 01:29:53 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/11/21 01:29:53 INFO : one: Copied (new) 2025/11/21 01:29:53 DEBUG : two: size = 3 OK 2025/11/21 01:29:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/11/21 01:29:53 DEBUG : two: Unchanged skipping 2025/11/21 01:29:53 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone1184197542) 2025/11/21 01:29:53 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-peyeler6ciyu/dst') 2025/11/21 01:29:53 DEBUG : three.txt: Sizes differ 2025/11/21 01:29:54 DEBUG : three.txt: same parent dir (ODFfOTYwNjk1MV9JcVNKdQ) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/11/21 01:29:54 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/11/21 01:29:55 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/11/21 01:29:56 DEBUG : three.txt: size = 6 OK 2025/11/21 01:29:56 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/11/21 01:29:56 INFO : three.txt: Copied (new) 2025/11/21 01:29:56 DEBUG : one: size = 5 (Local file system at /tmp/rclone1184197542) 2025/11/21 01:29:56 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-peyeler6ciyu/dst') 2025/11/21 01:29:56 DEBUG : one: Sizes differ 2025/11/21 01:29:57 INFO : one-2019-01-01: Deleted 2025/11/21 01:29:57 DEBUG : one: same parent dir (ODFfOTYwNjk1MV9JcVNKdQ) - using file/rename instead of move_copy for one-2019-01-01 2025/11/21 01:29:57 INFO : one: Moved (server-side) to: one-2019-01-01 2025/11/21 01:29:58 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/11/21 01:29:58 DEBUG : one: size = 5 OK 2025/11/21 01:29:58 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/11/21 01:29:58 INFO : one: Copied (new) 2025/11/21 01:29:59 DEBUG : two: size = 3 OK 2025/11/21 01:29:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/11/21 01:29:59 DEBUG : two: Unchanged skipping 2025/11/21 01:29:59 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone1184197542) 2025/11/21 01:29:59 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-peyeler6ciyu/dst') 2025/11/21 01:29:59 DEBUG : three.txt: Sizes differ 2025/11/21 01:29:59 INFO : three-2019-01-01.txt: Deleted 2025/11/21 01:29:59 DEBUG : three.txt: same parent dir (ODFfOTYwNjk1MV9JcVNKdQ) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/11/21 01:29:59 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/11/21 01:30:00 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/11/21 01:30:01 DEBUG : three.txt: size = 19 OK 2025/11/21 01:30:01 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/11/21 01:30:01 INFO : three.txt: Copied (new) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 /usr/local/go/src/runtime/asm_amd64.s:1700 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: listing wrong, want dst/one (5), dst/one-2019-01-01 (4), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3) got dst/one-2019-01-01 (5), dst/three-2019-01-01.txt (6), dst/three.txt (19), dst/two (3) fstest.go:143: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:149 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: OpenDrive root 'rclone-test-peyeler6ciyu'/dst/one-2019-01-01: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 4 actual : 5 Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: size incorrect file=4 vs obj=5 fstest.go:127: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:127 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:132 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffixKeepExtension Messages: dst/one-2019-01-01: Modification time difference too big |-119h59m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:203: Not found "dst/one" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:345 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2551 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffixKeepExtension Messages: 1 objects not found --- FAIL: TestSyncSuffixKeepExtension (27.85s) === RUN TestSyncReplaceDirModTimeWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-peyeler6ciyu'", Local "Local file system at /tmp/rclone1184197542", Modify Window "1s" 2025/11/21 01:30:11 DEBUG : empty_dir: Making directory with metadata 2025/11/21 01:30:11 INFO : empty_dir: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/11/21 01:30:11 DEBUG : empty_on_remote: Making directory with metadata 2025/11/21 01:30:11 INFO : empty_on_remote: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/11/21 01:30:11 INFO : empty_on_remote: Making directory 2025/11/21 01:30:12 INFO : empty_on_remote: Made directory with modification time 2011-12-25 12:59:59.123456789 +0000 UTC 2025/11/21 01:30:16 INFO : test_dir1: Set directory modification time (using DirSetModTime) 2025/11/21 01:30:16 INFO : test_dir2: Set directory modification time (using DirSetModTime) 2025/11/21 01:30:16 INFO : test_dir2/sub_dir: Set directory modification time (using DirSetModTime) 2025/11/21 01:30:16 INFO : empty_on_remote: Set directory modification time (using DirSetModTime) 2025/11/21 01:30:16 INFO : empty_dir: Set directory modification time (using DirSetModTime) run.go:130: removing dir "test_dir2/sub_dir" failed - try 1/3: folder not empty --- PASS: TestSyncReplaceDirModTimeWithEmptyDirs (9.74s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-peyeler6ciyu'", Local "Local file system at /tmp/rclone1184197542", Modify Window "1s" 2025/11/21 01:30:21 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/11/21 01:30:21 INFO : sub dir: Making directory 2025/11/21 01:30:22 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (16.25s) FAIL 2025/11/21 01:30:37 DEBUG : OpenDrive root 'rclone-test-peyeler6ciyu': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestServerSideCopy|TestSyncReplaceDirModTimeWithEmptyDirs|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 1m9.58730076s (try 2/5): exit status 1: Failed [TestSyncSuffixKeepExtension]