"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter)$|^TestTransform$/^NFC$'" - Starting (try 2/5) 2025/06/21 03:57:46 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi" 2025/06/21 03:57:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/06/21 03:57:47 DEBUG : Starting OpenDrive session with ID: 929f1b66d07ae547455f776a80245915d70df65bb9aeb3630bb5830ef642fe7f 2025/06/21 03:57:47 DEBUG : Creating backend with remote "/tmp/rclone713981014" === RUN TestServerSideCopyOverSelf run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:57:49 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/21 03:57:50 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-hifijur6vole" 2025/06/21 03:57:50 DEBUG : Starting OpenDrive session with ID: f42aca1477274494c4865fa492fd44b82fdd45f5f03ea99626b7ca874f15fc1d sync_test.go:627: Server side copy (if possible) OpenDrive root 'rclone-test-getifip0zugi' -> OpenDrive root 'rclone-test-hifijur6vole' 2025/06/21 03:57:51 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/21 03:57:51 DEBUG : OpenDrive root 'rclone-test-hifijur6vole': Waiting for checks to finish 2025/06/21 03:57:51 DEBUG : OpenDrive root 'rclone-test-hifijur6vole': Waiting for transfers to finish 2025/06/21 03:57:52 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/21 03:57:52 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/21 03:57:53 DEBUG : sub dir/hello world: Uploading chunk 0, size=17, remain=0 2025/06/21 03:57:54 DEBUG : OpenDrive root 'rclone-test-hifijur6vole': Waiting for checks to finish 2025/06/21 03:57:54 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11) 2025/06/21 03:57:54 DEBUG : OpenDrive root 'rclone-test-hifijur6vole': Waiting for transfers to finish 2025/06/21 03:57:55 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/06/21 03:57:55 INFO : sub dir/hello world: Copied (server-side copy) 2025/06/21 03:57:55 DEBUG : OpenDrive root 'rclone-test-hifijur6vole': Purge remote --- PASS: TestServerSideCopyOverSelf (9.39s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:57:57 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/06/21 03:57:57 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/06/21 03:57:57 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for checks to finish 2025/06/21 03:57:57 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for transfers to finish 2025/06/21 03:57:58 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0 2025/06/21 03:57:59 DEBUG : nested/sub dir/file: Uploading chunk 0, size=6, remain=0 2025/06/21 03:57:59 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/06/21 03:57:59 INFO : sub dir/hello world: Copied (new) 2025/06/21 03:57:59 INFO : sub dir/hello world: Deleted 2025/06/21 03:58:00 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2025/06/21 03:58:00 INFO : nested/sub dir/file: Copied (new) 2025/06/21 03:58:00 INFO : nested/sub dir/file: Deleted run.go:125: removing dir "sub dir" failed - try 1/3: folder not empty --- PASS: TestMoveWithoutDeleteEmptySrcDirs (6.76s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:58:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/rclone-sync-test" 2025/06/21 03:58:04 DEBUG : Starting OpenDrive session with ID: d78ebe95b23db0d3c0db5d20e7cee695434500524e172c4b10d9d2e473b1109c 2025/06/21 03:58:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/rclone-sync-test-include/layer2" 2025/06/21 03:58:05 DEBUG : Starting OpenDrive session with ID: 92c7281e608b2613931036238f054ef2fe7468397f73aff404f9489337b9d9dc 2025/06/21 03:58:07 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/rclone-sync-test-ignore-file" 2025/06/21 03:58:07 DEBUG : Starting OpenDrive session with ID: 92c30e03671b57df9094408b165f44df5b21f2409d7533f1068b75552fe484bd 2025/06/21 03:58:09 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2025/06/21 03:58:11 DEBUG : rclone-sync-test: Excluded 2025/06/21 03:58:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/21 03:58:11 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/06/21 03:58:11 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/rclone-sync-test': Waiting for checks to finish 2025/06/21 03:58:11 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/rclone-sync-test': Waiting for transfers to finish 2025/06/21 03:58:11 DEBUG : Waiting for deletions to finish 2025/06/21 03:58:11 INFO : There was nothing to transfer 2025/06/21 03:58:12 DEBUG : rclone-sync-test: Excluded 2025/06/21 03:58:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/06/21 03:58:12 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/06/21 03:58:12 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for checks to finish 2025/06/21 03:58:12 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for transfers to finish 2025/06/21 03:58:12 DEBUG : Waiting for deletions to finish 2025/06/21 03:58:12 INFO : rclone-sync-test-include: Removing directory 2025/06/21 03:58:13 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': deleted 1 directories 2025/06/21 03:58:13 INFO : There was nothing to transfer 2025/06/21 03:58:13 ERROR : OpenDrive root 'rclone-test-getifip0zugi': Failed to discover whether directory is included: failed to get folder list: Folder is already deleted (Error 403) sync_test.go:2004: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2004 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2030 Error: Received unexpected error: can't sync or move files on overlapping remotes (try excluding the destination with a filter rule) Test: TestSyncOverlapWithFilter run.go:125: removing dir "rclone-sync-test-include" failed - try 1/3: Folder is already deleted (Error 403) run.go:125: removing dir "rclone-sync-test-include" failed - try 2/3: Folder is already deleted (Error 403) run.go:125: removing dir "rclone-sync-test-include" failed - try 3/3: Folder is already deleted (Error 403) run.go:128: removing dir "rclone-sync-test-include" failed: Folder is already deleted (Error 403) --- FAIL: TestSyncOverlapWithFilter (14.68s) === RUN TestSyncBackupDir run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:58:19 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/06/21 03:58:21 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/06/21 03:58:22 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/06/21 03:58:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/dst" 2025/06/21 03:58:24 DEBUG : Starting OpenDrive session with ID: 78c434de0a44cb26596819fce8da8bce4785c46dcf5afd776bfa7b0b0233ce43 2025/06/21 03:58:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/backup" 2025/06/21 03:58:25 DEBUG : Starting OpenDrive session with ID: 30968b7a19943d1a8254b6e955ff4065e54f1eb7eca08982158c4cdcd9825112 2025/06/21 03:58:26 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 03:58:26 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:58:26 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:58:26 DEBUG : two: Unchanged skipping 2025/06/21 03:58:26 INFO : one: Moved (server-side) 2025/06/21 03:58:26 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:58:27 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/06/21 03:58:28 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/21 03:58:28 INFO : one: Copied (new) 2025/06/21 03:58:28 DEBUG : Waiting for deletions to finish 2025/06/21 03:58:28 INFO : three.txt: Moved (server-side) 2025/06/21 03:58:28 INFO : three.txt: Moved into backup dir fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:298: Flushing the directory cache fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2399 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/two (3) got backup/one (4), backup/three.txt (5), dst/two (3) fstest.go:140: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:140 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2399 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: OpenDrive root 'rclone-test-getifip0zugi'/backup/one: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:147: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:147 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2399 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDir Messages: backup/one: size incorrect file=3 vs obj=4 fstest.go:124: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:124 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:129 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:148 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2399 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: backup/one: 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:200: Not found "dst/one" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2399 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found 2025/06/21 03:58:38 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Flushing the directory cache fstest.go:295: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:298: Flushing the directory cache fstest.go:302: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2405 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: listing wrong, want backup/one (3), backup/three.txt (5), dst/one (4), dst/three.txt (6), dst/two (3) got backup/one (4), backup/three.txt (5), dst/three.txt (6), dst/two (3) fstest.go:140: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:140 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:146 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2405 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: OpenDrive root 'rclone-test-getifip0zugi'/backup/one: md5 hash incorrect - expecting "f97c5d29941bfb1b2fdab0874906ab82" got "c7957179c41f69d44f217a108c7915d8" fstest.go:147: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:147 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2405 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Not equal: expected: 3 actual : 4 Test: TestSyncBackupDir Messages: backup/one: size incorrect file=3 vs obj=4 fstest.go:124: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:124 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:129 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:148 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:192 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2405 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Should be true Test: TestSyncBackupDir Messages: backup/one: 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:200: Not found "dst/one" fstest.go:203: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:203 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:307 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:335 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:347 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:340 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2405 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2428 Error: Not equal: expected: 0 actual : 1 Test: TestSyncBackupDir Messages: 1 objects not found 2025/06/21 03:58:48 DEBUG : one: Need to transfer - File not found at Destination 2025/06/21 03:58:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:58:48 DEBUG : two: Unchanged skipping 2025/06/21 03:58:48 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:58:48 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:58:48 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/06/21 03:58:49 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/21 03:58:49 INFO : one: Copied (new) 2025/06/21 03:58:49 DEBUG : Waiting for deletions to finish 2025/06/21 03:58:49 INFO : three.txt: Deleted 2025/06/21 03:58:50 INFO : three.txt: Moved (server-side) 2025/06/21 03:58:50 INFO : three.txt: Moved into backup dir --- FAIL: TestSyncBackupDir (34.22s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:58:53 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/06/21 03:58:55 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/06/21 03:58:57 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/06/21 03:58:58 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/dst" 2025/06/21 03:58:59 DEBUG : Starting OpenDrive session with ID: 0214589711c8f32f84a7f6cd5743fcbcda9da4f6286afe943ecdcc2bf4b34d7a 2025/06/21 03:58:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/backup" 2025/06/21 03:59:00 DEBUG : Starting OpenDrive session with ID: eb7064e12f4fe996e65a75e3f9247354cb41a2de383afd1c8def019f1c44feda 2025/06/21 03:59:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 03:59:00 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:00 DEBUG : two: Unchanged skipping 2025/06/21 03:59:00 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:01 INFO : one: Moved (server-side) to: one.bak 2025/06/21 03:59:01 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:02 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/06/21 03:59:03 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/21 03:59:03 INFO : one: Copied (new) 2025/06/21 03:59:03 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:04 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/21 03:59:04 INFO : three.txt: Moved into backup dir 2025/06/21 03:59:05 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/06/21 03:59:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/21 03:59:06 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:06 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:06 DEBUG : two: Unchanged skipping 2025/06/21 03:59:06 INFO : one.bak: Deleted 2025/06/21 03:59:07 INFO : one: Moved (server-side) to: one.bak 2025/06/21 03:59:07 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:08 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/06/21 03:59:09 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/21 03:59:09 INFO : one: Copied (new) 2025/06/21 03:59:09 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:09 INFO : three.txt.bak: Deleted 2025/06/21 03:59:09 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/21 03:59:09 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (19.28s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:59:13 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/06/21 03:59:14 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/06/21 03:59:16 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/06/21 03:59:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/dst" 2025/06/21 03:59:18 DEBUG : Starting OpenDrive session with ID: 46094db6e9f75dbde5573da5ce82b6a87f219b453afe6d782d410870e2ffbc2b 2025/06/21 03:59:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/backup" 2025/06/21 03:59:19 DEBUG : Starting OpenDrive session with ID: ff8c844eb7b189dcdd7373125b4941d027e8e938f57dc88e67b5532d31a8d2fb 2025/06/21 03:59:20 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:20 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 03:59:20 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:20 DEBUG : two: Unchanged skipping 2025/06/21 03:59:20 INFO : one: Moved (server-side) to: one-2019-01-01 2025/06/21 03:59:20 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:21 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/06/21 03:59:22 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/21 03:59:22 INFO : one: Copied (new) 2025/06/21 03:59:22 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:23 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/06/21 03:59:23 INFO : three.txt: Moved into backup dir 2025/06/21 03:59:24 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/06/21 03:59:25 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/21 03:59:25 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:25 DEBUG : two: Unchanged skipping 2025/06/21 03:59:25 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:26 INFO : one-2019-01-01: Deleted 2025/06/21 03:59:26 INFO : one: Moved (server-side) to: one-2019-01-01 2025/06/21 03:59:26 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:27 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/06/21 03:59:28 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/21 03:59:28 INFO : one: Copied (new) 2025/06/21 03:59:28 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:28 INFO : three-2019-01-01.txt: Deleted 2025/06/21 03:59:28 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/06/21 03:59:28 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (19.02s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:59:32 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/06/21 03:59:33 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/06/21 03:59:35 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/06/21 03:59:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-getifip0zugi/dst" 2025/06/21 03:59:37 DEBUG : Starting OpenDrive session with ID: b9ef532f80abe452f0064b363a578a5a1473de82ad3c4825e0a740b976a4149c 2025/06/21 03:59:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/06/21 03:59:37 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:37 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:37 DEBUG : two: Unchanged skipping 2025/06/21 03:59:38 DEBUG : one: same parent dir (ODFfOTEyMTA4NF9ORUd5cA) - using file/rename instead of move_copy for one.bak 2025/06/21 03:59:38 INFO : one: Moved (server-side) to: one.bak 2025/06/21 03:59:38 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:39 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/06/21 03:59:40 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/06/21 03:59:40 INFO : one: Copied (new) 2025/06/21 03:59:40 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:40 DEBUG : three.txt: same parent dir (ODFfOTEyMTA4NF9ORUd5cA) - using file/rename instead of move_copy for three.txt.bak 2025/06/21 03:59:40 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/21 03:59:40 INFO : three.txt: Moved into backup dir 2025/06/21 03:59:41 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 fstest.go:295: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Flushing the directory cache fstest.go:289: Sleeping for 6s just to make sure 2025/06/21 03:59:50 DEBUG : one.bak: Excluded (Path Filter) 2025/06/21 03:59:50 DEBUG : one.bak: Excluded 2025/06/21 03:59:50 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/06/21 03:59:50 DEBUG : three.txt.bak: Excluded 2025/06/21 03:59:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/06/21 03:59:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 03:59:50 DEBUG : two: Unchanged skipping 2025/06/21 03:59:50 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for checks to finish 2025/06/21 03:59:51 INFO : one.bak: Deleted 2025/06/21 03:59:51 DEBUG : one: same parent dir (ODFfOTEyMTA4NF9ORUd5cA) - using file/rename instead of move_copy for one.bak 2025/06/21 03:59:51 INFO : one: Moved (server-side) to: one.bak 2025/06/21 03:59:51 DEBUG : OpenDrive root 'rclone-test-getifip0zugi/dst': Waiting for transfers to finish 2025/06/21 03:59:52 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/06/21 03:59:53 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/06/21 03:59:53 INFO : one: Copied (new) 2025/06/21 03:59:53 DEBUG : Waiting for deletions to finish 2025/06/21 03:59:53 INFO : three.txt.bak: Deleted 2025/06/21 03:59:53 DEBUG : three.txt: same parent dir (ODFfOTEyMTA4NF9ORUd5cA) - using file/rename instead of move_copy for three.txt.bak 2025/06/21 03:59:53 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/06/21 03:59:53 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (24.80s) === RUN TestFixCase run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 03:59:56 DEBUG : EXISTING: Uploading chunk 0, size=6, remain=0 2025/06/21 03:59:58 DEBUG : EXISTINGBUTDIFFERENT: Uploading chunk 0, size=8, remain=0 2025/06/21 04:00:00 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: Uploading chunk 0, size=8, remain=0 2025/06/21 04:00:03 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Uploading chunk 0, size=8, remain=0 2025/06/21 04:00:05 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:00:05 DEBUG : existing: Unchanged skipping 2025/06/21 04:00:05 DEBUG : existingbutdifferent: Sizes differ (src 5 vs dst 8) 2025/06/21 04:00:05 DEBUG : subdira-rclone-move-wiwufol7: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using folder/rename instead of move_copy 2025/06/21 04:00:06 DEBUG : EXISTING: moving to existing-rclone-move-yexuhev3 2025/06/21 04:00:06 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent-rclone-move-cutoqaj1 2025/06/21 04:00:06 DEBUG : subdira: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using folder/rename instead of move_copy 2025/06/21 04:00:06 DEBUG : EXISTINGBUTDIFFERENT: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using file/rename instead of move_copy for existingbutdifferent-rclone-move-cutoqaj1 2025/06/21 04:00:06 DEBUG : EXISTING: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using file/rename instead of move_copy for existing-rclone-move-yexuhev3 2025/06/21 04:00:06 INFO : SUBDIRA: Fixed case by renaming to: subdira 2025/06/21 04:00:06 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent-rclone-move-cutoqaj1 2025/06/21 04:00:06 DEBUG : EXISTINGBUTDIFFERENT: moving to existingbutdifferent 2025/06/21 04:00:07 DEBUG : existingbutdifferent-rclone-move-cutoqaj1: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using file/rename instead of move_copy for existingbutdifferent 2025/06/21 04:00:07 DEBUG : subdira/subdirb-rclone-move-tamekiy6: same parent dir (ODFfOTEyMTA5MV9TTDlBdg) - using folder/rename instead of move_copy 2025/06/21 04:00:07 INFO : existingbutdifferent-rclone-move-cutoqaj1: Moved (server-side) to: existingbutdifferent 2025/06/21 04:00:07 INFO : EXISTINGBUTDIFFERENT: Moved (server-side) to: existingbutdifferent 2025/06/21 04:00:07 INFO : EXISTINGBUTDIFFERENT: Fixed case by renaming to: existingbutdifferent 2025/06/21 04:00:07 DEBUG : existingbutdifferent: Uploading chunk 0, size=5, remain=0 2025/06/21 04:00:07 INFO : EXISTING: Moved (server-side) to: existing-rclone-move-yexuhev3 2025/06/21 04:00:07 DEBUG : EXISTING: moving to existing 2025/06/21 04:00:08 DEBUG : subdira/subdirb: same parent dir (ODFfOTEyMTA5MV9TTDlBdg) - using folder/rename instead of move_copy 2025/06/21 04:00:08 DEBUG : existing-rclone-move-yexuhev3: same parent dir (ODFfOTEyMTA1Nl9Xemc4WQ) - using file/rename instead of move_copy for existing 2025/06/21 04:00:08 INFO : SUBDIRA/subdirb: Fixed case by renaming to: subdira/subdirb 2025/06/21 04:00:08 INFO : existing-rclone-move-yexuhev3: Moved (server-side) to: existing 2025/06/21 04:00:08 INFO : EXISTING: Moved (server-side) to: existing 2025/06/21 04:00:08 INFO : EXISTING: Fixed case by renaming to: existing 2025/06/21 04:00:08 DEBUG : subdira/subdirb/subdirc-rclone-move-ladufoc9: same parent dir (ODFfOTEyMTA5Ml9kTnV3aw) - using folder/rename instead of move_copy 2025/06/21 04:00:09 DEBUG : existingbutdifferent: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/06/21 04:00:09 INFO : existingbutdifferent: Copied (replaced existing) 2025/06/21 04:00:09 DEBUG : subdira/subdirb/subdirc: same parent dir (ODFfOTEyMTA5Ml9kTnV3aw) - using folder/rename instead of move_copy 2025/06/21 04:00:09 INFO : SUBDIRA/subdirb/SUBDIRC: Fixed case by renaming to: subdira/subdirb/subdirc 2025/06/21 04:00:10 DEBUG : subdira/subdirb/subdirc/hello: Sizes differ (src 5 vs dst 8) 2025/06/21 04:00:10 DEBUG : subdira/subdirb/subdirc/subdird-rclone-move-nacosec9: same parent dir (ODFfOTEyMTA5M19XYjNpNw) - using folder/rename instead of move_copy 2025/06/21 04:00:10 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello-rclone-move-yepinam6 2025/06/21 04:00:11 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: same parent dir (ODFfOTEyMTA5M19XYjNpNw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello-rclone-move-yepinam6 2025/06/21 04:00:11 DEBUG : subdira/subdirb/subdirc/subdird: same parent dir (ODFfOTEyMTA5M19XYjNpNw) - using folder/rename instead of move_copy 2025/06/21 04:00:11 INFO : SUBDIRA/subdirb/SUBDIRC/subdird: Fixed case by renaming to: subdira/subdirb/subdirc/subdird 2025/06/21 04:00:11 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Sizes differ (src 5 vs dst 8) 2025/06/21 04:00:11 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for checks to finish 2025/06/21 04:00:12 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yimedag8 2025/06/21 04:00:12 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: same parent dir (ODFfOTEyMTA5NF8xekhYdA) - using file/rename instead of move_copy for subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yimedag8 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello-rclone-move-yepinam6 2025/06/21 04:00:12 DEBUG : SUBDIRA/subdirb/SUBDIRC/HELLO: moving to subdira/subdirb/subdirc/hello 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yimedag8 2025/06/21 04:00:12 DEBUG : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: moving to subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/06/21 04:00:12 DEBUG : subdira/subdirb/subdirc/hello-rclone-move-yepinam6: same parent dir (ODFfOTEyMTA5M19XYjNpNw) - using file/rename instead of move_copy for subdira/subdirb/subdirc/hello 2025/06/21 04:00:12 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yimedag8: same parent dir (ODFfOTEyMTA5NF8xekhYdA) - using file/rename instead of move_copy for subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/06/21 04:00:12 INFO : subdira/subdirb/subdirc/hello-rclone-move-yepinam6: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Moved (server-side) to: subdira/subdirb/subdirc/hello 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/HELLO: Fixed case by renaming to: subdira/subdirb/subdirc/hello 2025/06/21 04:00:12 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences-rclone-move-yimedag8: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Moved (server-side) to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/06/21 04:00:12 INFO : SUBDIRA/subdirb/SUBDIRC/subdird/filewithoutcasedifferences: Fixed case by renaming to: subdira/subdirb/subdirc/subdird/filewithoutcasedifferences 2025/06/21 04:00:12 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for transfers to finish 2025/06/21 04:00:12 DEBUG : subdira/subdirb/subdirc/hello: Uploading chunk 0, size=5, remain=0 2025/06/21 04:00:13 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Uploading chunk 0, size=5, remain=0 2025/06/21 04:00:13 DEBUG : subdira/subdirb/subdirc/hello: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/06/21 04:00:13 INFO : subdira/subdirb/subdirc/hello: Copied (replaced existing) 2025/06/21 04:00:14 DEBUG : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: md5 = 81bdd90fd4bc5a1c9b7b603014ff1388 OK 2025/06/21 04:00:14 INFO : subdira/subdirb/subdirc/subdird/filewithoutcasedifferences: Copied (replaced existing) 2025/06/21 04:00:14 DEBUG : Waiting for deletions to finish --- PASS: TestFixCase (22.40s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 04:00:18 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/06/21 04:00:18 INFO : sub dir: Making directory 2025/06/21 04:00:18 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC --- PASS: TestNothingToTransferWithEmptyDirs (15.45s) === RUN TestTransform === RUN TestTransform/NFC run.go:180: Remote "OpenDrive root 'rclone-test-getifip0zugi'", Local "Local file system at /tmp/rclone713981014", Modify Window "1s" 2025/06/21 04:00:35 DEBUG : dir1/0000-abcdefg.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:36 DEBUG : dir1/0001-bcdefgh.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:38 DEBUG : dir1/0002-cdefghi.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:40 DEBUG : dir1/0003-defghij.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:41 DEBUG : dir1/0004-efghijk.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:43 DEBUG : dir1/0005-fghijkl.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:45 DEBUG : dir1/0006-ghijklm.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:47 DEBUG : dir1/0007-1234567.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:48 DEBUG : dir1/0008-2345678.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:50 DEBUG : dir1/0009-3456789.txt: Uploading chunk 0, size=21, remain=0 2025/06/21 04:00:52 DEBUG : dir1/0010-456789.txt: Uploading chunk 0, size=20, remain=0 2025/06/21 04:00:53 DEBUG : dir1/0011-56789;.txt: Uploading chunk 0, size=20, remain=0 2025/06/21 04:00:55 DEBUG : dir1/0012-6789;.txt: Uploading chunk 0, size=19, remain=0 2025/06/21 04:00:57 DEBUG : dir1/0013-789;=.txt: Uploading chunk 0, size=19, remain=0 2025/06/21 04:00:58 DEBUG : dir1/0014-89;=.txt: Uploading chunk 0, size=18, remain=0 2025/06/21 04:01:00 DEBUG : dir1/0015-9;=.txt: Uploading chunk 0, size=17, remain=0 2025/06/21 04:01:02 DEBUG : apple: Uploading chunk 0, size=5, remain=0 2025/06/21 04:01:04 DEBUG : banana: Uploading chunk 0, size=6, remain=0 2025/06/21 04:01:05 DEBUG : appleappleapplebanana: Uploading chunk 0, size=21, remain=0 2025/06/21 04:01:07 DEBUG : splitbananasplit: Uploading chunk 0, size=16, remain=0 2025/06/21 04:01:08 DEBUG : Waiting for deletions to finish 2025/06/21 04:01:08 DEBUG : apple: Excluded (Path Filter) 2025/06/21 04:01:08 DEBUG : apple: Excluded 2025/06/21 04:01:08 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/21 04:01:08 DEBUG : appleappleapplebanana: Excluded 2025/06/21 04:01:08 DEBUG : banana: Excluded (Path Filter) 2025/06/21 04:01:08 DEBUG : banana: Excluded 2025/06/21 04:01:08 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/21 04:01:08 DEBUG : splitbananasplit: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0003-defghij.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0007-1234567.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0008-2345678.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0009-3456789.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0010-456789.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0011-56789;.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0012-6789;.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0013-789;=.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0014-89;=.txt: Excluded 2025/06/21 04:01:09 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/21 04:01:09 DEBUG : dir1/0015-9;=.txt: Excluded 2025/06/21 04:01:09 DEBUG : apple: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : apple: Unchanged skipping 2025/06/21 04:01:09 DEBUG : appleappleapplebanana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/21 04:01:09 DEBUG : banana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : banana: Unchanged skipping 2025/06/21 04:01:09 DEBUG : splitbananasplit: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : splitbananasplit: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for checks to finish 2025/06/21 04:01:09 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:09 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/21 04:01:09 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for transfers to finish 2025/06/21 04:01:09 DEBUG : Waiting for deletions to finish 2025/06/21 04:01:09 INFO : There was nothing to transfer 2025/06/21 04:01:09 DEBUG : Waiting for deletions to finish 2025/06/21 04:01:10 DEBUG : apple: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : apple: Excluded 2025/06/21 04:01:10 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : appleappleapplebanana: Excluded 2025/06/21 04:01:10 DEBUG : banana: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : banana: Excluded 2025/06/21 04:01:10 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : splitbananasplit: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0003-defghij.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0007-1234567.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0008-2345678.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0009-3456789.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0010-456789.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0011-56789;.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0012-6789;.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0013-789;=.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0014-89;=.txt: Excluded 2025/06/21 04:01:10 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/21 04:01:10 DEBUG : dir1/0015-9;=.txt: Excluded 2025/06/21 04:01:10 DEBUG : apple: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:10 DEBUG : apple: Unchanged skipping 2025/06/21 04:01:10 DEBUG : appleappleapplebanana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:10 DEBUG : appleappleapplebanana: Unchanged skipping 2025/06/21 04:01:10 DEBUG : banana: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:10 DEBUG : banana: Unchanged skipping 2025/06/21 04:01:10 DEBUG : splitbananasplit: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:10 DEBUG : splitbananasplit: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0000-abcdefg.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0000-abcdefg.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0001-bcdefgh.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0001-bcdefgh.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0002-cdefghi.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0002-cdefghi.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0003-defghij.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0003-defghij.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0004-efghijk.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0004-efghijk.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0005-fghijkl.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0005-fghijkl.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0006-ghijklm.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0006-ghijklm.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0007-1234567.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0007-1234567.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0008-2345678.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0008-2345678.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0009-3456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0009-3456789.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0010-456789.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0010-456789.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0011-56789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0011-56789;.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0012-6789;.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0012-6789;.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0013-789;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0013-789;=.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0014-89;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0014-89;=.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : dir1/0015-9;=.txt: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/06/21 04:01:11 DEBUG : dir1/0015-9;=.txt: Unchanged skipping 2025/06/21 04:01:11 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for checks to finish 2025/06/21 04:01:11 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Waiting for transfers to finish 2025/06/21 04:01:11 DEBUG : Waiting for deletions to finish 2025/06/21 04:01:11 INFO : There was nothing to transfer 2025/06/21 04:01:11 DEBUG : Waiting for deletions to finish 2025/06/21 04:01:11 DEBUG : apple: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : apple: Excluded 2025/06/21 04:01:11 DEBUG : appleappleapplebanana: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : appleappleapplebanana: Excluded 2025/06/21 04:01:11 DEBUG : banana: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : banana: Excluded 2025/06/21 04:01:11 DEBUG : splitbananasplit: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : splitbananasplit: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0000-abcdefg.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0000-abcdefg.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0001-bcdefgh.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0001-bcdefgh.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0002-cdefghi.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0002-cdefghi.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0003-defghij.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0003-defghij.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0004-efghijk.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0004-efghijk.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0005-fghijkl.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0005-fghijkl.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0006-ghijklm.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0006-ghijklm.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0007-1234567.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0007-1234567.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0008-2345678.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0008-2345678.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0009-3456789.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0009-3456789.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0010-456789.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0010-456789.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0011-56789;.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0011-56789;.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0012-6789;.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0012-6789;.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0013-789;=.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0013-789;=.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0014-89;=.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0014-89;=.txt: Excluded 2025/06/21 04:01:11 DEBUG : dir1/0015-9;=.txt: Excluded (Path Filter) 2025/06/21 04:01:11 DEBUG : dir1/0015-9;=.txt: Excluded --- PASS: TestTransform (42.63s) --- PASS: TestTransform/NFC (42.63s) FAIL 2025/06/21 04:01:16 DEBUG : OpenDrive root 'rclone-test-getifip0zugi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestFixCase|TestMoveWithoutDeleteEmptySrcDirs|TestNothingToTransferWithEmptyDirs|TestServerSideCopyOverSelf|TestSyncBackupDir|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffix|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter)$|^TestTransform$/^NFC$'" - Finished ERROR in 3m30.125061575s (try 2/5): exit status 1: Failed [TestSyncOverlapWithFilter TestSyncBackupDir]