"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileBackupDir|TestMaxDeleteSize|TestPurge|TestRemoveExisting)$'" - Starting (try 2/5)
2026/02/19 02:17:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wajupip2lexu"
2026/02/19 02:17:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/19 02:17:08 DEBUG : Starting OpenDrive session with ID: a72898ce2aafa6f809730ace9e6592ae389e494d3b3666f9d199f4959495c0a8
2026/02/19 02:17:08 DEBUG : Creating backend with remote "/tmp/rclone2276589122"
=== RUN   TestCopyFileBackupDir
    run.go:185: Remote "OpenDrive root 'rclone-test-wajupip2lexu'", Local "Local file system at /tmp/rclone2276589122", Modify Window "1s"
2026/02/19 02:17:10 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0
2026/02/19 02:17:11 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wajupip2lexu/backup"
2026/02/19 02:17:12 DEBUG : Starting OpenDrive session with ID: e405ccb19052a158decf5ccb371e973c388f34d2ac59d983bf16f851d7f18b0e
2026/02/19 02:17:12 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone2276589122)
2026/02/19 02:17:12 DEBUG : dst/file1: size = 18 (OpenDrive root 'rclone-test-wajupip2lexu')
2026/02/19 02:17:12 DEBUG : dst/file1: Sizes differ
2026/02/19 02:17:14 INFO  : dst/file1: Moved (server-side)
2026/02/19 02:17:14 DEBUG : dst/file1: Uploading chunk 0, size=14, remain=0
2026/02/19 02:17:15 DEBUG : dst/file1: size = 14 OK
2026/02/19 02:17:15 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/02/19 02:17:15 INFO  : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (9.68s)
=== RUN   TestMaxDeleteSize
    run.go:185: Remote "OpenDrive root 'rclone-test-wajupip2lexu'", Local "Local file system at /tmp/rclone2276589122", Modify Window "1s"
2026/02/19 02:17:19 DEBUG : small: Uploading chunk 0, size=10, remain=0
2026/02/19 02:17:21 DEBUG : medium: Uploading chunk 0, size=60, remain=0
2026/02/19 02:17:23 DEBUG : large: Uploading chunk 0, size=100, remain=0
2026/02/19 02:17:24 DEBUG : Waiting for deletions to finish
2026/02/19 02:17:24 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached
2026/02/19 02:17:24 INFO  : large: Deleted
2026/02/19 02:17:24 INFO  : medium: Deleted
    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: TestMaxDeleteSize (14.52s)
=== RUN   TestPurge
2026/02/19 02:17:33 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-ximorin0tini"
2026/02/19 02:17:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/02/19 02:17:33 DEBUG : Starting OpenDrive session with ID: a2126d9bfd6770151e3ef9a0c4e9c9f894c8cdfa9c511ae78cc2dad157513eaa
2026/02/19 02:17:33 DEBUG : Creating backend with remote "/tmp/rclone467181358"
    run.go:185: Remote "OpenDrive root 'rclone-test-ximorin0tini'", Local "Local file system at /tmp/rclone467181358", Modify Window "1s"
2026/02/19 02:17:36 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2026/02/19 02:17:37 INFO  : A2: Making directory
2026/02/19 02:17:37 INFO  : A1/B2: Making directory
2026/02/19 02:17:37 INFO  : A1/B2/C2: Making directory
2026/02/19 02:17:38 INFO  : A1/B1/C3: Making directory
2026/02/19 02:17:38 INFO  : A3: Making directory
2026/02/19 02:17:38 INFO  : A3/B3: Making directory
2026/02/19 02:17:39 INFO  : A3/B3/C4: Making directory
2026/02/19 02:17:40 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2026/02/19 02:17:44 ERROR : error listing: directory not found
2026/02/19 02:17:44 DEBUG : OpenDrive root 'rclone-test-ximorin0tini': Purge remote
2026/02/19 02:17:44 NOTICE: purge failed: directory not found
--- PASS: TestPurge (11.25s)
=== RUN   TestRemoveExisting
    run.go:185: Remote "OpenDrive root 'rclone-test-wajupip2lexu'", Local "Local file system at /tmp/rclone2276589122", Modify Window "1s"
2026/02/19 02:17:45 DEBUG : sub dir/test remove existing: Uploading chunk 0, size=11, remain=0
2026/02/19 02:17:47 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: Uploading chunk 0, size=21, remain=0
2026/02/19 02:17:49 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.haqubeg7" before starting
2026/02/19 02:17:49 DEBUG : sub dir/test remove existing: same parent dir (ODFfMTAyMzg0ODRfbVpWOWk) - using file/rename instead of move_copy for sub dir/test remove existing.haqubeg7
2026/02/19 02:17:50 DEBUG : sub dir/test remove existing.haqubeg7: TEST: removing renamed existing file after operation
2026/02/19 02:17:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.zojazat1" before starting
2026/02/19 02:17:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfMTAyMzg0ODRfbVpWOWk) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.zojazat1
2026/02/19 02:17:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.zojazat1: TEST: renaming existing back after failed operation
2026/02/19 02:17:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.zojazat1: same parent dir (ODFfMTAyMzg0ODRfbVpWOWk) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789
2026/02/19 02:17:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.gerijaf7" before starting
2026/02/19 02:17:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: same parent dir (ODFfMTAyMzg0ODRfbVpWOWk) - using file/rename instead of move_copy for sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.gerijaf7
2026/02/19 02:17:53 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.gerijaf7: TEST: removing renamed existing file after operation
--- PASS: TestRemoveExisting (10.65s)
PASS
2026/02/19 02:17:54 DEBUG : OpenDrive root 'rclone-test-wajupip2lexu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCopyFileBackupDir|TestMaxDeleteSize|TestPurge|TestRemoveExisting)$'" - Finished OK in 47.353781719s (try 2/5)