"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenamesStrategyLeaf)$'" - Starting (try 2/5) 2025/04/15 03:48:54 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi" 2025/04/15 03:48:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/04/15 03:48:54 DEBUG : Starting OpenDrive session with ID: 9da7d5abdfbf44ccc0bbe79f0a5e07c159d35e24c7f3b7d4334dd33053dd9490 2025/04/15 03:48:55 DEBUG : Creating backend with remote "/tmp/rclone2455443634" === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" sync_test.go:1649: Can track renames: true 2025/04/15 03:48:55 INFO : OpenDrive root 'rclone-test-rewosey5fidi': Making map for --track-renames 2025/04/15 03:48:55 INFO : OpenDrive root 'rclone-test-rewosey5fidi': Finished making map for --track-renames 2025/04/15 03:48:55 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for checks to finish 2025/04/15 03:48:55 DEBUG : potato: Need to transfer - No matching file found at Destination 2025/04/15 03:48:55 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2025/04/15 03:48:55 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for renames to finish 2025/04/15 03:48:55 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for transfers to finish 2025/04/15 03:48:56 DEBUG : potato: Uploading chunk 0, size=14, remain=0 2025/04/15 03:48:57 DEBUG : sub/yam: Uploading chunk 0, size=11, remain=0 2025/04/15 03:48:57 DEBUG : potato: md5 = 7f6fa9ddec9bfebda9510af0323fd696 OK 2025/04/15 03:48:57 INFO : potato: Copied (new) 2025/04/15 03:48:58 DEBUG : sub/yam: md5 = 48dc443644c4b89556dc06834b46451d OK 2025/04/15 03:48:58 INFO : sub/yam: Copied (new) 2025/04/15 03:48:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:48:58 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:48:58 DEBUG : potato: Unchanged skipping 2025/04/15 03:48:58 INFO : OpenDrive root 'rclone-test-rewosey5fidi': Making map for --track-renames 2025/04/15 03:48:58 INFO : OpenDrive root 'rclone-test-rewosey5fidi': Finished making map for --track-renames 2025/04/15 03:48:58 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for checks to finish 2025/04/15 03:48:58 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for renames to finish 2025/04/15 03:48:59 INFO : sub/yam: Moved (server-side) to: yam 2025/04/15 03:48:59 INFO : yam: Renamed from "sub/yam" 2025/04/15 03:48:59 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for transfers to finish 2025/04/15 03:48:59 DEBUG : Waiting for deletions to finish 2025/04/15 03:48:59 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (6.52s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:01 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/rclone-sync-test" 2025/04/15 03:49:02 DEBUG : Starting OpenDrive session with ID: fb2e0240694314cdfd8a638583043cdfa4f2504ec0f8f6df9a2b157c66ad970d 2025/04/15 03:49:03 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/rclone-sync-test-include/layer2" 2025/04/15 03:49:03 DEBUG : Starting OpenDrive session with ID: aa80dc24a6be19841601783206e521ff1781bc69cf1f859f1e572d61e1df7b54 2025/04/15 03:49:05 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/rclone-sync-test-ignore-file" 2025/04/15 03:49:05 DEBUG : Starting OpenDrive session with ID: 812a77f7e59af1d691fa2dc9d5eca9af6c873cd77f2d1a0a2cf7d656582df406 2025/04/15 03:49:08 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0 2025/04/15 03:49:09 DEBUG : rclone-sync-test: Excluded 2025/04/15 03:49:09 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/15 03:49:10 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/15 03:49:10 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test': Waiting for checks to finish 2025/04/15 03:49:10 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test': Waiting for transfers to finish 2025/04/15 03:49:10 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:10 INFO : There was nothing to transfer 2025/04/15 03:49:10 DEBUG : rclone-sync-test: Excluded 2025/04/15 03:49:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/15 03:49:11 DEBUG : rclone-sync-test-include/layer2: Excluded 2025/04/15 03:49:11 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for checks to finish 2025/04/15 03:49:11 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for transfers to finish 2025/04/15 03:49:11 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:11 INFO : rclone-sync-test-include: Removing directory 2025/04/15 03:49:11 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': deleted 1 directories 2025/04/15 03:49:11 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:12 DEBUG : rclone-sync-test: Excluded 2025/04/15 03:49:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/15 03:49:12 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test-include/layer2': Waiting for checks to finish 2025/04/15 03:49:12 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test-include/layer2': Waiting for transfers to finish 2025/04/15 03:49:12 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:12 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:13 DEBUG : rclone-sync-test: Excluded 2025/04/15 03:49:13 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/15 03:49:13 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for checks to finish 2025/04/15 03:49:13 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Waiting for transfers to finish 2025/04/15 03:49:13 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:13 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:14 DEBUG : : Excluded 2025/04/15 03:49:14 DEBUG : rclone-sync-test: Excluded 2025/04/15 03:49:14 DEBUG : rclone-sync-test-ignore-file: Excluded 2025/04/15 03:49:14 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test-ignore-file': Waiting for checks to finish 2025/04/15 03:49:14 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/rclone-sync-test-ignore-file': Waiting for transfers to finish 2025/04/15 03:49:14 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:14 INFO : There was nothing to transfer run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" --- PASS: TestSyncOverlapWithFilter (19.65s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:22 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/15 03:49:24 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/15 03:49:25 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/15 03:49:27 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/dst" 2025/04/15 03:49:27 DEBUG : Starting OpenDrive session with ID: b61bb886802906666129ff5237e51c079e31ec7ec4065dc23f319974492080c2 2025/04/15 03:49:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/backup" 2025/04/15 03:49:28 DEBUG : Starting OpenDrive session with ID: 2c40cb4fb2e15ee212c0e8ca022d41a4cc655e367bc89d843e0989a68598cb6a 2025/04/15 03:49:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/15 03:49:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:49:29 DEBUG : two: Unchanged skipping 2025/04/15 03:49:29 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for checks to finish 2025/04/15 03:49:30 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/15 03:49:30 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for transfers to finish 2025/04/15 03:49:31 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/15 03:49:31 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/15 03:49:31 INFO : one: Copied (new) 2025/04/15 03:49:31 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:32 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/15 03:49:32 INFO : three.txt: Moved into backup dir 2025/04/15 03:49:33 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/15 03:49:34 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/15 03:49:34 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:49:34 DEBUG : two: Unchanged skipping 2025/04/15 03:49:34 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for checks to finish 2025/04/15 03:49:35 INFO : one-2019-01-01: Deleted 2025/04/15 03:49:35 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/15 03:49:35 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for transfers to finish 2025/04/15 03:49:36 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/15 03:49:37 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/15 03:49:37 INFO : one: Copied (new) 2025/04/15 03:49:37 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:37 INFO : three-2019-01-01.txt: Deleted 2025/04/15 03:49:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/15 03:49:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (18.59s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:49:41 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/15 03:49:42 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/15 03:49:44 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/15 03:49:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/dst" 2025/04/15 03:49:46 DEBUG : Starting OpenDrive session with ID: 25b366dffded7f0c689e6651c57c2a8b25230f3d320db7275a0318c20a35e851 2025/04/15 03:49:46 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for checks to finish 2025/04/15 03:49:46 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:49:46 DEBUG : two: Unchanged skipping 2025/04/15 03:49:46 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/15 03:49:47 DEBUG : one: same parent dir (ODFfODIzNTE5NF9wNzNLOA) - using file/rename instead of move_copy for one.bak 2025/04/15 03:49:47 INFO : one: Moved (server-side) to: one.bak 2025/04/15 03:49:47 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for transfers to finish 2025/04/15 03:49:47 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/15 03:49:49 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/15 03:49:49 INFO : one: Copied (new) 2025/04/15 03:49:49 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:49 DEBUG : three.txt: same parent dir (ODFfODIzNTE5NF9wNzNLOA) - using file/rename instead of move_copy for three.txt.bak 2025/04/15 03:49:49 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/15 03:49:49 INFO : three.txt: Moved into backup dir 2025/04/15 03:49:50 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0 2025/04/15 03:49:52 DEBUG : one.bak: Excluded (Path Filter) 2025/04/15 03:49:52 DEBUG : one.bak: Excluded 2025/04/15 03:49:52 DEBUG : three.txt.bak: Excluded (Path Filter) 2025/04/15 03:49:52 DEBUG : three.txt.bak: Excluded 2025/04/15 03:49:52 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/15 03:49:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:49:52 DEBUG : two: Unchanged skipping 2025/04/15 03:49:52 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for checks to finish 2025/04/15 03:49:52 INFO : one.bak: Deleted 2025/04/15 03:49:52 DEBUG : one: same parent dir (ODFfODIzNTE5NF9wNzNLOA) - using file/rename instead of move_copy for one.bak 2025/04/15 03:49:52 INFO : one: Moved (server-side) to: one.bak 2025/04/15 03:49:52 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi/dst': Waiting for transfers to finish 2025/04/15 03:49:53 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/15 03:49:54 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/15 03:49:54 INFO : one: Copied (new) 2025/04/15 03:49:54 DEBUG : Waiting for deletions to finish 2025/04/15 03:49:55 INFO : three.txt.bak: Deleted 2025/04/15 03:49:55 DEBUG : three.txt: same parent dir (ODFfODIzNTE5NF9wNzNLOA) - using file/rename instead of move_copy for three.txt.bak 2025/04/15 03:49:55 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/15 03:49:55 INFO : three.txt: Moved into backup dir fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:286: Sleeping for 6s just to make sure --- PASS: TestSyncBackupDirSuffixOnly (24.84s) === RUN TestSyncSuffix run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:50:05 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/15 03:50:07 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/15 03:50:09 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/15 03:50:10 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/dst" 2025/04/15 03:50:11 DEBUG : Starting OpenDrive session with ID: 7eb4018431580301eba46bc86dfbfac0dbcb8452134589be7a30339a64acd5f9 2025/04/15 03:50:11 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/15 03:50:11 DEBUG : one: same parent dir (ODFfODIzNTE5NV9taGpKZA) - using file/rename instead of move_copy for one.bak 2025/04/15 03:50:12 INFO : one: Moved (server-side) to: one.bak 2025/04/15 03:50:12 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/15 03:50:13 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/15 03:50:13 INFO : one: Copied (new) 2025/04/15 03:50:13 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:50:13 DEBUG : two: Unchanged skipping 2025/04/15 03:50:14 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/15 03:50:14 DEBUG : three.txt: same parent dir (ODFfODIzNTE5NV9taGpKZA) - using file/rename instead of move_copy for three.txt.bak 2025/04/15 03:50:14 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/15 03:50:15 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/04/15 03:50:16 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/04/15 03:50:16 INFO : three.txt: Copied (new) 2025/04/15 03:50:17 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/15 03:50:17 INFO : one.bak: Deleted 2025/04/15 03:50:17 DEBUG : one: same parent dir (ODFfODIzNTE5NV9taGpKZA) - using file/rename instead of move_copy for one.bak 2025/04/15 03:50:17 INFO : one: Moved (server-side) to: one.bak 2025/04/15 03:50:18 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/15 03:50:19 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/15 03:50:19 INFO : one: Copied (new) 2025/04/15 03:50:19 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:50:19 DEBUG : two: Unchanged skipping 2025/04/15 03:50:19 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/15 03:50:19 INFO : three.txt.bak: Deleted 2025/04/15 03:50:20 DEBUG : three.txt: same parent dir (ODFfODIzNTE5NV9taGpKZA) - using file/rename instead of move_copy for three.txt.bak 2025/04/15 03:50:20 INFO : three.txt: Moved (server-side) to: three.txt.bak 2025/04/15 03:50:20 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/04/15 03:50:22 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/04/15 03:50:22 INFO : three.txt: Copied (new) fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /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:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: listing wrong, want dst/one (5), dst/one.bak (4), dst/three.txt (19), dst/three.txt.bak (6), dst/two (3) got dst/one.bak (5), dst/three.txt (19), dst/three.txt.bak (6), dst/two (3) fstest.go:137: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:137 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:143 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /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:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: OpenDrive root 'rclone-test-rewosey5fidi'/dst/one.bak: md5 hash incorrect - expecting "c7957179c41f69d44f217a108c7915d8" got "0f93e81041f0cab37c37a05ae998b219" 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:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /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:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Not equal: expected: 4 actual : 5 Test: TestSyncSuffix Messages: dst/one.bak: size incorrect file=4 vs obj=5 fstest.go:121: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:121 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:126 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:145 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /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:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Should be true Test: TestSyncSuffix Messages: dst/one.bak: Modification time difference too big |-119h59m59.876543211s| > 1s (want 2011-12-25 12:59:59.123456789 +0000 UTC vs got 2011-12-30 12:59:59 +0000 UTC) (precision 1s) fstest.go:197: Not found "dst/one" fstest.go:200: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:200 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:304 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /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:2521 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2523 Error: Not equal: expected: 0 actual : 1 Test: TestSyncSuffix Messages: 1 objects not found --- FAIL: TestSyncSuffix (27.78s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:50:33 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0 2025/04/15 03:50:35 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0 2025/04/15 03:50:37 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0 2025/04/15 03:50:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-rewosey5fidi/dst" 2025/04/15 03:50:38 DEBUG : Starting OpenDrive session with ID: 5085cd5ee1f37c623a1655bf8b462b98ffbd214182f076a9510b059d1553d01e 2025/04/15 03:50:39 DEBUG : one: Sizes differ (src 4 vs dst 3) 2025/04/15 03:50:39 DEBUG : one: same parent dir (ODFfODIzNTE5Nl9udFEzRA) - using file/rename instead of move_copy for one-2019-01-01 2025/04/15 03:50:39 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/15 03:50:40 DEBUG : one: Uploading chunk 0, size=4, remain=0 2025/04/15 03:50:41 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK 2025/04/15 03:50:41 INFO : one: Copied (new) 2025/04/15 03:50:41 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:50:41 DEBUG : two: Unchanged skipping 2025/04/15 03:50:41 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2025/04/15 03:50:42 DEBUG : three.txt: same parent dir (ODFfODIzNTE5Nl9udFEzRA) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/04/15 03:50:42 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/15 03:50:43 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0 2025/04/15 03:50:44 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK 2025/04/15 03:50:44 INFO : three.txt: Copied (new) 2025/04/15 03:50:45 DEBUG : one: Sizes differ (src 5 vs dst 4) 2025/04/15 03:50:45 INFO : one-2019-01-01: Deleted 2025/04/15 03:50:45 DEBUG : one: same parent dir (ODFfODIzNTE5Nl9udFEzRA) - using file/rename instead of move_copy for one-2019-01-01 2025/04/15 03:50:45 INFO : one: Moved (server-side) to: one-2019-01-01 2025/04/15 03:50:46 DEBUG : one: Uploading chunk 0, size=5, remain=0 2025/04/15 03:50:47 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK 2025/04/15 03:50:47 INFO : one: Copied (new) 2025/04/15 03:50:47 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2025/04/15 03:50:47 DEBUG : two: Unchanged skipping 2025/04/15 03:50:47 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2025/04/15 03:50:48 INFO : three-2019-01-01.txt: Deleted 2025/04/15 03:50:48 DEBUG : three.txt: same parent dir (ODFfODIzNTE5Nl9udFEzRA) - using file/rename instead of move_copy for three-2019-01-01.txt 2025/04/15 03:50:48 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2025/04/15 03:50:49 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0 2025/04/15 03:50:50 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK 2025/04/15 03:50:50 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (19.72s) === RUN TestNothingToTransferWithEmptyDirs run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" 2025/04/15 03:50:52 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/04/15 03:50:52 INFO : sub dir: Making directory 2025/04/15 03:50:52 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" run.go:180: Remote "OpenDrive root 'rclone-test-rewosey5fidi'", Local "Local file system at /tmp/rclone2455443634", Modify Window "1s" --- PASS: TestNothingToTransferWithEmptyDirs (16.03s) FAIL 2025/04/15 03:51:08 DEBUG : OpenDrive root 'rclone-test-rewosey5fidi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestNothingToTransferWithEmptyDirs|TestSyncBackupDirSuffixOnly|TestSyncBackupDirWithSuffixKeepExtension|TestSyncOverlapWithFilter|TestSyncSuffix|TestSyncSuffixKeepExtension|TestSyncWithTrackRenamesStrategyLeaf)$'" - Finished ERROR in 2m14.556934206s (try 2/5): exit status 1: Failed [TestSyncSuffix]