"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncWithTrackRenamesStrategyModtime)$'" - Starting (try 2/5) 2026/04/26 04:48:57 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rimoruq2doza" 2026/04/26 04:48:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/04/26 04:48:57 DEBUG : Starting OpenDrive session with ID: fb8396e28b58a7c9d94c19614b9c8b0ea2393269fd8b30973fb88647e8065052 2026/04/26 04:48:57 DEBUG : Creating backend with remote "/tmp/rclone1828780279" === RUN TestSyncWithTrackRenamesStrategyModtime run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" sync_test.go:1641: Can track renames: true 2026/04/26 04:48:58 INFO : OpenDrive root 'rclone-test-rimoruq2doza': Making map for --track-renames 2026/04/26 04:48:58 INFO : OpenDrive root 'rclone-test-rimoruq2doza': Finished making map for --track-renames 2026/04/26 04:48:58 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for checks to finish 2026/04/26 04:48:58 DEBUG : potato: Need to transfer - No matching file found at Destination 2026/04/26 04:48:58 DEBUG : yam: Need to transfer - No matching file found at Destination 2026/04/26 04:48:58 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for renames to finish 2026/04/26 04:48:58 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for transfers to finish 2026/04/26 04:48:59 DEBUG : potato: Uploading chunk 0, size=14, remain=0 2026/04/26 04:48:59 DEBUG : yam: Uploading chunk 0, size=11, remain=0 2026/04/26 04:49:00 DEBUG : potato: size = 14 OK 2026/04/26 04:49:00 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2026/04/26 04:49:00 INFO : potato: Copied (new) 2026/04/26 04:49:00 DEBUG : yam: size = 11 OK 2026/04/26 04:49:00 DEBUG : yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2026/04/26 04:49:00 INFO : yam: Copied (new) 2026/04/26 04:49:00 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:00 DEBUG : potato: size = 14 OK 2026/04/26 04:49:00 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/26 04:49:00 INFO : OpenDrive root 'rclone-test-rimoruq2doza': Making map for --track-renames 2026/04/26 04:49:00 INFO : OpenDrive root 'rclone-test-rimoruq2doza': Finished making map for --track-renames 2026/04/26 04:49:00 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for checks to finish 2026/04/26 04:49:00 DEBUG : potato: Unchanged skipping 2026/04/26 04:49:00 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for renames to finish 2026/04/26 04:49:01 DEBUG : yam: same parent dir (ODFfMTE4MTQxMTVfQ2hyMzI) - using file/rename instead of move_copy for yaml 2026/04/26 04:49:01 INFO : yam: Moved (server-side) to: yaml 2026/04/26 04:49:01 INFO : yaml: Renamed from "yam" 2026/04/26 04:49:01 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for transfers to finish 2026/04/26 04:49:01 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:01 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (4.30s) === RUN TestSyncBackupDirWithSuffix run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" 2026/04/26 04:49:03 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/04/26 04:49:05 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/04/26 04:49:06 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2026/04/26 04:49:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rimoruq2doza/dst" 2026/04/26 04:49:08 DEBUG : Starting OpenDrive session with ID: d2e32298a6c1d2b91fed365d49c071c17e8c278e33f53b25f47e9ce5684ba205 2026/04/26 04:49:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rimoruq2doza/backup" 2026/04/26 04:49:09 DEBUG : Starting OpenDrive session with ID: 800a36ab115769bd0a5ec7ad498de7c337c2885cd562907248bea97c9de1bd33 2026/04/26 04:49:10 DEBUG : one: size = 4 (Local file system at /tmp/rclone1828780279) 2026/04/26 04:49:10 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-rimoruq2doza/dst') 2026/04/26 04:49:10 DEBUG : one: Sizes differ 2026/04/26 04:49:10 DEBUG : two: size = 3 OK 2026/04/26 04:49:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/26 04:49:10 DEBUG : two: Unchanged skipping 2026/04/26 04:49:10 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for checks to finish 2026/04/26 04:49:11 INFO : one: Moved (server-side) to: one.bak 2026/04/26 04:49:11 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for transfers to finish 2026/04/26 04:49:12 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/04/26 04:49:13 DEBUG : one: size = 4 OK 2026/04/26 04:49:13 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/04/26 04:49:13 INFO : one: Copied (new) 2026/04/26 04:49:13 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:13 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/26 04:49:13 INFO : three.txt: Moved into backup dir 2026/04/26 04:49:14 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2026/04/26 04:49:16 DEBUG : one: size = 5 (Local file system at /tmp/rclone1828780279) 2026/04/26 04:49:16 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-rimoruq2doza/dst') 2026/04/26 04:49:16 DEBUG : one: Sizes differ 2026/04/26 04:49:16 DEBUG : two: size = 3 OK 2026/04/26 04:49:16 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/26 04:49:16 DEBUG : two: Unchanged skipping 2026/04/26 04:49:16 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for checks to finish 2026/04/26 04:49:16 INFO : one.bak: Deleted 2026/04/26 04:49:17 INFO : one: Moved (server-side) to: one.bak 2026/04/26 04:49:17 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for transfers to finish 2026/04/26 04:49:17 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/04/26 04:49:18 DEBUG : one: size = 5 OK 2026/04/26 04:49:18 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/04/26 04:49:18 INFO : one: Copied (new) 2026/04/26 04:49:18 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:19 INFO : three.txt.bak: Deleted 2026/04/26 04:49:19 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/26 04:49:19 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.70s) === RUN TestSyncBackupDirSuffixOnly run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" 2026/04/26 04:49:23 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2026/04/26 04:49:24 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2026/04/26 04:49:26 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 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:2408 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: dst/three.txt: Modification time difference too big |-221112h44m19.500000001s| > 1s (want 2001-02-03 04:05:06.499999999 +0000 UTC vs got 2026-04-26 04:49:26 +0000 UTC) (precision 1s) 2026/04/26 04:49:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rimoruq2doza/dst" 2026/04/26 04:49:28 DEBUG : Starting OpenDrive session with ID: eaf4dc46cc19d702525f42f1f56a4e7aeba0f6b05ae96431942c231762ef75e7 2026/04/26 04:49:28 DEBUG : one: size = 4 (Local file system at /tmp/rclone1828780279) 2026/04/26 04:49:28 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-rimoruq2doza/dst') 2026/04/26 04:49:28 DEBUG : one: Sizes differ 2026/04/26 04:49:28 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for checks to finish 2026/04/26 04:49:28 DEBUG : two: size = 3 OK 2026/04/26 04:49:28 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/26 04:49:28 DEBUG : two: Unchanged skipping 2026/04/26 04:49:29 DEBUG : one: same parent dir (ODFfMTE4MTQxMjBfemNCOTI) - using file/rename instead of move_copy for one.bak 2026/04/26 04:49:29 INFO : one: Moved (server-side) to: one.bak 2026/04/26 04:49:29 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for transfers to finish 2026/04/26 04:49:29 DEBUG : one: Uploading chunk 0, size=4, remain=0 2026/04/26 04:49:30 DEBUG : one: size = 4 OK 2026/04/26 04:49:30 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2026/04/26 04:49:30 INFO : one: Copied (new) 2026/04/26 04:49:30 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:31 DEBUG : three.txt: same parent dir (ODFfMTE4MTQxMjBfemNCOTI) - using file/rename instead of move_copy for three.txt.bak 2026/04/26 04:49:31 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/26 04:49:31 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:2429 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDirSuffixOnly Messages: listing wrong, want dst/one (4), dst/one.bak (3), dst/three.txt.bak (5), dst/two (3) got dst/one.bak (4), dst/three.txt.bak (5), 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:2429 /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-rimoruq2doza'/dst/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" 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:2429 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: size incorrect file=3 vs obj=4 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:2429 /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 |-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: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:2429 /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 2026/04/26 04:49:40 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 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:2435 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncBackupDirSuffixOnly 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: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:2435 /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-rimoruq2doza'/dst/one.bak: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" 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:2435 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2470 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDirSuffixOnly Messages: dst/one.bak: size incorrect file=3 vs obj=4 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:2435 /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 |-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: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:2435 /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 2026/04/26 04:49:50 DEBUG : one.bak: Excluded (Path Filter) 2026/04/26 04:49:50 DEBUG : one.bak: Excluded 2026/04/26 04:49:50 DEBUG : three.txt.bak: Excluded (Path Filter) 2026/04/26 04:49:50 DEBUG : three.txt.bak: Excluded 2026/04/26 04:49:50 DEBUG : one: Need to transfer - File not found at Destination 2026/04/26 04:49:50 DEBUG : two: size = 3 OK 2026/04/26 04:49:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2026/04/26 04:49:50 DEBUG : two: Unchanged skipping 2026/04/26 04:49:50 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for checks to finish 2026/04/26 04:49:50 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza/dst': Waiting for transfers to finish 2026/04/26 04:49:51 DEBUG : one: Uploading chunk 0, size=5, remain=0 2026/04/26 04:49:52 DEBUG : one: size = 5 OK 2026/04/26 04:49:52 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2026/04/26 04:49:52 INFO : one: Copied (new) 2026/04/26 04:49:52 DEBUG : Waiting for deletions to finish 2026/04/26 04:49:52 INFO : three.txt.bak: Deleted 2026/04/26 04:49:52 DEBUG : three.txt: same parent dir (ODFfMTE4MTQxMjBfemNCOTI) - using file/rename instead of move_copy for three.txt.bak 2026/04/26 04:49:52 INFO : three.txt: Moved (server-side) to: three.txt.bak 2026/04/26 04:49:52 INFO : three.txt: Moved into backup dir --- FAIL: TestSyncBackupDirSuffixOnly (33.05s) === RUN TestNothingToTransferWithEmptyDirs run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" 2026/04/26 04:49:54 INFO : sub dir: Set directory modification time (using DirSetModTime) 2026/04/26 04:49:54 INFO : sub dir: Making directory 2026/04/26 04:49:55 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (14.79s) === RUN TestManualTransformFile run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" 2026/04/26 04:50:09 DEBUG : Reset feature "Copy" 2026/04/26 04:50:09 DEBUG : Reset feature "Move" 2026/04/26 04:50:09 DEBUG : Reset feature "Copy" 2026/04/26 04:50:09 DEBUG : Reset feature "Move" 2026/04/26 04:50:09 DEBUG : empty_dir: Making directory with metadata 2026/04/26 04:50:09 INFO : empty_dir: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/04/26 04:50:09 DEBUG : toe: transformed to: tictactoe 2026/04/26 04:50:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/26 04:50:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/26 04:50:09 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/26 04:50:09 INFO : tictacempty_dir: Making directory 2026/04/26 04:50:10 DEBUG : empty_dir: transformed to: tictacempty_dir 2026/04/26 04:50:10 DEBUG : toe: transformed to: tictactoe 2026/04/26 04:50:10 DEBUG : toe: transformed to: tictactoe 2026/04/26 04:50:10 INFO : tictactoe: Making directory 2026/04/26 04:50:10 DEBUG : toe: transformed to: tictactoe 2026/04/26 04:50:10 DEBUG : toe: transformed to: tictactoe 2026/04/26 04:50:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/26 04:50:10 INFO : tictactoe/tictactoe: Making directory 2026/04/26 04:50:10 DEBUG : toe/toe: transformed to: tictactoe/tictactoe 2026/04/26 04:50:10 DEBUG : toe.txt: transformed to: tictactoe.txt 2026/04/26 04:50:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/26 04:50:10 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/26 04:50:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/26 04:50:10 DEBUG : toe/toe/toe.txt: transformed to: tictactoe/tictactoe/tictactoe.txt 2026/04/26 04:50:10 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for checks to finish 2026/04/26 04:50:10 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for transfers to finish 2026/04/26 04:50:11 DEBUG : tictactoe/tictactoe/tictactoe.txt: Uploading chunk 0, size=11, remain=0 2026/04/26 04:50:12 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/26 04:50:12 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/26 04:50:12 INFO : toe/toe/toe.txt: Copied (new) to: tictactoe/tictactoe/tictactoe.txt 2026/04/26 04:50:12 INFO : toe/toe/toe.txt: Deleted 2026/04/26 04:50:12 INFO : toe/toe: Removing directory 2026/04/26 04:50:12 INFO : toe: Removing directory 2026/04/26 04:50:12 INFO : empty_dir: Removing directory 2026/04/26 04:50:12 DEBUG : Local file system at /tmp/rclone1828780279: deleted 3 directories 2026/04/26 04:50:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: size = 11 OK 2026/04/26 04:50:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: Size and modification time the same (differ by 0s, within tolerance 1s) 2026/04/26 04:50:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/26 04:50:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/26 04:50:13 DEBUG : tictactoe/tictactoe/tictactoe.txt: transformed to: toe/toe/toe.txt 2026/04/26 04:50:15 DEBUG : toe/toe/toe.txt: Uploading chunk 0, size=11, remain=0 2026/04/26 04:50:16 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/04/26 04:50:16 DEBUG : tictactoe/tictactoe/tictactoe.txt: Src hash empty - aborting Dst hash check 2026/04/26 04:50:16 INFO : tictactoe/tictactoe/tictactoe.txt: Copied (new) to: toe/toe/toe.txt 2026/04/26 04:50:16 INFO : tictactoe/tictactoe/tictactoe.txt: Deleted --- PASS: TestManualTransformFile (10.02s) === RUN TestBase64 run.go:185: Remote "OpenDrive root 'rclone-test-rimoruq2doza'", Local "Local file system at /tmp/rclone1828780279", Modify Window "1s" 2026/04/26 04:50:19 DEBUG : toe: transformed to: dG9l 2026/04/26 04:50:19 DEBUG : toe: transformed to: dG9l 2026/04/26 04:50:19 INFO : dG9l: Making directory 2026/04/26 04:50:20 DEBUG : toe: transformed to: dG9l 2026/04/26 04:50:20 DEBUG : toe: transformed to: dG9l 2026/04/26 04:50:20 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/04/26 04:50:20 INFO : dG9l/dG9l: Making directory 2026/04/26 04:50:20 DEBUG : toe/toe: transformed to: dG9l/dG9l 2026/04/26 04:50:20 DEBUG : toe.txt: transformed to: dG9lLnR4dA== 2026/04/26 04:50:20 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/04/26 04:50:20 DEBUG : toe/toe/toe.txt: Need to transfer - File not found at Destination 2026/04/26 04:50:20 DEBUG : toe/toe/toe.txt: transformed to: dG9l/dG9l/dG9lLnR4dA== 2026/04/26 04:50:20 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for checks to finish 2026/04/26 04:50:20 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Waiting for transfers to finish 2026/04/26 04:50:21 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Uploading chunk 0, size=11, remain=0 2026/04/26 04:50:22 DEBUG : dG9l/dG9l/dG9lLnR4dA==: size = 11 OK 2026/04/26 04:50:22 DEBUG : toe/toe/toe.txt: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2026/04/26 04:50:22 INFO : toe/toe/toe.txt: Copied (new) to: dG9l/dG9l/dG9lLnR4dA== 2026/04/26 04:50:22 DEBUG : Waiting for deletions to finish 2026/04/26 04:50:23 DEBUG : dG9l: transformed to: toe 2026/04/26 04:50:23 DEBUG : dG9l: transformed to: toe 2026/04/26 04:50:23 DEBUG : toe: Directory modification time the same (differ by -275.269732ms, within tolerance 1s) 2026/04/26 04:50:23 DEBUG : Added delayed dir = "toe", newDst=toe 2026/04/26 04:50:23 DEBUG : dG9l: transformed to: toe 2026/04/26 04:50:23 DEBUG : dG9l/dG9l: transformed to: toe/toe 2026/04/26 04:50:23 INFO : toe/toe: Set directory modification time (using SetModTime) 2026/04/26 04:50:23 DEBUG : Added delayed dir = "toe/toe", newDst=toe/toe 2026/04/26 04:50:23 DEBUG : dG9lLnR4dA==: transformed to: toe.txt 2026/04/26 04:50:23 DEBUG : Local file system at /tmp/rclone1828780279: Waiting for checks to finish 2026/04/26 04:50:23 DEBUG : toe/toe/toe.txt: size = 11 OK 2026/04/26 04:50:23 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Size and modification time the same (differ by 499.999999ms, within tolerance 1s) 2026/04/26 04:50:23 DEBUG : dG9l/dG9l/dG9lLnR4dA==: transformed to: toe/toe/toe.txt 2026/04/26 04:50:23 DEBUG : dG9l/dG9l/dG9lLnR4dA==: Unchanged skipping 2026/04/26 04:50:23 DEBUG : Local file system at /tmp/rclone1828780279: Waiting for transfers to finish 2026/04/26 04:50:23 DEBUG : Waiting for deletions to finish --- PASS: TestBase64 (5.74s) FAIL 2026/04/26 04:50:25 DEBUG : OpenDrive root 'rclone-test-rimoruq2doza': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBase64|TestManualTransformFile|TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncWithTrackRenamesStrategyModtime)$'" - Finished ERROR in 1m28.902146359s (try 2/5): exit status 1: Failed [TestSyncBackupDirSuffixOnly]