"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncOverlapWithFilter|TestSyncSuffixKeepExtension)$'" - Starting (try 3/5)
2025/04/26 02:09:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizinup8pafu"
2025/04/26 02:09:17 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/26 02:09:18 DEBUG : Starting OpenDrive session with ID: cbadeea5877779209d761de2650255aff3d6b42df911603268d5cf2d067f15b6
2025/04/26 02:09:18 DEBUG : Creating backend with remote "/tmp/rclone3019244684"
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
2025/04/26 02:09:18 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizinup8pafu/rclone-sync-test"
2025/04/26 02:09:19 DEBUG : Starting OpenDrive session with ID: 7ffe3ff730b0b37a8fbd840e79686ca3823acfb63caa7bd9575bc2578faa497b
2025/04/26 02:09:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizinup8pafu/rclone-sync-test-include/layer2"
2025/04/26 02:09:20 DEBUG : Starting OpenDrive session with ID: 01c58589c7d7a02ea032f7f69b1b5d56025518b9958b7af87a05af62bb9b5440
2025/04/26 02:09:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizinup8pafu/rclone-sync-test-ignore-file"
2025/04/26 02:09:22 DEBUG : Starting OpenDrive session with ID: 919e3a58862a68160dd71332dc7a4d39dabeaa2024afdf6e1d3b6a9619bd325c
2025/04/26 02:09:24 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/04/26 02:09:26 DEBUG : rclone-sync-test: Excluded
2025/04/26 02:09:26 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/26 02:09:27 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/04/26 02:09:27 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test': Waiting for checks to finish
2025/04/26 02:09:27 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test': Waiting for transfers to finish
2025/04/26 02:09:27 DEBUG : Waiting for deletions to finish
2025/04/26 02:09:27 INFO  : There was nothing to transfer
2025/04/26 02:09:27 DEBUG : rclone-sync-test: Excluded
2025/04/26 02:09:28 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/26 02:09:28 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/04/26 02:09:28 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': Waiting for checks to finish
2025/04/26 02:09:28 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': Waiting for transfers to finish
2025/04/26 02:09:28 DEBUG : Waiting for deletions to finish
2025/04/26 02:09:28 INFO  : rclone-sync-test-include: Removing directory
2025/04/26 02:09:28 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': deleted 1 directories
2025/04/26 02:09:28 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
2025/04/26 02:09:29 DEBUG : rclone-sync-test: Excluded
2025/04/26 02:09:29 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/26 02:09:29 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/04/26 02:09:29 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/04/26 02:09:29 DEBUG : Waiting for deletions to finish
2025/04/26 02:09:29 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
2025/04/26 02:09:30 DEBUG : rclone-sync-test: Excluded
2025/04/26 02:09:30 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/26 02:09:30 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': Waiting for checks to finish
2025/04/26 02:09:30 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': Waiting for transfers to finish
2025/04/26 02:09:30 DEBUG : Waiting for deletions to finish
2025/04/26 02:09:30 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
2025/04/26 02:09:30 DEBUG : : Excluded
2025/04/26 02:09:30 DEBUG : rclone-sync-test: Excluded
2025/04/26 02:09:31 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/04/26 02:09:31 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/04/26 02:09:31 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/04/26 02:09:31 DEBUG : Waiting for deletions to finish
2025/04/26 02:09:31 INFO  : There was nothing to transfer
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
--- PASS: TestSyncOverlapWithFilter (20.80s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:180: Remote "OpenDrive root 'rclone-test-nizinup8pafu'", Local "Local file system at /tmp/rclone3019244684", Modify Window "1s"
2025/04/26 02:09:40 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/04/26 02:09:42 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/04/26 02:09:44 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/04/26 02:09:45 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nizinup8pafu/dst"
2025/04/26 02:09:46 DEBUG : Starting OpenDrive session with ID: 5fe95a9c655c46ef3f6eb6443543ab75236ea12c0f744d0b037dd06820c720ee
2025/04/26 02:09:47 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/04/26 02:09:47 DEBUG : one: same parent dir (ODFfODQ3NTMzM19ENnRUeg) - using file/rename instead of move_copy for one-2019-01-01
2025/04/26 02:09:47 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/04/26 02:09:48 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/04/26 02:09:49 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/04/26 02:09:49 INFO  : one: Copied (new)
2025/04/26 02:09:49 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/26 02:09:49 DEBUG : two: Unchanged skipping
2025/04/26 02:09:49 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2025/04/26 02:09:50 DEBUG : three.txt: same parent dir (ODFfODQ3NTMzM19ENnRUeg) - using file/rename instead of move_copy for three-2019-01-01.txt
2025/04/26 02:09:50 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/04/26 02:09:51 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2025/04/26 02:09:52 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2025/04/26 02:09:52 INFO  : three.txt: Copied (new)
2025/04/26 02:09:52 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/04/26 02:09:53 INFO  : one-2019-01-01: Deleted
2025/04/26 02:09:53 DEBUG : one: same parent dir (ODFfODQ3NTMzM19ENnRUeg) - using file/rename instead of move_copy for one-2019-01-01
2025/04/26 02:09:53 INFO  : one: Moved (server-side) to: one-2019-01-01
2025/04/26 02:09:54 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/04/26 02:09:55 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/04/26 02:09:55 INFO  : one: Copied (new)
2025/04/26 02:09:55 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/04/26 02:09:55 DEBUG : two: Unchanged skipping
2025/04/26 02:09:55 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2025/04/26 02:09:56 INFO  : three-2019-01-01.txt: Deleted
2025/04/26 02:09:56 DEBUG : three.txt: same parent dir (ODFfODQ3NTMzM19ENnRUeg) - using file/rename instead of move_copy for three-2019-01-01.txt
2025/04/26 02:09:56 INFO  : three.txt: Moved (server-side) to: three-2019-01-01.txt
2025/04/26 02:09:57 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2025/04/26 02:09:58 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2025/04/26 02:09:58 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (21.04s)
PASS
2025/04/26 02:10:00 DEBUG : OpenDrive root 'rclone-test-nizinup8pafu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncOverlapWithFilter|TestSyncSuffixKeepExtension)$'" - Finished OK in 43.200098056s (try 3/5)