"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDir|TestSyncOverlapWithFilter)$'" - Starting (try 3/5)
2025/06/21 04:01:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono"
2025/06/21 04:01:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/06/21 04:01:17 DEBUG : Starting OpenDrive session with ID: 516e7d65422ae881477a037e9ba4279c61ac9320b7942ae3c7dcacc1a0ee30be
2025/06/21 04:01:17 DEBUG : Creating backend with remote "/tmp/rclone209831920"
=== RUN   TestSyncOverlapWithFilter
    run.go:180: Remote "OpenDrive root 'rclone-test-teseduw0dono'", Local "Local file system at /tmp/rclone209831920", Modify Window "1s"
2025/06/21 04:01:17 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono/rclone-sync-test"
2025/06/21 04:01:18 DEBUG : Starting OpenDrive session with ID: cdf60a1c7f76cc72c8ad4aaab58e77881277b70de315036a25de8d56d39fca3b
2025/06/21 04:01:19 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono/rclone-sync-test-include/layer2"
2025/06/21 04:01:19 DEBUG : Starting OpenDrive session with ID: 57ccf60b53ff7d022c5a02bd620c42f70d025a849848e429d4383be9f2d756ad
2025/06/21 04:01:21 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono/rclone-sync-test-ignore-file"
2025/06/21 04:01:21 DEBUG : Starting OpenDrive session with ID: 635be8e935af4d2d033ac761cded36aa63f9fe9f847d673b368c68dfef25892f
2025/06/21 04:01:23 DEBUG : rclone-sync-test-ignore-file/.ignore: Uploading chunk 0, size=1, remain=0
2025/06/21 04:01:25 DEBUG : rclone-sync-test: Excluded
2025/06/21 04:01:25 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/06/21 04:01:26 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/06/21 04:01:26 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test': Waiting for checks to finish
2025/06/21 04:01:26 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test': Waiting for transfers to finish
2025/06/21 04:01:26 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:26 INFO  : There was nothing to transfer
2025/06/21 04:01:26 DEBUG : rclone-sync-test: Excluded
2025/06/21 04:01:26 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/06/21 04:01:27 DEBUG : rclone-sync-test-include/layer2: Excluded
2025/06/21 04:01:27 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': Waiting for checks to finish
2025/06/21 04:01:27 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': Waiting for transfers to finish
2025/06/21 04:01:27 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:27 INFO  : rclone-sync-test-include: Removing directory
2025/06/21 04:01:27 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': deleted 1 directories
2025/06/21 04:01:27 INFO  : There was nothing to transfer
2025/06/21 04:01:28 DEBUG : rclone-sync-test: Excluded
2025/06/21 04:01:28 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/06/21 04:01:28 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test-include/layer2': Waiting for checks to finish
2025/06/21 04:01:28 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test-include/layer2': Waiting for transfers to finish
2025/06/21 04:01:28 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:28 INFO  : There was nothing to transfer
2025/06/21 04:01:29 DEBUG : rclone-sync-test: Excluded
2025/06/21 04:01:29 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/06/21 04:01:29 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': Waiting for checks to finish
2025/06/21 04:01:29 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': Waiting for transfers to finish
2025/06/21 04:01:29 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:29 INFO  : There was nothing to transfer
2025/06/21 04:01:29 DEBUG : Excluded
2025/06/21 04:01:29 DEBUG : rclone-sync-test: Excluded
2025/06/21 04:01:29 DEBUG : rclone-sync-test-ignore-file: Excluded
2025/06/21 04:01:29 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test-ignore-file': Waiting for checks to finish
2025/06/21 04:01:29 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/rclone-sync-test-ignore-file': Waiting for transfers to finish
2025/06/21 04:01:29 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:29 INFO  : There was nothing to transfer
--- PASS: TestSyncOverlapWithFilter (13.68s)
=== RUN   TestSyncBackupDir
    run.go:180: Remote "OpenDrive root 'rclone-test-teseduw0dono'", Local "Local file system at /tmp/rclone209831920", Modify Window "1s"
2025/06/21 04:01:32 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2025/06/21 04:01:34 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2025/06/21 04:01:36 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2025/06/21 04:01:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono/dst"
2025/06/21 04:01:37 DEBUG : Starting OpenDrive session with ID: d5ed145aaac1f906c38387981bbb6aa00a09fc5a3e401595c1bf6d55c257615a
2025/06/21 04:01:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-teseduw0dono/backup"
2025/06/21 04:01:38 DEBUG : Starting OpenDrive session with ID: 904a525c54c159437581437271343421454a86fc36ac312b6dbabff16e8b31da
2025/06/21 04:01:39 DEBUG : one: Sizes differ (src 4 vs dst 3)
2025/06/21 04:01:39 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/06/21 04:01:39 DEBUG : two: Unchanged skipping
2025/06/21 04:01:39 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/dst': Waiting for checks to finish
2025/06/21 04:01:40 INFO  : one: Moved (server-side)
2025/06/21 04:01:40 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/dst': Waiting for transfers to finish
2025/06/21 04:01:41 DEBUG : one: Uploading chunk 0, size=4, remain=0
2025/06/21 04:01:42 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2025/06/21 04:01:42 INFO  : one: Copied (new)
2025/06/21 04:01:42 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:42 INFO  : three.txt: Moved (server-side)
2025/06/21 04:01:42 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:289: Sleeping for 6s just to make sure
2025/06/21 04:01:51 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2025/06/21 04:01:52 DEBUG : one: Sizes differ (src 5 vs dst 4)
2025/06/21 04:01:52 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2025/06/21 04:01:52 DEBUG : two: Unchanged skipping
2025/06/21 04:01:52 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/dst': Waiting for checks to finish
2025/06/21 04:01:53 INFO  : one: Deleted
2025/06/21 04:01:53 INFO  : one: Moved (server-side)
2025/06/21 04:01:53 DEBUG : OpenDrive root 'rclone-test-teseduw0dono/dst': Waiting for transfers to finish
2025/06/21 04:01:54 DEBUG : one: Uploading chunk 0, size=5, remain=0
2025/06/21 04:01:55 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2025/06/21 04:01:55 INFO  : one: Copied (new)
2025/06/21 04:01:55 DEBUG : Waiting for deletions to finish
2025/06/21 04:01:55 INFO  : three.txt: Deleted
2025/06/21 04:01:56 INFO  : three.txt: Moved (server-side)
2025/06/21 04:01:56 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:289: Sleeping for 6s just to make sure
--- PASS: TestSyncBackupDir (34.64s)
PASS
2025/06/21 04:02:06 DEBUG : OpenDrive root 'rclone-test-teseduw0dono': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestSyncBackupDir|TestSyncOverlapWithFilter)$'" - Finished OK in 49.626873657s (try 3/5)