"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Starting (try 2/5)
2026/06/25 03:13:22 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-caqimoh4rano"
2026/06/25 03:13:22 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/06/25 03:13:22 DEBUG : Starting OpenDrive session with ID: 81f2bad42466d10004096ed8814af664af989dbac36b70d5ff3284253bc8d4b8
2026/06/25 03:13:22 DEBUG : Creating backend with remote "/tmp/rclone2303959797"
=== RUN   TestSyncBackupDir
    run.go:198: Remote "OpenDrive root 'rclone-test-caqimoh4rano'", Local "Local file system at /tmp/rclone2303959797", Modify Window "1s"
2026/06/25 03:13:24 DEBUG : dst/one: Uploading chunk 0, size=3, remain=0
2026/06/25 03:13:26 DEBUG : dst/two: Uploading chunk 0, size=3, remain=0
2026/06/25 03:13:28 DEBUG : dst/three.txt: Uploading chunk 0, size=5, remain=0
2026/06/25 03:13:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-caqimoh4rano/dst"
2026/06/25 03:13:29 DEBUG : Starting OpenDrive session with ID: d5fe1c98a0fc1d84467ed8585ce39d8a1e129c836c7737875258ba39a3565d04
2026/06/25 03:13:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-caqimoh4rano/backup"
2026/06/25 03:13:30 DEBUG : Starting OpenDrive session with ID: 62a40644515520865bb65c6eeb794e630b40fa67e3b0f77ffddb087e94cbc2a2
2026/06/25 03:13:31 DEBUG : one: size = 4 (Local file system at /tmp/rclone2303959797)
2026/06/25 03:13:31 DEBUG : one: size = 3 (OpenDrive root 'rclone-test-caqimoh4rano/dst')
2026/06/25 03:13:31 DEBUG : one: Sizes differ
2026/06/25 03:13:31 DEBUG : two: size = 3 OK
2026/06/25 03:13:31 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/06/25 03:13:31 DEBUG : two: Unchanged skipping
2026/06/25 03:13:31 DEBUG : OpenDrive root 'rclone-test-caqimoh4rano/dst': Waiting for checks to finish
2026/06/25 03:13:32 INFO  : one: Moved (server-side)
2026/06/25 03:13:32 DEBUG : OpenDrive root 'rclone-test-caqimoh4rano/dst': Waiting for transfers to finish
2026/06/25 03:13:33 DEBUG : one: Uploading chunk 0, size=4, remain=0
2026/06/25 03:13:34 DEBUG : one: size = 4 OK
2026/06/25 03:13:34 DEBUG : one: md5 = c7957179c41f69d44f217a108c7915d8 OK
2026/06/25 03:13:34 INFO  : one: Copied (new)
2026/06/25 03:13:34 DEBUG : Waiting for deletions to finish
2026/06/25 03:13:35 INFO  : three.txt: Moved (server-side)
2026/06/25 03:13:35 INFO  : three.txt: Moved into backup dir
2026/06/25 03:13:36 DEBUG : dst/three.txt: Uploading chunk 0, size=6, remain=0
2026/06/25 03:13:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone2303959797)
2026/06/25 03:13:38 DEBUG : one: size = 4 (OpenDrive root 'rclone-test-caqimoh4rano/dst')
2026/06/25 03:13:38 DEBUG : one: Sizes differ
2026/06/25 03:13:38 DEBUG : two: size = 3 OK
2026/06/25 03:13:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/06/25 03:13:38 DEBUG : two: Unchanged skipping
2026/06/25 03:13:38 DEBUG : OpenDrive root 'rclone-test-caqimoh4rano/dst': Waiting for checks to finish
2026/06/25 03:13:38 INFO  : one: Deleted
2026/06/25 03:13:39 INFO  : one: Moved (server-side)
2026/06/25 03:13:39 DEBUG : OpenDrive root 'rclone-test-caqimoh4rano/dst': Waiting for transfers to finish
2026/06/25 03:13:39 DEBUG : one: Uploading chunk 0, size=5, remain=0
2026/06/25 03:13:41 DEBUG : one: size = 5 OK
2026/06/25 03:13:41 DEBUG : one: md5 = 0f93e81041f0cab37c37a05ae998b219 OK
2026/06/25 03:13:41 INFO  : one: Copied (new)
2026/06/25 03:13:41 DEBUG : Waiting for deletions to finish
2026/06/25 03:13:41 INFO  : three.txt: Deleted
2026/06/25 03:13:41 INFO  : three.txt: Moved (server-side)
2026/06/25 03:13:41 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (21.25s)
PASS
2026/06/25 03:13:44 DEBUG : OpenDrive root 'rclone-test-caqimoh4rano': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^TestSyncBackupDir$'" - Finished OK in 22.583045318s (try 2/5)