"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirMove|TestMoveFileBackupDir|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Starting (try 2/5)
2025/12/23 03:00:08 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-miheken9jeqo"
2025/12/23 03:00:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/23 03:00:09 DEBUG : Starting OpenDrive session with ID: d5ad1bf126da728b9cf290af7f1d54cb58368fc06ac5a471169692658c167092
2025/12/23 03:00:09 DEBUG : Creating backend with remote "/tmp/rclone4016527154"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:185: Remote "OpenDrive root 'rclone-test-miheken9jeqo'", Local "Local file system at /tmp/rclone4016527154", Modify Window "1s"
2025/12/23 03:00:12 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2025/12/23 03:00:13 INFO  : A2: Making directory
2025/12/23 03:00:13 INFO  : A1/B2: Making directory
2025/12/23 03:00:14 INFO  : A1/B2/C2: Making directory
2025/12/23 03:00:14 INFO  : A1/B1/C3: Making directory
2025/12/23 03:00:15 INFO  : A3: Making directory
2025/12/23 03:00:15 INFO  : A3/B3: Making directory
2025/12/23 03:00:15 INFO  : A3/B3/C4: Making directory
2025/12/23 03:00:17 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/12/23 03:00:19 DEBUG : removing 1 level 3 directories
2025/12/23 03:00:19 INFO  : A3/B3/C4: Removing directory
2025/12/23 03:00:21 DEBUG : removing 2 level 3 directories
2025/12/23 03:00:21 INFO  : A1/B2/C2: Removing directory
2025/12/23 03:00:21 INFO  : A1/B1/C3: Removing directory
2025/12/23 03:00:22 DEBUG : removing 2 level 2 directories
2025/12/23 03:00:22 INFO  : A3/B3: Removing directory
2025/12/23 03:00:22 INFO  : A1/B2: Removing directory
2025/12/23 03:00:22 DEBUG : removing 2 level 1 directories
2025/12/23 03:00:22 INFO  : A3: Removing directory
2025/12/23 03:00:22 INFO  : A2: Removing directory
2025/12/23 03:00:25 DEBUG : removing 1 level 3 directories
2025/12/23 03:00:25 INFO  : A1/B1/C1: Removing directory
2025/12/23 03:00:25 DEBUG : removing 1 level 2 directories
2025/12/23 03:00:25 INFO  : A1/B1: Removing directory
2025/12/23 03:00:26 DEBUG : removing 1 level 1 directories
2025/12/23 03:00:26 INFO  : A1: Removing directory
2025/12/23 03:00:26 DEBUG : removing 1 level 0 directories
2025/12/23 03:00:26 INFO  : OpenDrive root 'rclone-test-miheken9jeqo': Removing directory
2025/12/23 03:00:27 ERROR : error listing: directory not found
2025/12/23 03:00:27 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (17.57s)
=== RUN   TestRmdirsWithFilter
    run.go:185: Remote "OpenDrive root 'rclone-test-miheken9jeqo'", Local "Local file system at /tmp/rclone4016527154", Modify Window "1s"
2025/12/23 03:00:27 INFO  : A1: Making directory
2025/12/23 03:00:28 INFO  : A1/B1: Making directory
2025/12/23 03:00:28 INFO  : A1/B1/C1: Making directory
2025/12/23 03:00:30 DEBUG : removing 1 level 3 directories
2025/12/23 03:00:30 INFO  : A1/B1/C1: Removing directory
2025/12/23 03:00:31 DEBUG : removing 1 level 2 directories
2025/12/23 03:00:31 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (5.47s)
=== RUN   TestMoveFileBackupDir
    run.go:185: Remote "OpenDrive root 'rclone-test-miheken9jeqo'", Local "Local file system at /tmp/rclone4016527154", Modify Window "1s"
2025/12/23 03:00:34 DEBUG : dst/file1: Uploading chunk 0, size=18, remain=0
2025/12/23 03:00:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-miheken9jeqo/backup"
2025/12/23 03:00:36 DEBUG : Starting OpenDrive session with ID: dd1d1b2d6bb917ee2898f3a630a1cef32e584100da154406ed5ecae0565a69f9
2025/12/23 03:00:37 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4016527154)
2025/12/23 03:00:37 DEBUG : dst/file1: size = 18 (OpenDrive root 'rclone-test-miheken9jeqo')
2025/12/23 03:00:37 DEBUG : dst/file1: Sizes differ
2025/12/23 03:00:38 INFO  : dst/file1: Moved (server-side)
2025/12/23 03:00:39 DEBUG : dst/file1: Uploading chunk 0, size=14, remain=0
2025/12/23 03:00:40 DEBUG : dst/file1: size = 14 OK
2025/12/23 03:00:40 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2025/12/23 03:00:40 INFO  : dst/file1: Copied (new)
2025/12/23 03:00:40 INFO  : dst/file1: Deleted
    run.go:130: removing dir "dst" failed - try 1/3: folder not empty
--- PASS: TestMoveFileBackupDir (11.62s)
=== RUN   TestDirMove
    run.go:185: Remote "OpenDrive root 'rclone-test-miheken9jeqo'", Local "Local file system at /tmp/rclone4016527154", Modify Window "1s"
2025/12/23 03:00:45 DEBUG : A1/one: Uploading chunk 0, size=3, remain=0
2025/12/23 03:00:47 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2025/12/23 03:00:49 DEBUG : A1/B1/three: Uploading chunk 0, size=5, remain=0
2025/12/23 03:00:52 DEBUG : A1/B1/C1/four: Uploading chunk 0, size=4, remain=0
2025/12/23 03:00:54 DEBUG : A1/B1/C2/five: Uploading chunk 0, size=4, remain=0
2025/12/23 03:00:55 INFO  : A1/B2: Making directory
2025/12/23 03:00:56 INFO  : A1/B1/C3: Making directory
2025/12/23 03:00:57 DEBUG : A2: same parent dir (ODFfOTY1MjA2NV8wNHUwMw) - using folder/rename instead of move_copy
    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
2025/12/23 03:01:10 INFO  : A2/one: Moved (server-side) to: A3/one
2025/12/23 03:01:10 INFO  : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four
2025/12/23 03:01:10 INFO  : A2/B1/three: Moved (server-side) to: A3/B1/three
2025/12/23 03:01:10 INFO  : A2/two: Moved (server-side) to: A3/two
2025/12/23 03:01:10 INFO  : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five
2025/12/23 03:01:13 INFO  : OpenDrive root 'rclone-test-miheken9jeqo': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2025/12/23 03:01:17 INFO  : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four
2025/12/23 03:01:17 INFO  : A3/one: Moved (server-side) to: A4/one
2025/12/23 03:01:17 INFO  : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five
2025/12/23 03:01:17 INFO  : A3/two: Moved (server-side) to: A4/two
2025/12/23 03:01:17 INFO  : A3/B1/three: Moved (server-side) to: A4/B1/three
    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 (48.88s)
PASS
2025/12/23 03:01:33 DEBUG : OpenDrive root 'rclone-test-miheken9jeqo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestDirMove|TestMoveFileBackupDir|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Finished OK in 1m25.045932324s (try 2/5)