"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckDownload|TestCheckSum|TestDelete|TestDirMove|TestLs|TestLsd|TestPurge)$|^TestCheck$/^3$|^TestCheckSizeOnly$/^1$'" - Starting (try 2/5)
2025/08/03 01:29:51 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cunewuk9doba"
2025/08/03 01:29:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/03 01:29:51 DEBUG : Starting OpenDrive session with ID: a85ee71d03eaf333703f3d1efe2cf9c84d4f56e066a178733687bbf60b8b6fd0
2025/08/03 01:29:51 DEBUG : Creating backend with remote "/tmp/rclone3956488468"
=== RUN   TestCheck
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:29:53 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
2025/08/03 01:29:55 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
=== RUN   TestCheck/3
2025/08/03 01:29:57 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2025/08/03 01:29:59 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
--- PASS: TestCheck (9.51s)
    --- PASS: TestCheck/3 (0.18s)
=== RUN   TestCheckDownload
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:30:02 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2025/08/03 01:30:05 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
=== RUN   TestCheckDownload/3
2025/08/03 01:30:07 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2025/08/03 01:30:11 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
=== NAME  TestCheckDownload
    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
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
=== NAME  TestCheckDownload
    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: TestCheckDownload (27.76s)
    --- PASS: TestCheckDownload/1 (0.38s)
    --- PASS: TestCheckDownload/2 (0.53s)
    --- PASS: TestCheckDownload/3 (0.35s)
    --- PASS: TestCheckDownload/4 (0.71s)
    --- PASS: TestCheckDownload/5 (0.55s)
    --- PASS: TestCheckDownload/6 (0.40s)
    --- PASS: TestCheckDownload/7 (0.61s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:30:29 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
=== RUN   TestCheckSizeOnly/1
2025/08/03 01:30:31 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2025/08/03 01:30:33 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2025/08/03 01:30:35 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
--- PASS: TestCheckSizeOnly (8.98s)
    --- PASS: TestCheckSizeOnly/1 (0.18s)
=== RUN   TestCheckSum
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:30:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-cunewuk9doba/data"
2025/08/03 01:30:38 DEBUG : Starting OpenDrive session with ID: ba1da15360c44f0ad839f3970af79e6435c001757ec22b8452d83865fd6902e3
2025/08/03 01:30:40 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/08/03 01:30:42 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSum/subtest1
2025/08/03 01:30:44 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/08/03 01:30:46 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSum/subtest2
2025/08/03 01:30:48 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest3
2025/08/03 01:30:50 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest4
2025/08/03 01:30:52 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSum/subtest5
2025/08/03 01:30:54 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSum/subtest6
2025/08/03 01:30:56 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/08/03 01:30:58 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/08/03 01:30:59 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest7
--- PASS: TestCheckSum (24.36s)
    --- PASS: TestCheckSum/subtest1 (0.71s)
    --- PASS: TestCheckSum/subtest2 (0.52s)
    --- PASS: TestCheckSum/subtest3 (0.55s)
    --- PASS: TestCheckSum/subtest4 (0.52s)
    --- PASS: TestCheckSum/subtest5 (0.52s)
    --- PASS: TestCheckSum/subtest6 (0.51s)
    --- PASS: TestCheckSum/subtest7 (0.52s)
=== RUN   TestLsd
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:31:03 DEBUG : sub dir/hello world: Uploading chunk 0, size=11, remain=0
--- PASS: TestLsd (3.58s)
=== RUN   TestLs
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:31:06 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2025/08/03 01:31:08 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
--- PASS: TestLs (4.56s)
=== RUN   TestDelete
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:31:11 DEBUG : small: Uploading chunk 0, size=10, remain=0
2025/08/03 01:31:13 DEBUG : medium: Uploading chunk 0, size=60, remain=0
2025/08/03 01:31:14 DEBUG : large: Uploading chunk 0, size=100, remain=0
2025/08/03 01:31:15 DEBUG : Waiting for deletions to finish
2025/08/03 01:31:16 DEBUG : large: Excluded (Size Filter)
2025/08/03 01:31:16 DEBUG : large: Excluded
2025/08/03 01:31:16 INFO  : medium: Deleted
2025/08/03 01:31:16 INFO  : small: Deleted
    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: TestDelete (13.77s)
=== RUN   TestPurge
2025/08/03 01:31:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wugeluk8sixu"
2025/08/03 01:31:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/03 01:31:24 DEBUG : Starting OpenDrive session with ID: 17a64a1262aa49daf723e01ad77d28a2fb58898549cbf018e12c6520d8b3d86d
2025/08/03 01:31:25 DEBUG : Creating backend with remote "/tmp/rclone1922127238"
    run.go:180: Remote "OpenDrive root 'rclone-test-wugeluk8sixu'", Local "Local file system at /tmp/rclone1922127238", Modify Window "1s"
2025/08/03 01:31:27 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2025/08/03 01:31:28 INFO  : A2: Making directory
2025/08/03 01:31:28 INFO  : A1/B2: Making directory
2025/08/03 01:31:28 INFO  : A1/B2/C2: Making directory
2025/08/03 01:31:29 INFO  : A1/B1/C3: Making directory
2025/08/03 01:31:29 INFO  : A3: Making directory
2025/08/03 01:31:30 INFO  : A3/B3: Making directory
2025/08/03 01:31:30 INFO  : A3/B3/C4: Making directory
2025/08/03 01:31:31 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/08/03 01:31:35 DEBUG : OpenDrive root 'rclone-test-wugeluk8sixu': Purge remote
2025/08/03 01:31:35 NOTICE: purge failed: Folder is already deleted (Error 403)
--- PASS: TestPurge (11.03s)
=== RUN   TestDirMove
    run.go:180: Remote "OpenDrive root 'rclone-test-cunewuk9doba'", Local "Local file system at /tmp/rclone3956488468", Modify Window "1s"
2025/08/03 01:31:36 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0
2025/08/03 01:31:38 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/08/03 01:31:40 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0
2025/08/03 01:31:42 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0
2025/08/03 01:31:44 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0
2025/08/03 01:31:45 INFO  : A1/B2: Making directory
2025/08/03 01:31:45 INFO  : A1/B1/C3: Making directory
2025/08/03 01:31:47 DEBUG : A2: same parent dir (ODFfOTM2NDI1MV90WGNlUg) - using folder/rename instead of move_copy
2025/08/03 01:31:51 INFO  : A2/one: Moved (server-side) to: A3/one
2025/08/03 01:31:51 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/08/03 01:31:51 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/08/03 01:31:51 INFO  : A2/two: Moved (server-side) to: A3/two
2025/08/03 01:31:51 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/08/03 01:31:55 INFO  : OpenDrive root 'rclone-test-cunewuk9doba': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/08/03 01:31:58 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/08/03 01:31:58 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/08/03 01:31:58 INFO  : A3/two: Moved (server-side) to: A4/two
2025/08/03 01:31:58 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
2025/08/03 01:31:58 INFO  : A3/one: Moved (server-side) to: A4/one
--- PASS: TestDirMove (29.65s)
PASS
2025/08/03 01:32:05 DEBUG : OpenDrive root 'rclone-test-cunewuk9doba': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestCheckDownload|TestCheckSum|TestDelete|TestDirMove|TestLs|TestLsd|TestPurge)$|^TestCheck$/^3$|^TestCheckSizeOnly$/^1$'" - Finished OK in 2m14.562922634s (try 2/5)