"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncSuffix)$'" - Starting (try 3/5)
2026/02/10 05:07:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mekabip2lopi"
2026/02/10 05:07:21 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/10 05:07:22 DEBUG : Starting OpenDrive session with ID: ec9925d39600b12fc3f9fc85f75a3536b8b1c1a530c0baa9cb5abbf75026508c
2026/02/10 05:07:22 DEBUG : Creating backend with remote "/tmp/rclone2493651799"
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:185: Remote "OpenDrive root 'rclone-test-mekabip2lopi'", Local "Local file system at /tmp/rclone2493651799", Modify Window "1s"
2026/02/10 05:07:24 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2026/02/10 05:07:25 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2026/02/10 05:07:27 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2026/02/10 05:07:28 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mekabip2lopi/dst"
2026/02/10 05:07:29 DEBUG : Starting OpenDrive session with ID: 47a6b5a68720b78d34b84ebf4cc48e8270a2d400fede31bdada44e42dabea549
2026/02/10 05:07:29 DEBUG : one: size = 4 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:07:29 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:07:29 DEBUG : one: Sizes differ
2026/02/10 05:07:29 DEBUG : OpenDrive root 'rclone-test-mekabip2lopi/dst': Waiting for checks to finish
2026/02/10 05:07:29 DEBUG : two: size = 3 OK
2026/02/10 05:07:29 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 05:07:29 DEBUG : two: Unchanged skipping
2026/02/10 05:07:30 DEBUG : one: same parent dir (ODFfMTAwNDcwODhfWlZVTHo) - using file/rename instead of move_copy for one.bak
2026/02/10 05:07:30 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 05:07:30 DEBUG : OpenDrive root 'rclone-test-mekabip2lopi/dst': Waiting for transfers to finish
2026/02/10 05:07:31 DEBUG : one: Uploading chunk 0, size=4, remain=0
2026/02/10 05:07:32 DEBUG : one: size = 4 OK
2026/02/10 05:07:32 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2026/02/10 05:07:32 INFO  : one: Copied (new)
2026/02/10 05:07:32 DEBUG : Waiting for deletions to finish
2026/02/10 05:07:32 DEBUG : three.txt: same parent dir (ODFfMTAwNDcwODhfWlZVTHo) - using file/rename instead of move_copy for three.txt.bak
2026/02/10 05:07:32 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 05:07:32 INFO  : three.txt: Moved into backup dir
2026/02/10 05:07:34 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
2026/02/10 05:07:43 DEBUG : one.bak: Excluded (Path Filter)
2026/02/10 05:07:43 DEBUG : one.bak: Excluded
2026/02/10 05:07:43 DEBUG : three.txt.bak: Excluded (Path Filter)
2026/02/10 05:07:43 DEBUG : three.txt.bak: Excluded
2026/02/10 05:07:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:07:43 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:07:43 DEBUG : two: size = 3 OK
2026/02/10 05:07:43 DEBUG : one: Sizes differ
2026/02/10 05:07:43 DEBUG : OpenDrive root 'rclone-test-mekabip2lopi/dst': Waiting for checks to finish
2026/02/10 05:07:43 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 05:07:43 DEBUG : two: Unchanged skipping
2026/02/10 05:07:43 INFO  : one.bak: Deleted
2026/02/10 05:07:43 DEBUG : one: same parent dir (ODFfMTAwNDcwODhfWlZVTHo) - using file/rename instead of move_copy for one.bak
2026/02/10 05:07:43 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 05:07:43 DEBUG : OpenDrive root 'rclone-test-mekabip2lopi/dst': Waiting for transfers to finish
2026/02/10 05:07:44 DEBUG : one: Uploading chunk 0, size=5, remain=0
2026/02/10 05:07:45 DEBUG : one: size = 5 OK
2026/02/10 05:07:45 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2026/02/10 05:07:45 INFO  : one: Copied (new)
2026/02/10 05:07:45 DEBUG : Waiting for deletions to finish
2026/02/10 05:07:45 INFO  : three.txt.bak: Deleted
2026/02/10 05:07:46 DEBUG : three.txt: same parent dir (ODFfMTAwNDcwODhfWlZVTHo) - using file/rename instead of move_copy for three.txt.bak
2026/02/10 05:07:46 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 05:07:46 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (25.49s)
=== RUN   TestSyncSuffix
    run.go:185: Remote "OpenDrive root 'rclone-test-mekabip2lopi'", Local "Local file system at /tmp/rclone2493651799", Modify Window "1s"
