"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileBackupDir|TestDirMove|TestPurge)$'" - Starting (try 2/5)
2026/01/14 02:31:55 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nesikad0fipe"
2026/01/14 02:31:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/14 02:31:56 DEBUG : Starting OpenDrive session with ID: 74ef7d3b2e5efc04db15d00fabc6c3343634f705d76fee3b752d8e7baab593c3
2026/01/14 02:31:56 DEBUG : Creating backend with remote "/tmp/rclone1686927717"
=== RUN   TestCopyFileBackupDir
    run.go:185: Remote "OpenDrive root 'rclone-test-nesikad0fipe'", Local "Local file system at /tmp/rclone1686927717", Modify Window "1s"
2026/01/14 02:31:58 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0
2026/01/14 02:31:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-nesikad0fipe/backup"
2026/01/14 02:32:00 DEBUG : Starting OpenDrive session with ID: 268ae2c38832b3a1b3e045fda5d037533682f7a28e490788867d90724ce62fc5
2026/01/14 02:32:00 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone1686927717)
2026/01/14 02:32:00 DEBUG : dst/file1: size = 18 (OpenDrive root 'rclone-test-nesikad0fipe')
2026/01/14 02:32:00 DEBUG : dst/file1: Sizes differ
2026/01/14 02:32:02 INFO  : dst/file1: Moved (server-side)
2026/01/14 02:32:02 DEBUG : dst/file1: Uploading chunk 0, size=14, remain=0
2026/01/14 02:32:03 DEBUG : dst/file1: size = 14 OK
2026/01/14 02:32:03 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/01/14 02:32:03 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (10.28s)
=== RUN   TestPurge
2026/01/14 02:32:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-diwudug3juvo"
2026/01/14 02:32:06 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/14 02:32:07 DEBUG : Starting OpenDrive session with ID: 0959cacf924fc7883b98ec96c3747b98f2c83f6c75072971b12fa42a624b5bcb
2026/01/14 02:32:07 DEBUG : Creating backend with remote "/tmp/rclone1857697132"
    run.go:185: Remote "OpenDrive root 'rclone-test-diwudug3juvo'", Local "Local file system at /tmp/rclone1857697132", Modify Window "1s"
2026/01/14 02:32:09 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2026/01/14 02:32:10 INFO  : A2: Making directory
2026/01/14 02:32:11 INFO  : A1/B2: Making directory
2026/01/14 02:32:11 INFO  : A1/B2/C2: Making directory
2026/01/14 02:32:11 INFO  : A1/B1/C3: Making directory
2026/01/14 02:32:12 INFO  : A3: Making directory
2026/01/14 02:32:12 INFO  : A3/B3: Making directory
2026/01/14 02:32:12 INFO  : A3/B3/C4: Making directory
2026/01/14 02:32:14 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2026/01/14 02:32:17 DEBUG : OpenDrive root 'rclone-test-diwudug3juvo': Purge remote
2026/01/14 02:32:17 NOTICE: purge failed: Folder is already deleted (Error 403)
--- PASS: TestPurge (11.06s)
=== RUN   TestDirMove
    run.go:185: Remote "OpenDrive root 'rclone-test-nesikad0fipe'", Local "Local file system at /tmp/rclone1686927717", Modify Window "1s"
2026/01/14 02:32:19 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0
2026/01/14 02:32:20 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2026/01/14 02:32:23 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0
2026/01/14 02:32:25 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0
2026/01/14 02:32:27 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0
2026/01/14 02:32:28 INFO  : A1/B2: Making directory
2026/01/14 02:32:28 INFO  : A1/B1/C3: Making directory
2026/01/14 02:32:30 DEBUG : A2: same parent dir (ODFfOTcwMjk1Nl9UQ0I0YQ) - using folder/rename instead of move_copy
2026/01/14 02:32:34 INFO  : A2/one: Moved (server-side) to: A3/one
2026/01/14 02:32:34 INFO  : A2/two: Moved (server-side) to: A3/two
2026/01/14 02:32:34 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2026/01/14 02:32:34 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2026/01/14 02:32:34 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
    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/01/14 02:32:45 INFO  : OpenDrive root 'rclone-test-nesikad0fipe': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/01/14 02:32:48 INFO  : A3/one: Moved (server-side) to: A4/one
2026/01/14 02:32:48 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2026/01/14 02:32:48 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2026/01/14 02:32:48 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2026/01/14 02:32:49 INFO  : A3/two: Moved (server-side) to: A4/two
    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
--- PASS: TestDirMove (46.90s)
PASS
2026/01/14 02:33:04 DEBUG : OpenDrive root 'rclone-test-nesikad0fipe': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileBackupDir|TestDirMove|TestPurge)$'" - Finished OK in 1m9.627791357s (try 2/5)