"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Starting (try 2/5) 2026/02/19 04:10:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba" 2026/02/19 04:10:18 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/02/19 04:10:18 DEBUG : Starting OpenDrive session with ID: 01d4742665dd6d3dc66e54fe40e10bb2fa0e3c11fa9b03fc902aa41cc06099f8 2026/02/19 04:10:19 DEBUG : Creating backend with remote "/tmp/rclone3580451158" === RUN TestSyncOverlapWithFilter run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:10:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/rclone-sync-test" 2026/02/19 04:10:19 DEBUG : Starting OpenDrive session with ID: c31c830caad441ffd0a61a9ebbb67a6de2f44d84a62c3f81af34edf138e4ae19 2026/02/19 04:10:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/rclone-sync-test-include/layer2" 2026/02/19 04:10:21 DEBUG : Starting OpenDrive session with ID: 8f1de76fa7360cf93bc6835afc1b1a90b062ccc25405d9ec56ac902c8c658f53 2026/02/19 04:10:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/rclone-sync-test-ignore-file" 2026/02/19 04:10:23 DEBUG : Starting OpenDrive session with ID: 4731a9af615c6258c1b35c7e5cdda3f384057e88f804a426591f894bdc7ff85a 2026/02/19 04:10:25 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2026/02/19 04:10:27 DEBUG : rclone-sync-test: Excluded 2026/02/19 04:10:27 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/19 04:10:28 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/19 04:10:28 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test': Waiting for checks to finish 2026/02/19 04:10:28 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test': Waiting for transfers to finish 2026/02/19 04:10:28 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:28 INFO : There was nothing to transfer 2026/02/19 04:10:28 DEBUG : rclone-sync-test: Excluded 2026/02/19 04:10:28 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/19 04:10:29 DEBUG : rclone-sync-test-include/layer2: Excluded 2026/02/19 04:10:29 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': Waiting for checks to finish 2026/02/19 04:10:29 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': Waiting for transfers to finish 2026/02/19 04:10:29 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:29 INFO : rclone-sync-test-include: Removing directory 2026/02/19 04:10:29 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': deleted 1 directories 2026/02/19 04:10:29 INFO : There was nothing to transfer 2026/02/19 04:10:30 DEBUG : rclone-sync-test: Excluded 2026/02/19 04:10:30 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/19 04:10:30 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test-include/layer2': Waiting for checks to finish 2026/02/19 04:10:30 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test-include/layer2': Waiting for transfers to finish 2026/02/19 04:10:30 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:30 INFO : There was nothing to transfer 2026/02/19 04:10:31 DEBUG : rclone-sync-test: Excluded 2026/02/19 04:10:31 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/19 04:10:31 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': Waiting for checks to finish 2026/02/19 04:10:31 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': Waiting for transfers to finish 2026/02/19 04:10:31 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:31 INFO : There was nothing to transfer 2026/02/19 04:10:31 DEBUG : Excluded 2026/02/19 04:10:31 DEBUG : rclone-sync-test: Excluded 2026/02/19 04:10:31 DEBUG : rclone-sync-test-ignore-file: Excluded 2026/02/19 04:10:31 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test-ignore-file': Waiting for checks to finish 2026/02/19 04:10:31 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/rclone-sync-test-ignore-file': Waiting for transfers to finish 2026/02/19 04:10:31 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:31 INFO : There was nothing to transfer --- PASS: TestSyncOverlapWithFilter (16.42s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:10:36 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:10:38 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:10:40 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:10:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/dst" 2026/02/19 04:10:42 DEBUG : Starting OpenDrive session with ID: d4cc08067fac5bd8c6abfe022200badcabd1a0ebf846f4ee67f13435c4f1e9cc 2026/02/19 04:10:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/backup" 2026/02/19 04:10:43 DEBUG : Starting OpenDrive session with ID: d93fe029fcaac066c944d6462b0e5d735217b54792b38df4b1879b31060279c1 2026/02/19 04:10:44 DEBUG : one: size = 4 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:10:44 DEBUG : two: size = 3 OK 2026/02/19 04:10:44 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:10:44 DEBUG : two: Unchanged skipping 2026/02/19 04:10:44 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for checks to finish 2026/02/19 04:10:44 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:10:44 DEBUG : one: Sizes differ 2026/02/19 04:10:45 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:10:45 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for transfers to finish 2026/02/19 04:10:46 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:10:47 DEBUG : one: size = 4 OK 2026/02/19 04:10:47 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:10:47 INFO : one: Copied (new) 2026/02/19 04:10:47 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:47 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:10:47 INFO : three.txt: Moved into backup dir 2026/02/19 04:10:48 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:10:50 DEBUG : two: size = 3 OK 2026/02/19 04:10:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:10:50 DEBUG : two: Unchanged skipping 2026/02/19 04:10:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:10:50 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:10:50 DEBUG : one: Sizes differ 2026/02/19 04:10:50 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for checks to finish 2026/02/19 04:10:51 INFO : one-2019-01-01: Deleted 2026/02/19 04:10:51 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:10:51 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for transfers to finish 2026/02/19 04:10:51 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:10:53 DEBUG : one: size = 5 OK 2026/02/19 04:10:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:10:53 INFO : one: Copied (new) 2026/02/19 04:10:53 DEBUG : Waiting for deletions to finish 2026/02/19 04:10:53 INFO : three-2019-01-01.txt: Deleted 2026/02/19 04:10:53 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:10:53 INFO : three.txt: Moved into backup dir 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: listing wrong, want backup/one-2019-01-01 (4), backup/three-2019-01-01.txt (6), dst/one (5), dst/two (3) got backup/one-2019-01-01 (5), backup/three-2019-01-01.txt (6), 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: OpenDrive root 'rclone-test-vukadaf5zaba'/backup/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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Should be true Test: TestSyncBackupDirWithSuffixKeepExtension Messages: backup/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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2466 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirWithSuffixKeepExtension Messages: 1 objects not found --- FAIL: TestSyncBackupDirWithSuffixKeepExtension (29.10s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:11:05 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:11:07 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:11:09 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:11:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/dst" 2026/02/19 04:11:11 DEBUG : Starting OpenDrive session with ID: cdf33ae421303a88d9de3017852d237efe5a16f819912ceab7ef6122519e22a9 2026/02/19 04:11:11 DEBUG : one: size = 4 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:11 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:11 DEBUG : one: Sizes differ 2026/02/19 04:11:11 DEBUG : two: size = 3 OK 2026/02/19 04:11:11 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for checks to finish 2026/02/19 04:11:11 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:11:11 DEBUG : two: Unchanged skipping 2026/02/19 04:11:12 DEBUG : one: same parent dir (ODFfMTAyMzg4NTZfZHJDQ3E) - using file/rename instead of move_copy for one.bak 2026/02/19 04:11:12 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:11:12 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for transfers to finish 2026/02/19 04:11:13 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:11:14 DEBUG : one: size = 4 OK 2026/02/19 04:11:14 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:11:14 INFO : one: Copied (new) 2026/02/19 04:11:14 DEBUG : Waiting for deletions to finish 2026/02/19 04:11:14 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NTZfZHJDQ3E) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:11:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:11:14 INFO : three.txt: Moved into backup dir 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 2026/02/19 04:11:23 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:11:24 DEBUG : one.bak: Excluded (Path Filter) 2026/02/19 04:11:24 DEBUG : one.bak: Excluded 2026/02/19 04:11:24 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/02/19 04:11:24 DEBUG : three.txt.bak: Excluded 2026/02/19 04:11:24 DEBUG : one: size = 5 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:24 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:24 DEBUG : one: Sizes differ 2026/02/19 04:11:24 DEBUG : two: size = 3 OK 2026/02/19 04:11:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:11:24 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for checks to finish 2026/02/19 04:11:24 DEBUG : two: Unchanged skipping 2026/02/19 04:11:25 INFO : one.bak: Deleted 2026/02/19 04:11:25 DEBUG : one: same parent dir (ODFfMTAyMzg4NTZfZHJDQ3E) - using file/rename instead of move_copy for one.bak 2026/02/19 04:11:25 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:11:25 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba/dst': Waiting for transfers to finish 2026/02/19 04:11:25 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:11:26 DEBUG : one: size = 5 OK 2026/02/19 04:11:26 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:11:26 INFO : one: Copied (new) 2026/02/19 04:11:26 DEBUG : Waiting for deletions to finish 2026/02/19 04:11:27 INFO : three.txt.bak: Deleted 2026/02/19 04:11:27 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NTZfZHJDQ3E) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:11:27 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:11:27 INFO : three.txt: Moved into backup dir 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/asm_amd64.s:1693 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt.bak (6), dst/two (3) got dst/one.bak (5), dst/three.txt.bak (6), 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: OpenDrive root 'rclone-test-vukadaf5zaba'/dst/one.bak: 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Not equal: expected: 4 actual : 5 Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: 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:2454 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDirSuffixOnly Messages: 1 objects not found --- FAIL: TestSyncBackupDirSuffixOnly (32.91s) === RUN TestSyncSuffix run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:11:38 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:11:40 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:11:42 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:11:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/dst" 2026/02/19 04:11:43 DEBUG : Starting OpenDrive session with ID: f6a8534654b68b34e0b5c3051431c2d8b1a74ad611457eb4b08d032ecbc7b080 2026/02/19 04:11:44 DEBUG : one: size = 4 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:44 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:44 DEBUG : one: Sizes differ 2026/02/19 04:11:44 DEBUG : one: same parent dir (ODFfMTAyMzg4NTdfQ2gyZFg) - using file/rename instead of move_copy for one.bak 2026/02/19 04:11:45 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:11:45 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:11:46 DEBUG : one: size = 4 OK 2026/02/19 04:11:46 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:11:46 INFO : one: Copied (new) 2026/02/19 04:11:47 DEBUG : two: size = 3 OK 2026/02/19 04:11:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:11:47 DEBUG : two: Unchanged skipping 2026/02/19 04:11:47 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:47 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:47 DEBUG : three.txt: Sizes differ 2026/02/19 04:11:47 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NTdfQ2gyZFg) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:11:47 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:11:48 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:11:49 DEBUG : three.txt: size = 6 OK 2026/02/19 04:11:49 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2026/02/19 04:11:49 INFO : three.txt: Copied (new) 2026/02/19 04:11:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:50 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:50 DEBUG : one: Sizes differ 2026/02/19 04:11:51 INFO : one.bak: Deleted 2026/02/19 04:11:51 DEBUG : one: same parent dir (ODFfMTAyMzg4NTdfQ2gyZFg) - using file/rename instead of move_copy for one.bak 2026/02/19 04:11:51 INFO : one: Moved (server-side) to: one.bak 2026/02/19 04:11:52 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:11:53 DEBUG : one: size = 5 OK 2026/02/19 04:11:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:11:53 INFO : one: Copied (new) 2026/02/19 04:11:53 DEBUG : two: size = 3 OK 2026/02/19 04:11:53 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:11:53 DEBUG : two: Unchanged skipping 2026/02/19 04:11:54 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:11:54 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:11:54 DEBUG : three.txt: Sizes differ 2026/02/19 04:11:54 INFO : three.txt.bak: Deleted 2026/02/19 04:11:54 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NTdfQ2gyZFg) - using file/rename instead of move_copy for three.txt.bak 2026/02/19 04:11:54 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/02/19 04:11:55 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2026/02/19 04:11:56 DEBUG : three.txt: size = 19 OK 2026/02/19 04:11:56 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2026/02/19 04:11:56 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (21.45s) === RUN TestSyncSuffixKeepExtension run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:12:00 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/02/19 04:12:02 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/02/19 04:12:03 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/02/19 04:12:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vukadaf5zaba/dst" 2026/02/19 04:12:05 DEBUG : Config file has changed externally - reloading 2026/02/19 04:12:05 DEBUG : Starting OpenDrive session with ID: b6292cf07dc95f31b32c6aed14d13924928b65bb89c0b9f900b5b84be83c487e 2026/02/19 04:12:06 DEBUG : one: size = 4 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:12:06 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:12:06 DEBUG : one: Sizes differ 2026/02/19 04:12:06 DEBUG : one: same parent dir (ODFfMTAyMzg4NThfdUI0cjA) - using file/rename instead of move_copy for one-2019-01-01 2026/02/19 04:12:06 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:12:07 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/02/19 04:12:08 DEBUG : one: size = 4 OK 2026/02/19 04:12:08 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/02/19 04:12:08 INFO : one: Copied (new) 2026/02/19 04:12:08 DEBUG : two: size = 3 OK 2026/02/19 04:12:08 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:12:08 DEBUG : two: Unchanged skipping 2026/02/19 04:12:08 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:12:08 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:12:08 DEBUG : three.txt: Sizes differ 2026/02/19 04:12:09 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NThfdUI0cjA) - using file/rename instead of move_copy for three-2019-01-01.txt 2026/02/19 04:12:09 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:12:10 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2026/02/19 04:12:11 DEBUG : three.txt: size = 6 OK 2026/02/19 04:12:11 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2026/02/19 04:12:11 INFO : three.txt: Copied (new) 2026/02/19 04:12:12 DEBUG : one: size = 5 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:12:12 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:12:12 DEBUG : one: Sizes differ 2026/02/19 04:12:12 INFO : one-2019-01-01: Deleted 2026/02/19 04:12:12 DEBUG : one: same parent dir (ODFfMTAyMzg4NThfdUI0cjA) - using file/rename instead of move_copy for one-2019-01-01 2026/02/19 04:12:12 INFO : one: Moved (server-side) to: one-2019-01-01 2026/02/19 04:12:13 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/02/19 04:12:14 DEBUG : one: size = 5 OK 2026/02/19 04:12:14 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/02/19 04:12:14 INFO : one: Copied (new) 2026/02/19 04:12:14 DEBUG : two: size = 3 OK 2026/02/19 04:12:14 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/02/19 04:12:14 DEBUG : two: Unchanged skipping 2026/02/19 04:12:14 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone3580451158) 2026/02/19 04:12:14 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-vukadaf5zaba/dst') 2026/02/19 04:12:14 DEBUG : three.txt: Sizes differ 2026/02/19 04:12:15 INFO : three-2019-01-01.txt: Deleted 2026/02/19 04:12:15 DEBUG : three.txt: same parent dir (ODFfMTAyMzg4NThfdUI0cjA) - using file/rename instead of move_copy for three-2019-01-01.txt 2026/02/19 04:12:15 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/02/19 04:12:16 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2026/02/19 04:12:17 DEBUG : three.txt: size = 19 OK 2026/02/19 04:12:17 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2026/02/19 04:12:17 INFO : three.txt: Copied (new) run.go:130: removing dir "dst" failed - try 1/3: folder not empty --- PASS: TestSyncSuffixKeepExtension (21.52s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-vukadaf5zaba'", Local "Local file system at /tmp/rclone3580451158", Modify Window "1s" 2026/02/19 04:12:20 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/02/19 04:12:20 INFO : sub dir: Making directory 2026/02/19 04:12:20 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (15.61s) FAIL 2026/02/19 04:12:36 DEBUG : OpenDrive root 'rclone-test-vukadaf5zaba': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter|TestSyncSuffix|TestSyncSuffixKeepExtension)$'" - Finished ERROR in 2m18.323949054s (try 2/5): exit status 1: Failed [TestSyncBackupDirWithSuffixKeepExtension TestSyncBackupDirSuffixOnly]