"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirMove|TestMaxDeleteSize|TestRcatSizeUploadHeaders|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Starting (try 3/5)
2026/08/01 04:35:16 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zufucih1xabu"
2026/08/01 04:35:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/01 04:35:16 DEBUG : Linkbox root 'rclone-test-zufucih1xabu': Using cached web token
2026/08/01 04:35:17 DEBUG : Creating backend with remote "/tmp/rclone1985084736"
=== RUN   TestMaxDeleteSize
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:35:23 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:35:23 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:35:24 DEBUG : medium: Trying to read object after upload: try again in 400ms (3/10)
2026/08/01 04:35:27 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:35:27 DEBUG : Waiting for deletions to finish
2026/08/01 04:35:27 ERROR : medium: Got fatal error on delete: --max-delete-size threshold reached
2026/08/01 04:35:29 INFO  : large: Deleted
2026/08/01 04:35:29 INFO  : small: Deleted
--- PASS: TestMaxDeleteSize (14.23s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:35:39 INFO  : A2: Making directory
2026/08/01 04:35:40 INFO  : A1/B2: Making directory
2026/08/01 04:35:42 INFO  : A1/B2/C2: Making directory
2026/08/01 04:35:43 INFO  : A1/B1/C3: Making directory
2026/08/01 04:35:45 INFO  : A3: Making directory
2026/08/01 04:35:46 INFO  : A3/B3: Making directory
2026/08/01 04:35:48 INFO  : A3/B3/C4: Making directory
2026/08/01 04:35:54 DEBUG : removing 1 level 3 directories
2026/08/01 04:35:54 INFO  : A3/B3/C4: Removing directory
2026/08/01 04:35:59 DEBUG : removing 2 level 3 directories
2026/08/01 04:35:59 INFO  : A1/B2/C2: Removing directory
2026/08/01 04:35:59 INFO  : A1/B1/C3: Removing directory
2026/08/01 04:36:01 DEBUG : removing 2 level 2 directories
2026/08/01 04:36:01 INFO  : A3/B3: Removing directory
2026/08/01 04:36:01 INFO  : A1/B2: Removing directory
2026/08/01 04:36:03 DEBUG : removing 2 level 1 directories
2026/08/01 04:36:03 INFO  : A3: Removing directory
2026/08/01 04:36:03 INFO  : A2: Removing directory
2026/08/01 04:36:10 DEBUG : removing 1 level 3 directories
2026/08/01 04:36:10 INFO  : A1/B1/C1: Removing directory
2026/08/01 04:36:12 DEBUG : removing 1 level 2 directories
2026/08/01 04:36:12 INFO  : A1/B1: Removing directory
2026/08/01 04:36:13 DEBUG : removing 1 level 1 directories
2026/08/01 04:36:13 INFO  : A1: Removing directory
2026/08/01 04:36:15 DEBUG : removing 1 level 0 directories
2026/08/01 04:36:15 INFO  : Linkbox root 'rclone-test-zufucih1xabu': Removing directory
2026/08/01 04:36:16 ERROR : error listing: directory not found
2026/08/01 04:36:17 ERROR : error listing: directory not found
--- PASS: TestRmdirsNoLeaveRoot (45.27s)
=== RUN   TestRmdirsLeaveRoot
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:36:18 INFO  : A1: Making directory
2026/08/01 04:36:20 INFO  : A1/B1: Making directory
2026/08/01 04:36:21 INFO  : A1/B1/C1: Making directory
2026/08/01 04:36:24 DEBUG : removing 1 level 3 directories
2026/08/01 04:36:24 INFO  : A1/B1/C1: Removing directory
2026/08/01 04:36:26 DEBUG : removing 1 level 2 directories
2026/08/01 04:36:26 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (13.17s)
=== RUN   TestRmdirsWithFilter
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:36:30 INFO  : A1: Making directory
2026/08/01 04:36:31 INFO  : A1/B1: Making directory
2026/08/01 04:36:33 INFO  : A1/B1/C1: Making directory
2026/08/01 04:36:36 DEBUG : removing 1 level 3 directories
2026/08/01 04:36:36 INFO  : A1/B1/C1: Removing directory
2026/08/01 04:36:38 DEBUG : removing 1 level 2 directories
2026/08/01 04:36:38 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (11.82s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:36:46 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:36:48 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:36:48 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:36:49 DEBUG : A1/two: Trying to read object after upload: try again in 400ms (3/10)
2026/08/01 04:36:53 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:36:54 DEBUG : A1/B1/three: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:37:01 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:37:01 INFO  : A1/B2: Making directory
2026/08/01 04:37:03 INFO  : A1/B1/C3: Making directory
2026/08/01 04:37:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:37:13 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/01 04:37:14 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:37:14 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/01 04:37:14 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:37:14 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/01 04:37:15 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:37:15 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/01 04:37:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:37:19 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/01 04:37:22 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/01 04:37:22 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/01 04:37:24 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/01 04:37:25 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/01 04:37:25 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/01 04:37:26 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/01 04:37:26 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/01 04:37:26 DEBUG : pacer: Reducing sleep to 200ms
2026/08/01 04:37:26 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1176h47m2.893226947s different from this computer
2026/08/01 04:37:31 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:37:31 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:37:31 DEBUG : A2/B1/three: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:37:31 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:37:32 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:37:32 DEBUG : A2/one: size = 3 OK
2026/08/01 04:37:32 INFO  : A1/one: Copied (new) to: A2/one
2026/08/01 04:37:32 DEBUG : A2/B1/three: Trying to read object after upload: try again in 400ms (3/10)
2026/08/01 04:37:32 DEBUG : A2/two: size = 3 OK
2026/08/01 04:37:32 INFO  : A1/two: Copied (new) to: A2/two
2026/08/01 04:37:32 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 04:37:33 DEBUG : A2/B1/C1/four: size = 4 OK
2026/08/01 04:37:33 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/08/01 04:37:33 DEBUG : A2/B1/three: size = 5 OK
2026/08/01 04:37:33 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/08/01 04:37:34 DEBUG : A2/B1/C2/five: size = 4 OK
2026/08/01 04:37:34 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/08/01 04:37:34 INFO  : A1/one: Deleted
2026/08/01 04:37:34 INFO  : A1/two: Deleted
2026/08/01 04:37:35 INFO  : A1/B1/C1/four: Deleted
2026/08/01 04:37:35 INFO  : A1/B1/three: Deleted
2026/08/01 04:37:35 INFO  : A1/B1/C2/five: Deleted
2026/08/01 04:38:02 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:38:02 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 04:38:02 DEBUG : A3/one: size = 3 OK
2026/08/01 04:38:02 INFO  : A2/one: Copied (new) to: A3/one
2026/08/01 04:38:02 DEBUG : A3/B1/C2/five: size = 4 OK
2026/08/01 04:38:02 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/08/01 04:38:03 DEBUG : A3/B1/C1/four: size = 4 OK
2026/08/01 04:38:03 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/08/01 04:38:03 DEBUG : A3/two: size = 3 OK
2026/08/01 04:38:03 INFO  : A2/two: Copied (new) to: A3/two
2026/08/01 04:38:03 DEBUG : A3/B1/three: size = 5 OK
2026/08/01 04:38:03 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/08/01 04:38:04 INFO  : A2/one: Deleted
2026/08/01 04:38:04 INFO  : A2/B1/C2/five: Deleted
2026/08/01 04:38:05 INFO  : A2/B1/C1/four: Deleted
2026/08/01 04:38:05 INFO  : A2/two: Deleted
2026/08/01 04:38:05 INFO  : A2/B1/three: Deleted
2026/08/01 04:38:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/08/01 04:38:07 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:07 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/08/01 04:38:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/08/01 04:38:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:08 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/08/01 04:38:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:14 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:16 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:18 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value)
2026/08/01 04:38:20 DEBUG : pacer: Reducing sleep to 1.5s
2026/08/01 04:38:23 DEBUG : pacer: Reducing sleep to 1.125s
2026/08/01 04:38:23 DEBUG : pacer: Reducing sleep to 843.75ms
2026/08/01 04:38:26 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/08/01 04:38:26 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/08/01 04:38:28 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/08/01 04:38:28 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/08/01 04:38:29 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/08/01 04:38:29 DEBUG : pacer: Reducing sleep to 200ms
    operations_test.go:1436: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1436
        	Error:      	Received unexpected error:
        	            	RenameDir rmdir: directory not empty
        	Test:       	TestDirMove
    run.go:186: removing dir "A2/B1" failed - try 1/3: sleeping 1s: directory not found
    run.go:186: removing dir "A2/B1" failed - try 2/3: sleeping 1.5s: directory not found
    run.go:186: removing dir "A2/B1" failed: directory not found
--- FAIL: TestDirMove (130.78s)
=== RUN   TestRcatSizeUploadHeaders
    run.go:198: Remote "Linkbox root 'rclone-test-zufucih1xabu'", Local "Local file system at /tmp/rclone1985084736", Modify Window "876000h0m0s"
2026/08/01 04:38:55 DEBUG : potato1: Trying to read object after upload: try again in 100ms (1/10)
    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: TestRcatSizeUploadHeaders (11.66s)
FAIL
2026/08/01 04:39:04 DEBUG : Linkbox root 'rclone-test-zufucih1xabu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestDirMove|TestMaxDeleteSize|TestRcatSizeUploadHeaders|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$'" - Finished ERROR in 3m49.280750111s (try 3/5): exit status 1: Failed [TestDirMove]