"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyDirMetadata|TestDirMove|TestDirsEqual|TestMaxDelete|TestMkdirMetadata|TestMkdirModTime|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsNoLeaveRoot|TestSetDirModTime|TestTouchDir)$'" - Starting (try 5/5) 2026/08/07 02:22:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wocibac6xumo" 2026/08/07 02:22:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/07 02:22:11 DEBUG : Linkbox root 'rclone-test-wocibac6xumo': Using cached web token 2026/08/07 02:22:12 DEBUG : Creating backend with remote "/tmp/rclone2777923182" === RUN TestMaxDelete run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" 2026/08/07 02:22:16 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:22:16 DEBUG : small: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:22:19 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:22:19 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:22:20 DEBUG : medium: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 02:22:20 DEBUG : medium: Trying to read object after upload: try again in 800ms (4/10) 2026/08/07 02:22:23 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:22:24 DEBUG : large: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:22:24 DEBUG : large: Trying to read object after upload: try again in 400ms (3/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 2026/08/07 02:22:32 DEBUG : Waiting for deletions to finish 2026/08/07 02:22:33 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/08/07 02:22:34 INFO : large: Deleted 2026/08/07 02:22:34 INFO : medium: Deleted operations_test.go:453: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453 Error: Not equal: expected: 1 actual : 3 Test: TestMaxDelete run.go:172: removing file "large" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "large" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "large" failed: could not Remove: Linkbox error 403: not permit run.go:172: removing file "medium" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "medium" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "medium" failed: could not Remove: Linkbox error 403: not permit 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 --- FAIL: TestMaxDelete (38.47s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" 2026/08/07 02:22:57 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:22:57 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:22:58 INFO : A2: Making directory 2026/08/07 02:22:59 INFO : A1/B2: Making directory 2026/08/07 02:23:01 INFO : A1/B2/C2: Making directory 2026/08/07 02:23:02 INFO : A1/B1/C3: Making directory 2026/08/07 02:23:04 INFO : A3: Making directory 2026/08/07 02:23:05 INFO : A3/B3: Making directory 2026/08/07 02:23:07 INFO : A3/B3/C4: Making directory 2026/08/07 02:23:13 DEBUG : removing 1 level 3 directories 2026/08/07 02:23:13 INFO : A3/B3/C4: Removing directory 2026/08/07 02:23:18 DEBUG : removing 2 level 3 directories 2026/08/07 02:23:18 INFO : A1/B2/C2: Removing directory 2026/08/07 02:23:18 INFO : A1/B1/C3: Removing directory 2026/08/07 02:23:20 DEBUG : removing 2 level 2 directories 2026/08/07 02:23:20 INFO : A3/B3: Removing directory 2026/08/07 02:23:20 INFO : A1/B2: Removing directory 2026/08/07 02:23:22 DEBUG : removing 2 level 1 directories 2026/08/07 02:23:22 INFO : A3: Removing directory 2026/08/07 02:23:22 INFO : A2: Removing directory 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 2026/08/07 02:23:38 DEBUG : removing 1 level 3 directories 2026/08/07 02:23:38 INFO : A1/B1/C1: Removing directory 2026/08/07 02:23:39 DEBUG : removing 1 level 2 directories 2026/08/07 02:23:39 INFO : A1/B1: Removing directory 2026/08/07 02:23:41 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:23:41 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/07 02:23:41 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:23:41 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/07 02:23:42 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:23:42 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/07 02:23:42 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:23:42 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/07 02:23:44 DEBUG : pacer: Reducing sleep to 1.5s fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/08/07 02:23:46 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/07 02:23:48 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/07 02:23:49 DEBUG : pacer: Reducing sleep to 632.8125ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/08/07 02:23:51 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/07 02:23:52 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/07 02:23:52 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:730 Error: Not equal: expected: []string{} actual : []string{"A1"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=2) "A1" } Test: TestRmdirsNoLeaveRoot Messages: directories 2026/08/07 02:23:57 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/07 02:23:57 DEBUG : pacer: Reducing sleep to 200ms --- FAIL: TestRmdirsNoLeaveRoot (68.38s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" 2026/08/07 02:24:02 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:24:03 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:24:03 DEBUG : A1/one: Trying to read object after upload: try again in 400ms (3/10) 2026/08/07 02:24:06 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:24:06 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/08/07 02:24:13 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/08/07 02:24:17 INFO : A1/B2: Making directory 2026/08/07 02:24:19 INFO : A1/B1/C3: Making directory 2026/08/07 02:24:34 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1340h53m2.124124728s different from this computer 2026/08/07 02:24:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:35 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/07 02:24:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:35 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/07 02:24:35 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:35 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/07 02:24:36 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:36 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/07 02:24:38 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:40 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:42 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:44 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:24:46 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/07 02:24:48 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/07 02:24:49 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/07 02:24:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/07 02:24:51 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/07 02:24:52 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/07 02:24:53 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/07 02:24:53 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/07 02:24:53 DEBUG : pacer: Reducing sleep to 200ms 2026/08/07 02:24:54 DEBUG : A2/B1/C1/four: size = 4 OK 2026/08/07 02:24:54 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/08/07 02:24:55 DEBUG : A2/B1/three: size = 5 OK 2026/08/07 02:24:55 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/08/07 02:24:55 DEBUG : A2/B1/C2/five: size = 4 OK 2026/08/07 02:24:55 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/08/07 02:24:56 DEBUG : A2/one: size = 3 OK 2026/08/07 02:24:56 INFO : A1/one: Copied (new) to: A2/one 2026/08/07 02:24:56 INFO : A1/B1/C1/four: Deleted 2026/08/07 02:24:56 DEBUG : A2/two: size = 3 OK 2026/08/07 02:24:56 INFO : A1/two: Copied (new) to: A2/two 2026/08/07 02:24:57 INFO : A1/B1/three: Deleted 2026/08/07 02:24:57 INFO : A1/B1/C2/five: Deleted 2026/08/07 02:24:57 INFO : A1/one: Deleted 2026/08/07 02:24:58 INFO : A1/two: Deleted 2026/08/07 02:25:24 DEBUG : A3/B1/C2/five: size = 4 OK 2026/08/07 02:25:24 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/08/07 02:25:25 DEBUG : A3/one: size = 3 OK 2026/08/07 02:25:25 INFO : A2/one: Copied (new) to: A3/one 2026/08/07 02:25:25 DEBUG : A3/two: size = 3 OK 2026/08/07 02:25:25 INFO : A2/two: Copied (new) to: A3/two 2026/08/07 02:25:25 DEBUG : A3/B1/three: size = 5 OK 2026/08/07 02:25:25 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/08/07 02:25:25 DEBUG : A3/B1/C1/four: size = 4 OK 2026/08/07 02:25:25 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/08/07 02:25:26 INFO : A2/B1/C2/five: Deleted 2026/08/07 02:25:26 INFO : A2/one: Deleted 2026/08/07 02:25:27 INFO : A2/two: Deleted 2026/08/07 02:25:27 INFO : A2/B1/three: Deleted 2026/08/07 02:25:27 INFO : A2/B1/C1/four: Deleted 2026/08/07 02:25:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/07 02:25:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/07 02:25:31 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:31 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/07 02:25:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:32 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/07 02:25:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:39 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:41 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:43 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 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: getting files failed: invalid character '<' looking for beginning of value Test: TestDirMove 2026/08/07 02:25:45 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/07 02:25:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/07 02:25:49 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/07 02:25:51 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/07 02:25:53 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/07 02:25:55 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/07 02:25:55 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/07 02:25:55 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/07 02:25:56 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/07 02:25:56 DEBUG : pacer: Reducing sleep to 200ms --- FAIL: TestDirMove (137.53s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" 2026/08/07 02:26:18 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (3.72s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1747: Skipping test as remote does not support modtime --- SKIP: TestTouchDir (0.41s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1785: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.41s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1804: Skipping test as remote does not support DirSetModTime or MkdirMetadata --- SKIP: TestMkdirModTime (0.41s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1825: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestCopyDirMetadata (0.41s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1861: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime --- SKIP: TestSetDirModTime (0.41s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1905: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata --- SKIP: TestDirsEqual (0.41s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-wocibac6xumo'", Local "Local file system at /tmp/rclone2777923182", Modify Window "876000h0m0s" operations_test.go:1974: Skipping as remote can't Move --- SKIP: TestRemoveExisting (0.41s) FAIL 2026/08/07 02:26:23 DEBUG : Linkbox root 'rclone-test-wocibac6xumo': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCopyDirMetadata|TestDirMove|TestDirsEqual|TestMaxDelete|TestMkdirMetadata|TestMkdirModTime|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsNoLeaveRoot|TestSetDirModTime|TestTouchDir)$'" - Finished ERROR in 4m13.397493148s (try 5/5): exit status 1: Failed [TestMaxDelete TestRmdirsNoLeaveRoot TestDirMove]