"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncWithTrackRenames)$'" - Starting (try 2/5) 2026/09/08 01:26:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe" 2026/09/08 01:26:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/08 01:26:10 DEBUG : Starting OpenDrive session with ID: 2f334c58e08e2d2d5123fc5de48c53dfa62f140be113c80074cd04e46a45a2fd 2026/09/08 01:26:10 DEBUG : Creating backend with remote "/tmp/rclone679531781" === RUN TestSyncWithTrackRenames run.go:198: Remote "OpenDrive root 'rclone-test-nefuhun4wuqe'", Local "Local file system at /tmp/rclone679531781", Modify Window "1s" sync_test.go:1569: Can track renames: true 2026/09/08 01:26:11 INFO : OpenDrive root 'rclone-test-nefuhun4wuqe': Making map for --track-renames 2026/09/08 01:26:11 INFO : OpenDrive root 'rclone-test-nefuhun4wuqe': Finished making map for --track-renames 2026/09/08 01:26:11 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for checks to finish 2026/09/08 01:26:11 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/09/08 01:26:11 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/09/08 01:26:11 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for renames to finish 2026/09/08 01:26:11 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for transfers to finish 2026/09/08 01:26:12 DEBUG : potato: Uploading chunk 0, size=14, remain=0 2026/09/08 01:26:13 DEBUG : potato: size = 14 OK 2026/09/08 01:26:13 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2026/09/08 01:26:13 INFO : potato: Copied (new) 2026/09/08 01:26:13 DEBUG : yam: Uploading chunk 0, size=11, remain=0 2026/09/08 01:26:14 DEBUG : yam: size = 11 OK 2026/09/08 01:26:14 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2026/09/08 01:26:14 INFO : yam: Copied (new) 2026/09/08 01:26:14 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:15 DEBUG : potato: size = 14 OK 2026/09/08 01:26:15 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:26:15 DEBUG : potato: Unchanged skipping 2026/09/08 01:26:15 INFO : OpenDrive root 'rclone-test-nefuhun4wuqe': Making map for --track-renames 2026/09/08 01:26:15 INFO : OpenDrive root 'rclone-test-nefuhun4wuqe': Finished making map for --track-renames 2026/09/08 01:26:15 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for checks to finish 2026/09/08 01:26:15 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for renames to finish 2026/09/08 01:26:15 DEBUG : yam: same parent dir (ODFfMTMyNDE1OTZfMVJsVDU) - using file/rename instead of move_copy for yaml 2026/09/08 01:26:15 INFO : yam: Moved (server-side) to: yaml 2026/09/08 01:26:15 INFO : yaml: Renamed from "yam" 2026/09/08 01:26:15 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Waiting for transfers to finish 2026/09/08 01:26:15 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:15 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenames (5.78s) === RUN TestSyncBackupDir run.go:198: Remote "OpenDrive root 'rclone-test-nefuhun4wuqe'", Local "Local file system at /tmp/rclone679531781", Modify Window "1s" 2026/09/08 01:26:17 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:26:19 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:26:21 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:26:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/dst" 2026/09/08 01:26:22 DEBUG : Starting OpenDrive session with ID: afb7124bebbbecc8002f1956f58255892a6ab7819a60ae9e59c435a464f4d989 2026/09/08 01:26:23 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/backup" 2026/09/08 01:26:23 DEBUG : Starting OpenDrive session with ID: 1f4baadfc9a0c9943384b8640fabf4c4ae8f0d37c4c4c1a2d95523ef9ff67b8e 2026/09/08 01:26:24 DEBUG : one: size = 4 (Local file system at /tmp/rclone679531781) 2026/09/08 01:26:24 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:26:24 DEBUG : one: Sizes differ 2026/09/08 01:26:24 DEBUG : two: size = 3 OK 2026/09/08 01:26:24 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:26:24 DEBUG : two: Unchanged skipping 2026/09/08 01:26:24 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:26:25 INFO : one: Moved (server-side) 2026/09/08 01:26:25 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:26:26 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:26:27 DEBUG : one: size = 4 OK 2026/09/08 01:26:27 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:26:27 INFO : one: Copied (new) 2026/09/08 01:26:27 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:28 INFO : three.txt: Moved (server-side) 2026/09/08 01:26:28 INFO : three.txt: Moved into backup dir 2026/09/08 01:26:29 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:26:30 DEBUG : one: size = 5 (Local file system at /tmp/rclone679531781) 2026/09/08 01:26:30 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:26:30 DEBUG : one: Sizes differ 2026/09/08 01:26:30 DEBUG : two: size = 3 OK 2026/09/08 01:26:30 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:26:30 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:26:30 DEBUG : two: Unchanged skipping 2026/09/08 01:26:31 INFO : one: Deleted 2026/09/08 01:26:31 INFO : one: Moved (server-side) 2026/09/08 01:26:31 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:26:32 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:26:33 DEBUG : one: size = 5 OK 2026/09/08 01:26:33 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:26:33 INFO : one: Copied (new) 2026/09/08 01:26:33 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:34 INFO : three.txt: Deleted 2026/09/08 01:26:34 INFO : three.txt: Moved (server-side) 2026/09/08 01:26:34 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (20.10s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:198: Remote "OpenDrive root 'rclone-test-nefuhun4wuqe'", Local "Local file system at /tmp/rclone679531781", Modify Window "1s" 2026/09/08 01:26:37 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:26:39 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:26:41 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:26:42 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/dst" 2026/09/08 01:26:42 DEBUG : Starting OpenDrive session with ID: a69b41f0ddd3b903ed9805553f247492fa52c3ffd15c4620d9750adba4806baa 2026/09/08 01:26:43 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/backup" 2026/09/08 01:26:43 DEBUG : Starting OpenDrive session with ID: 368d1df5f8edf70a4e90a50877b23747300fb453d80667f7ffdff06324267b28 2026/09/08 01:26:44 DEBUG : one: size = 4 (Local file system at /tmp/rclone679531781) 2026/09/08 01:26:44 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:26:44 DEBUG : one: Sizes differ 2026/09/08 01:26:44 DEBUG : two: size = 3 OK 2026/09/08 01:26:44 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:26:44 DEBUG : two: Unchanged skipping 2026/09/08 01:26:44 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:26:45 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/08 01:26:45 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:26:46 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:26:47 DEBUG : one: size = 4 OK 2026/09/08 01:26:47 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:26:47 INFO : one: Copied (new) 2026/09/08 01:26:47 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:47 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/08 01:26:47 INFO : three.txt: Moved into backup dir 2026/09/08 01:26:49 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:26:50 DEBUG : one: size = 5 (Local file system at /tmp/rclone679531781) 2026/09/08 01:26:50 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:26:50 DEBUG : one: Sizes differ 2026/09/08 01:26:50 DEBUG : two: size = 3 OK 2026/09/08 01:26:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:26:50 DEBUG : two: Unchanged skipping 2026/09/08 01:26:50 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:26:51 INFO : one-2019-01-01: Deleted 2026/09/08 01:26:51 INFO : one: Moved (server-side) to: one-2019-01-01 2026/09/08 01:26:51 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:26:52 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:26:53 DEBUG : one: size = 5 OK 2026/09/08 01:26:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:26:53 INFO : one: Copied (new) 2026/09/08 01:26:53 DEBUG : Waiting for deletions to finish 2026/09/08 01:26:53 INFO : three-2019-01-01.txt: Deleted 2026/09/08 01:26:54 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2026/09/08 01:26:54 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /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 /usr/local/go/src/runtime/asm_amd64.s:1264 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:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /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-nefuhun4wuqe'/backup/one-2019-01-01: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" fstest.go:151: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /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:128: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:128 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:133 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:152 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /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:204: Not found "dst/one" fstest.go:207: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:207 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:311 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /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 (27.67s) === RUN TestSyncBackupDirSuffixOnly run.go:198: Remote "OpenDrive root 'rclone-test-nefuhun4wuqe'", Local "Local file system at /tmp/rclone679531781", Modify Window "1s" 2026/09/08 01:27:05 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:27:07 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:27:08 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:27:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/dst" 2026/09/08 01:27:10 DEBUG : Starting OpenDrive session with ID: e3ff77221a29cc4e98b5b332ae5fe34c939064e400b52e72e6be60a871c592ac 2026/09/08 01:27:11 DEBUG : one: size = 4 (Local file system at /tmp/rclone679531781) 2026/09/08 01:27:11 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:27:11 DEBUG : one: Sizes differ 2026/09/08 01:27:11 DEBUG : two: size = 3 OK 2026/09/08 01:27:11 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:27:11 DEBUG : two: Unchanged skipping 2026/09/08 01:27:11 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:27:11 DEBUG : one: same parent dir (ODFfMTMyNDE2MDVfTlFMVm0) - using file/rename instead of move_copy for one.bak 2026/09/08 01:27:12 INFO : one: Moved (server-side) to: one.bak 2026/09/08 01:27:12 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:27:12 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:27:13 DEBUG : one: size = 4 OK 2026/09/08 01:27:13 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:27:13 INFO : one: Copied (new) 2026/09/08 01:27:13 DEBUG : Waiting for deletions to finish 2026/09/08 01:27:14 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MDVfTlFMVm0) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:27:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:27:14 INFO : three.txt: Moved into backup dir 2026/09/08 01:27:15 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:27:17 DEBUG : one.bak: Excluded (Path Filter) 2026/09/08 01:27:17 DEBUG : one.bak: Excluded 2026/09/08 01:27:17 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/09/08 01:27:17 DEBUG : three.txt.bak: Excluded 2026/09/08 01:27:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone679531781) 2026/09/08 01:27:17 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:27:17 DEBUG : one: Sizes differ 2026/09/08 01:27:17 DEBUG : two: size = 3 OK 2026/09/08 01:27:17 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:27:17 DEBUG : two: Unchanged skipping 2026/09/08 01:27:17 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for checks to finish 2026/09/08 01:27:17 INFO : one.bak: Deleted 2026/09/08 01:27:18 DEBUG : one: same parent dir (ODFfMTMyNDE2MDVfTlFMVm0) - using file/rename instead of move_copy for one.bak 2026/09/08 01:27:18 INFO : one: Moved (server-side) to: one.bak 2026/09/08 01:27:18 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe/dst': Waiting for transfers to finish 2026/09/08 01:27:19 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:27:20 DEBUG : one: size = 5 OK 2026/09/08 01:27:20 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:27:20 INFO : one: Copied (new) 2026/09/08 01:27:20 DEBUG : Waiting for deletions to finish 2026/09/08 01:27:20 INFO : three.txt.bak: Deleted 2026/09/08 01:27:20 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MDVfTlFMVm0) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:27:21 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:27:21 INFO : three.txt: Moved into backup dir fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:293: Sleeping for 6s just to make sure --- PASS: TestSyncBackupDirSuffixOnly (26.39s) === RUN TestSyncSuffix run.go:198: Remote "OpenDrive root 'rclone-test-nefuhun4wuqe'", Local "Local file system at /tmp/rclone679531781", Modify Window "1s" 2026/09/08 01:27:31 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/09/08 01:27:33 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/09/08 01:27:35 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/09/08 01:27:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nefuhun4wuqe/dst" 2026/09/08 01:27:37 DEBUG : Starting OpenDrive session with ID: 1f0367435cf7c2b2922d459eb6d073992a0affffddb7bf1383dd2b3d1b8ed71f 2026/09/08 01:27:37 DEBUG : one: size = 4 (Local file system at /tmp/rclone679531781) 2026/09/08 01:27:37 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:27:37 DEBUG : one: Sizes differ 2026/09/08 01:27:38 DEBUG : one: same parent dir (ODFfMTMyNDE2MDlfYVU2WFU) - using file/rename instead of move_copy for one.bak 2026/09/08 01:27:38 INFO : one: Moved (server-side) to: one.bak 2026/09/08 01:27:38 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/09/08 01:27:40 DEBUG : one: size = 4 OK 2026/09/08 01:27:40 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/09/08 01:27:40 INFO : one: Copied (new) 2026/09/08 01:27:40 DEBUG : two: size = 3 OK 2026/09/08 01:27:40 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:27:40 DEBUG : two: Unchanged skipping 2026/09/08 01:27:40 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone679531781) 2026/09/08 01:27:40 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:27:40 DEBUG : three.txt: Sizes differ 2026/09/08 01:27:41 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MDlfYVU2WFU) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:27:41 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:27:42 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2026/09/08 01:27:43 DEBUG : three.txt: size = 6 OK 2026/09/08 01:27:43 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2026/09/08 01:27:43 INFO : three.txt: Copied (new) fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2522 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 /usr/local/go/src/runtime/asm_amd64.s:1264 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got dst/one.bak (4), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2522 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: OpenDrive root 'rclone-test-nefuhun4wuqe'/dst/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:151: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2522 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 3 actual : 4 Test: TestSyncSuffix Messages: dst/one.bak: size incorrect file=3 vs obj=4 fstest.go:128: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:128 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:133 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:152 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2522 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: dst/one.bak: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:207 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:311 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2522 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffix Messages: 1 objects not found fstest.go:299: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:302: Flushing the directory cache fstest.go:299: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:302: Flushing the directory cache fstest.go:306: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:306 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2528 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 /usr/local/go/src/runtime/asm_amd64.s:1264 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) got dst/one.bak (4), dst/three.txt (6), dst/three.txt.bak (5), dst/two (3) fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2528 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: OpenDrive root 'rclone-test-nefuhun4wuqe'/dst/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:151: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:151 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2528 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 3 actual : 4 Test: TestSyncSuffix Messages: dst/one.bak: size incorrect file=3 vs obj=4 fstest.go:128: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:128 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:133 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:152 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:196 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:309 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2528 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Should be true Test: TestSyncSuffix Messages: dst/one.bak: Modification time difference too big |-95456h54m52.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2011-12-25 12:59:59 +0000 UTC) (precision 1s) fstest.go:204: Not found "dst/one" fstest.go:207: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:207 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:311 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:339 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:351 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2528 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2554 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffix Messages: 1 objects not found 2026/09/08 01:28:00 DEBUG : one: Need to transfer - File not found at Destination 2026/09/08 01:28:01 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/09/08 01:28:01 DEBUG : one: size = 5 OK 2026/09/08 01:28:01 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/09/08 01:28:01 INFO : one: Copied (new) 2026/09/08 01:28:02 DEBUG : two: size = 3 OK 2026/09/08 01:28:02 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/09/08 01:28:02 DEBUG : two: Unchanged skipping 2026/09/08 01:28:02 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone679531781) 2026/09/08 01:28:02 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-nefuhun4wuqe/dst') 2026/09/08 01:28:02 DEBUG : three.txt: Sizes differ 2026/09/08 01:28:03 INFO : three.txt.bak: Deleted 2026/09/08 01:28:03 DEBUG : three.txt: same parent dir (ODFfMTMyNDE2MDlfYVU2WFU) - using file/rename instead of move_copy for three.txt.bak 2026/09/08 01:28:03 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/09/08 01:28:04 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2026/09/08 01:28:05 DEBUG : three.txt: size = 19 OK 2026/09/08 01:28:05 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2026/09/08 01:28:05 INFO : three.txt: Copied (new) run.go:186: removing dir "dst" failed - try 1/3: sleeping 1s: folder not empty --- FAIL: TestSyncSuffix (37.91s) FAIL 2026/09/08 01:28:08 DEBUG : OpenDrive root 'rclone-test-nefuhun4wuqe': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncSuffix|TestSyncWithTrackRenames)$'" - Finished ERROR in 1m59.164148966s (try 2/5): exit status 1: Failed [TestSyncBackupDirWithSuffixKeepExtension TestSyncSuffix]