"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestMoveFile|TestRmdirsNoLeaveRoot|TestTouchDir)$'" - Starting (try 2/5)
2026/03/19 02:19:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vuneduc6gori"
2026/03/19 02:19:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/03/19 02:19:17 DEBUG : Starting OpenDrive session with ID: 340878cba05eab125c9fb9ff694be9c24ccab221b7d5cebabc7100a59bf73b40
2026/03/19 02:19:17 DEBUG : Creating backend with remote "/tmp/rclone1690362986"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:185: Remote "OpenDrive root 'rclone-test-vuneduc6gori'", Local "Local file system at /tmp/rclone1690362986", Modify Window "1s"
2026/03/19 02:19:19 DEBUG : A1/B1/C1/one: Uploading chunk 0, size=3, remain=0
2026/03/19 02:19:20 INFO  : A2: Making directory
2026/03/19 02:19:21 INFO  : A1/B2: Making directory
2026/03/19 02:19:21 INFO  : A1/B2/C2: Making directory
2026/03/19 02:19:21 INFO  : A1/B1/C3: Making directory
2026/03/19 02:19:22 INFO  : A3: Making directory
2026/03/19 02:19:22 INFO  : A3/B3: Making directory
2026/03/19 02:19:22 INFO  : A3/B3/C4: Making directory
2026/03/19 02:19:23 DEBUG : A1/two: Uploading chunk 0, size=3, remain=0
2026/03/19 02:19:25 DEBUG : removing 1 level 3 directories
2026/03/19 02:19:25 INFO  : A3/B3/C4: Removing directory
2026/03/19 02:19:28 DEBUG : removing 2 level 3 directories
2026/03/19 02:19:28 INFO  : A1/B2/C2: Removing directory
2026/03/19 02:19:28 INFO  : A1/B1/C3: Removing directory
2026/03/19 02:19:29 DEBUG : removing 2 level 2 directories
2026/03/19 02:19:29 INFO  : A3/B3: Removing directory
2026/03/19 02:19:29 INFO  : A1/B2: Removing directory
2026/03/19 02:19:29 DEBUG : removing 2 level 1 directories
2026/03/19 02:19:29 INFO  : A3: Removing directory
2026/03/19 02:19:29 INFO  : A2: Removing directory
2026/03/19 02:19:33 DEBUG : removing 1 level 3 directories
2026/03/19 02:19:33 INFO  : A1/B1/C1: Removing directory
2026/03/19 02:19:33 DEBUG : removing 1 level 2 directories
2026/03/19 02:19:33 INFO  : A1/B1: Removing directory
2026/03/19 02:19:33 DEBUG : removing 1 level 1 directories
2026/03/19 02:19:33 INFO  : A1: Removing directory
2026/03/19 02:19:34 DEBUG : removing 1 level 0 directories
2026/03/19 02:19:34 INFO  : OpenDrive root 'rclone-test-vuneduc6gori': Removing directory
2026/03/19 02:19:35 ERROR : error listing: directory not found
2026/03/19 02:19:35 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (17.75s)
=== RUN   TestMoveFile
    run.go:185: Remote "OpenDrive root 'rclone-test-vuneduc6gori'", Local "Local file system at /tmp/rclone1690362986", Modify Window "1s"
2026/03/19 02:19:35 DEBUG : file1: Need to transfer - File not found at Destination
2026/03/19 02:19:37 DEBUG : sub/file2: Uploading chunk 0, size=14, remain=0
2026/03/19 02:19:39 DEBUG : sub/file2: size = 14 OK
2026/03/19 02:19:39 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/03/19 02:19:39 INFO  : file1: Copied (new) to: sub/file2
2026/03/19 02:19:39 INFO  : file1: Deleted
2026/03/19 02:19:39 DEBUG : sub/file2: size = 14 OK
2026/03/19 02:19:39 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/03/19 02:19:39 DEBUG : file1: Unchanged skipping
2026/03/19 02:19:39 INFO  : file1: Deleted
2026/03/19 02:19:40 DEBUG : OpenDrive root 'rclone-test-vuneduc6gori': don't need to copy/move sub/file2, it is already at target location
    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: TestMoveFile (14.44s)
=== RUN   TestTouchDir
    run.go:185: Remote "OpenDrive root 'rclone-test-vuneduc6gori'", Local "Local file system at /tmp/rclone1690362986", Modify Window "1s"
2026/03/19 02:19:50 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
2026/03/19 02:19:53 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
2026/03/19 02:19:55 DEBUG : sub dir/potato3: Uploading chunk 0, size=5, remain=0
2026/03/19 02:19:57 DEBUG : OpenDrive root 'rclone-test-vuneduc6gori': Touching "empty space"
2026/03/19 02:19:57 DEBUG : OpenDrive root 'rclone-test-vuneduc6gori': Touching "potato2"
2026/03/19 02:19:58 DEBUG : OpenDrive root 'rclone-test-vuneduc6gori': Touching "sub dir/potato3"
--- PASS: TestTouchDir (10.71s)
PASS
2026/03/19 02:20:00 DEBUG : OpenDrive root 'rclone-test-vuneduc6gori': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestMoveFile|TestRmdirsNoLeaveRoot|TestTouchDir)$'" - Finished OK in 44.405970234s (try 2/5)