"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncOverlapWithFilter|TestSyncSuffix)$'" - Starting (try 3/5)
2025/03/26 04:14:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jowanuj7coba"
2025/03/26 04:14:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/26 04:14:07 DEBUG : Starting OpenDrive session with ID: 6d604f4890c18a88471251e9b4c03a1ffc78173e1fe7f0bfb13d17f21f2853a5
2025/03/26 04:14:07 DEBUG : Creating backend with remote "/tmp/rclone2181650765"
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
2025/03/26 04:14:07 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jowanuj7coba/rclone-sync-test"
2025/03/26 04:14:08 DEBUG : Starting OpenDrive session with ID: 0a2ac19dc20a6a63f97ddd6427f9d98388f057c5232e25606fbe5603e65d2011
2025/03/26 04:14:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jowanuj7coba/rclone-sync-test-include/layer2"
2025/03/26 04:14:09 DEBUG : Starting OpenDrive session with ID: 0830e51ba87dfc13734238130de811ec6eb2f953a06ca7b008c7d8942dd2f1c6
2025/03/26 04:14:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jowanuj7coba/rclone-sync-test-ignore-file"
2025/03/26 04:14:11 DEBUG : Starting OpenDrive session with ID: 173fe284ba4c7f8517733b871028ce1fdf16fef18785a3c78e6c4b01294a9e6f
2025/03/26 04:14:13 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/03/26 04:14:15 DEBUG : rclone-sync-test: Excluded
2025/03/26 04:14:15 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/26 04:14:16 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/26 04:14:16 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test': Waiting for checks to finish
2025/03/26 04:14:16 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test': Waiting for transfers to finish
2025/03/26 04:14:16 DEBUG : Waiting for deletions to finish
2025/03/26 04:14:16 INFO  : There was nothing to transfer
2025/03/26 04:14:16 DEBUG : rclone-sync-test: Excluded
2025/03/26 04:14:16 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/26 04:14:17 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/03/26 04:14:17 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': Waiting for checks to finish
2025/03/26 04:14:17 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': Waiting for transfers to finish
2025/03/26 04:14:17 DEBUG : Waiting for deletions to finish
2025/03/26 04:14:17 INFO  : rclone-sync-test-include: Removing directory
2025/03/26 04:14:17 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': deleted 1 directories
2025/03/26 04:14:17 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
2025/03/26 04:14:18 DEBUG : rclone-sync-test: Excluded
2025/03/26 04:14:18 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/26 04:14:18 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/03/26 04:14:18 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/03/26 04:14:18 DEBUG : Waiting for deletions to finish
2025/03/26 04:14:18 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
2025/03/26 04:14:18 DEBUG : rclone-sync-test: Excluded
2025/03/26 04:14:19 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/26 04:14:19 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': Waiting for checks to finish
2025/03/26 04:14:19 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': Waiting for transfers to finish
2025/03/26 04:14:19 DEBUG : Waiting for deletions to finish
2025/03/26 04:14:19 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
2025/03/26 04:14:19 DEBUG : : Excluded
2025/03/26 04:14:19 DEBUG : rclone-sync-test: Excluded
2025/03/26 04:14:19 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/03/26 04:14:19 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/03/26 04:14:19 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/03/26 04:14:19 DEBUG : Waiting for deletions to finish
2025/03/26 04:14:19 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
--- PASS: TestSyncOverlapWithFilter (19.36s)
=== RUN   TestSyncSuffix
    run.go:180: Remote "OpenDrive root 'rclone-test-jowanuj7coba'", Local "Local file system at /tmp/rclone2181650765", Modify Window "1s"
2025/03/26 04:14:28 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/03/26 04:14:29 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/03/26 04:14:31 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/03/26 04:14:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-jowanuj7coba/dst"
2025/03/26 04:14:33 DEBUG : Starting OpenDrive session with ID: 6904a4333da051792e6e3858976f8f5ed0157e95474a57da5d9e7d93ff9a9cea
2025/03/26 04:14:34 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/03/26 04:14:34 DEBUG : one: same parent dir (ODFfODE5MjQ4MV92dUJQZg) - using file/rename instead of move_copy for one.bak
2025/03/26 04:14:34 INFO  : one: Moved (server-side) to: one.bak
2025/03/26 04:14:35 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/03/26 04:14:36 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/03/26 04:14:36 INFO  : one: Copied (new)
2025/03/26 04:14:36 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/26 04:14:36 DEBUG : two: Unchanged skipping
2025/03/26 04:14:36 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/03/26 04:14:36 DEBUG : three.txt: same parent dir (ODFfODE5MjQ4MV92dUJQZg) - using file/rename instead of move_copy for three.txt.bak
2025/03/26 04:14:37 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/26 04:14:37 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2025/03/26 04:14:38 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/03/26 04:14:38 INFO  : three.txt: Copied (new)
2025/03/26 04:14:39 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/03/26 04:14:40 INFO  : one.bak: Deleted
2025/03/26 04:14:40 DEBUG : one: same parent dir (ODFfODE5MjQ4MV92dUJQZg) - using file/rename instead of move_copy for one.bak
2025/03/26 04:14:40 INFO  : one: Moved (server-side) to: one.bak
2025/03/26 04:14:41 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/03/26 04:14:42 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/03/26 04:14:42 INFO  : one: Copied (new)
2025/03/26 04:14:42 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/03/26 04:14:42 DEBUG : two: Unchanged skipping
2025/03/26 04:14:42 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/03/26 04:14:42 INFO  : three.txt.bak: Deleted
2025/03/26 04:14:42 DEBUG : three.txt: same parent dir (ODFfODE5MjQ4MV92dUJQZg) - using file/rename instead of move_copy for three.txt.bak
2025/03/26 04:14:43 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2025/03/26 04:14:43 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2025/03/26 04:14:44 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/03/26 04:14:44 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (19.93s)
PASS
2025/03/26 04:14:46 DEBUG : OpenDrive root 'rclone-test-jowanuj7coba': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncOverlapWithFilter|TestSyncSuffix)$'" - Finished OK in 40.591836015s (try 3/5)