2026/02/10 05:07:49 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2026/02/10 05:07:50 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2026/02/10 05:07:52 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2026/02/10 05:07:53 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-mekabip2lopi/dst"
2026/02/10 05:07:54 DEBUG : Starting OpenDrive session with ID: b8a91a02a2aecc0a0f190cd83ed0ca13af138127114157488de095dd90bb442b
2026/02/10 05:07:54 DEBUG : one: size = 4 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:07:54 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:07:54 DEBUG : one: Sizes differ
2026/02/10 05:07:54 DEBUG : one: same parent dir (ODFfMTAwNDcwODlfczZtUjE) - using file/rename instead of move_copy for one.bak
2026/02/10 05:07:55 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 05:07:55 DEBUG : one: Uploading chunk 0, size=4, remain=0
2026/02/10 05:07:57 DEBUG : one: size = 4 OK
2026/02/10 05:07:57 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2026/02/10 05:07:57 INFO  : one: Copied (new)
2026/02/10 05:07:57 DEBUG : two: size = 3 OK
2026/02/10 05:07:57 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 05:07:57 DEBUG : two: Unchanged skipping
2026/02/10 05:07:57 DEBUG : three.txt: size = 6 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:07:57 DEBUG : three.txt: size = 5 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:07:57 DEBUG : three.txt: Sizes differ
2026/02/10 05:07:57 DEBUG : three.txt: same parent dir (ODFfMTAwNDcwODlfczZtUjE) - using file/rename instead of move_copy for three.txt.bak
2026/02/10 05:07:57 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 05:07:58 DEBUG : three.txt: Uploading chunk 0, size=6, remain=0
2026/02/10 05:07:59 DEBUG : three.txt: size = 6 OK
2026/02/10 05:07:59 DEBUG : three.txt: md5 = 91341eed84691a83caea73aa785736d5 OK
2026/02/10 05:07:59 INFO  : three.txt: Copied (new)
2026/02/10 05:08:00 DEBUG : one: size = 5 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:08:00 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:08:00 DEBUG : one: Sizes differ
2026/02/10 05:08:00 INFO  : one.bak: Deleted
2026/02/10 05:08:00 DEBUG : one: same parent dir (ODFfMTAwNDcwODlfczZtUjE) - using file/rename instead of move_copy for one.bak
2026/02/10 05:08:01 INFO  : one: Moved (server-side) to: one.bak
2026/02/10 05:08:01 DEBUG : one: Uploading chunk 0, size=5, remain=0
2026/02/10 05:08:03 DEBUG : one: size = 5 OK
2026/02/10 05:08:03 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2026/02/10 05:08:03 INFO  : one: Copied (new)
2026/02/10 05:08:03 DEBUG : two: size = 3 OK
2026/02/10 05:08:03 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/02/10 05:08:03 DEBUG : two: Unchanged skipping
2026/02/10 05:08:03 DEBUG : three.txt: size = 19 (Local file system at /tmp/rclone2493651799)
2026/02/10 05:08:03 DEBUG : three.txt: size = 6 (OpenDrive root 'rclone-test-mekabip2lopi/dst')
2026/02/10 05:08:03 DEBUG : three.txt: Sizes differ
2026/02/10 05:08:03 INFO  : three.txt.bak: Deleted
2026/02/10 05:08:03 DEBUG : three.txt: same parent dir (ODFfMTAwNDcwODlfczZtUjE) - using file/rename instead of move_copy for three.txt.bak
2026/02/10 05:08:04 INFO  : three.txt: Moved (server-side) to: three.txt.bak
2026/02/10 05:08:04 DEBUG : three.txt: Uploading chunk 0, size=19, remain=0
2026/02/10 05:08:05 DEBUG : three.txt: size = 19 OK
2026/02/10 05:08:05 DEBUG : three.txt: md5 = 423e01c5e84a30d129b97aedb6e219a9 OK
2026/02/10 05:08:05 INFO  : three.txt: Copied (new)
    run.go:130: removing dir "dst" failed - try 1/3: folder not empty
--- PASS: TestSyncSuffix (20.94s)
PASS
2026/02/10 05:08:09 DEBUG : OpenDrive root 'rclone-test-mekabip2lopi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDirSuffixOnly|TestSyncSuffix)$'" - Finished OK in 47.600508955s (try 3/5)