"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCat|TestCheckSumDownload|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURLDownloadHeaders|TestDirMove|TestDirsEqual|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 3/5) 2026/07/29 04:51:10 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dobaxoz0kaho" 2026/07/29 04:51:10 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/29 04:51:10 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': Using cached web token 2026/07/29 04:51:11 DEBUG : Creating backend with remote "/tmp/rclone3503978095" === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:51:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dobaxoz0kaho/data" 2026/07/29 04:51:11 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho/data': Using cached web token 2026/07/29 04:51:19 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:51:20 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) === RUN TestCheckSumDownload/subtest1 2026/07/29 04:51:24 DEBUG : data/potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:51:24 DEBUG : data/potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:51:25 DEBUG : data/potato: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:51:26 DEBUG : test.sum: Update: removing old file 2026/07/29 04:51:29 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:51:29 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:51:30 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:51:30 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10) 2026/07/29 04:51:31 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/29 04:51:33 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/29 04:51:37 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/29 04:51:43 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/29 04:51:56 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/29 04:52:22 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10) === NAME TestCheckSumDownload run.go:303: Failed to put "test.sum" to "Linkbox root 'rclone-test-dobaxoz0kaho'": object not found run.go:186: removing dir "data" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestCheckSumDownload (130.02s) --- PASS: TestCheckSumDownload/subtest1 (1.54s) === RUN TestCopyFileCompareDest run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:53:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dobaxoz0kaho/dst" 2026/07/29 04:53:21 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho/dst': Using cached web token 2026/07/29 04:53:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dobaxoz0kaho/CompareDest" 2026/07/29 04:53:22 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho/CompareDest': Using cached web token 2026/07/29 04:53:23 DEBUG : one: Need to transfer - File not found at Destination 2026/07/29 04:53:27 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:53:27 DEBUG : one: size = 3 OK 2026/07/29 04:53:27 INFO : one: Copied (new) 2026/07/29 04:53:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone3503978095) 2026/07/29 04:53:29 DEBUG : one: size = 3 (Linkbox root 'rclone-test-dobaxoz0kaho/dst') 2026/07/29 04:53:29 DEBUG : one: Sizes differ 2026/07/29 04:53:29 DEBUG : one: Update: removing old file 2026/07/29 04:53:32 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:53:32 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:53:33 DEBUG : one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:53:33 DEBUG : one: size = 5 OK 2026/07/29 04:53:33 INFO : one: Copied (replaced existing) 2026/07/29 04:53:34 DEBUG : dst/one: Update: removing old file 2026/07/29 04:53:37 DEBUG : dst/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:53:38 DEBUG : dst/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:53:42 DEBUG : CompareDest/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:53:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone3503978095) 2026/07/29 04:53:43 DEBUG : one: size = 3 (Linkbox root 'rclone-test-dobaxoz0kaho/dst') 2026/07/29 04:53:43 DEBUG : one: Sizes differ 2026/07/29 04:53:43 DEBUG : one: size = 5 OK 2026/07/29 04:53:43 DEBUG : one: Sizes identical 2026/07/29 04:53:43 DEBUG : one: Destination found in --compare-dest, skipping 2026/07/29 04:53:46 DEBUG : CompareDest/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:53:46 DEBUG : CompareDest/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:53:48 DEBUG : two: Need to transfer - File not found at Destination 2026/07/29 04:53:48 DEBUG : two: size = 3 OK 2026/07/29 04:53:48 DEBUG : two: Sizes identical 2026/07/29 04:53:48 DEBUG : two: Destination found in --compare-dest, skipping 2026/07/29 04:53:49 DEBUG : two: Need to transfer - File not found at Destination 2026/07/29 04:53:49 DEBUG : two: size = 3 OK 2026/07/29 04:53:49 DEBUG : two: Sizes identical 2026/07/29 04:53:49 DEBUG : two: Destination found in --compare-dest, skipping 2026/07/29 04:53:50 DEBUG : two: Need to transfer - File not found at Destination 2026/07/29 04:53:50 DEBUG : two: size = 5 (Local file system at /tmp/rclone3503978095) 2026/07/29 04:53:50 DEBUG : two: size = 3 (Linkbox root 'rclone-test-dobaxoz0kaho/CompareDest') 2026/07/29 04:53:50 DEBUG : two: Sizes differ 2026/07/29 04:53:52 DEBUG : two: size = 5 OK 2026/07/29 04:53:52 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (41.69s) === RUN TestMaxDelete run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:54:04 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:54:07 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:54:07 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:54:08 DEBUG : medium: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:54:10 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:54:11 DEBUG : Waiting for deletions to finish 2026/07/29 04:54:11 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/07/29 04:54:12 INFO : large: Deleted 2026/07/29 04:54:12 INFO : medium: Deleted 2026/07/29 04:54:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/29 04:54:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/29 04:54:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/29 04:54:14 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:14 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/29 04:54:16 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:17 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:19 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:21 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:23 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:25 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:25 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value operations_test.go:452: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:452 Error: Received unexpected error: getting files failed: invalid character '<' looking for beginning of value Test: TestMaxDelete 2026/07/29 04:54:27 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:31 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:54:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/29 04:54:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/29 04:54:37 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/29 04:54:38 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/29 04:54:39 DEBUG : pacer: Reducing sleep to 474.609375ms --- FAIL: TestMaxDelete (36.76s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:54:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/29 04:54:40 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/29 04:54:42 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/29 04:54:42 DEBUG : pacer: Reducing sleep to 200ms 2026/07/29 04:54:44 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:54:44 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:54:47 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:54:47 DEBUG : large: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:54:48 DEBUG : Waiting for deletions to finish 2026/07/29 04:54:49 INFO : medium: Deleted 2026/07/29 04:54:49 INFO : small: Deleted operations_test.go:471: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:471 Error: An error is expected but got nil. Test: TestMaxDeleteSizeLargeFile 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 run.go:172: removing file "small" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "small" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "small" failed: could not Remove: Linkbox error 403: not permit --- FAIL: TestMaxDeleteSizeLargeFile (18.65s) === RUN TestMaxDeleteSize run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:55:00 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:55:00 DEBUG : small: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:55:01 DEBUG : small: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:55:01 DEBUG : small: Trying to read object after upload: try again in 800ms (4/10) 2026/07/29 04:55:05 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:55:05 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/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/07/29 04:55:15 DEBUG : Waiting for deletions to finish 2026/07/29 04:55:16 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/07/29 04:55:17 INFO : small: Deleted 2026/07/29 04:55:17 INFO : medium: Deleted 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 run.go:172: removing file "small" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "small" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "small" failed: could not Remove: Linkbox error 403: not permit --- PASS: TestMaxDeleteSize (28.11s) === RUN TestCat run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:55:28 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:55:28 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:55:29 DEBUG : file1: 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 --- PASS: TestCat (19.37s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:55:52 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:55:52 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:55:53 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:55:53 INFO : A2: Making directory 2026/07/29 04:55:55 INFO : A1/B2: Making directory 2026/07/29 04:55:56 INFO : A1/B2/C2: Making directory 2026/07/29 04:55:58 INFO : A1/B1/C3: Making directory 2026/07/29 04:55:59 INFO : A3: Making directory 2026/07/29 04:56:01 INFO : A3/B3: Making directory 2026/07/29 04:56:02 INFO : A3/B3/C4: Making directory 2026/07/29 04:56:08 DEBUG : removing 1 level 3 directories 2026/07/29 04:56:08 INFO : A3/B3/C4: Removing directory 2026/07/29 04:56:14 DEBUG : removing 2 level 3 directories 2026/07/29 04:56:14 INFO : A1/B2/C2: Removing directory 2026/07/29 04:56:14 INFO : A1/B1/C3: Removing directory 2026/07/29 04:56:16 DEBUG : removing 2 level 2 directories 2026/07/29 04:56:16 INFO : A1/B2: Removing directory 2026/07/29 04:56:16 INFO : A3/B3: Removing directory 2026/07/29 04:56:18 DEBUG : removing 2 level 1 directories 2026/07/29 04:56:18 INFO : A3: Removing directory 2026/07/29 04:56:18 INFO : A2: Removing directory 2026/07/29 04:56:25 DEBUG : removing 1 level 3 directories 2026/07/29 04:56:25 INFO : A1/B1/C1: Removing directory 2026/07/29 04:56:26 DEBUG : removing 1 level 2 directories 2026/07/29 04:56:26 INFO : A1/B1: Removing directory 2026/07/29 04:56:26 ERROR : A1/B1: Failed to rmdir: directory not empty operations_test.go:728: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:728 Error: Received unexpected error: failed to remove directories: directory not empty Test: TestRmdirsNoLeaveRoot run.go:186: removing dir "A1" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestRmdirsNoLeaveRoot (46.46s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:56:32 INFO : A1: Making directory 2026/07/29 04:56:33 INFO : A1/B1: Making directory 2026/07/29 04:56:35 INFO : A1/B1/C1: Making directory 2026/07/29 04:56:38 DEBUG : removing 1 level 3 directories 2026/07/29 04:56:38 INFO : A1/B1/C1: Removing directory 2026/07/29 04:56:39 DEBUG : removing 1 level 2 directories 2026/07/29 04:56:39 INFO : A1/B1: 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 --- PASS: TestRmdirsLeaveRoot (19.56s) === RUN TestRmdirsWithFilter run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:56:51 INFO : A1: Making directory 2026/07/29 04:56:53 INFO : A1/B1: Making directory 2026/07/29 04:56:54 INFO : A1/B1/C1: Making directory 2026/07/29 04:56:57 DEBUG : removing 1 level 3 directories 2026/07/29 04:56:57 INFO : A1/B1/C1: Removing directory 2026/07/29 04:56:59 DEBUG : removing 1 level 2 directories 2026/07/29 04:56:59 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (11.71s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:57:05 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:57:05 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/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: TestCopyURLDownloadHeaders (12.03s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 04:57:19 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:57:19 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:57:19 DEBUG : A1/one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:57:22 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:57:22 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:57:24 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:24 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/29 04:57:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:24 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/29 04:57:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:25 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/29 04:57:26 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:26 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/29 04:57:27 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:29 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:31 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:33 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:57:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/29 04:57:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/29 04:57:40 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/29 04:57:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/29 04:57:40 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:57:41 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/29 04:57:42 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/29 04:57:43 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/29 04:57:43 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/29 04:57:44 DEBUG : pacer: Reducing sleep to 200ms 2026/07/29 04:57:49 INFO : A1/B2: Making directory 2026/07/29 04:57:51 INFO : A1/B1/C3: Making directory 2026/07/29 04:58:10 DEBUG : A2/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:58:10 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:58:10 DEBUG : A2/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:58:10 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:58:11 DEBUG : A2/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:58:11 DEBUG : A2/B1/three: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:58:11 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:58:11 DEBUG : A2/two: size = 3 OK 2026/07/29 04:58:11 INFO : A1/two: Copied (new) to: A2/two 2026/07/29 04:58:11 DEBUG : A2/B1/C1/four: size = 4 OK 2026/07/29 04:58:11 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/07/29 04:58:12 DEBUG : A2/one: size = 3 OK 2026/07/29 04:58:12 INFO : A1/one: Copied (new) to: A2/one 2026/07/29 04:58:12 DEBUG : A2/B1/three: size = 5 OK 2026/07/29 04:58:12 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/07/29 04:58:12 DEBUG : A2/B1/C2/five: size = 4 OK 2026/07/29 04:58:12 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/07/29 04:58:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/29 04:58:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/29 04:58:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:13 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/29 04:58:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:13 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/29 04:58:15 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:17 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:21 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:23 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:25 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:31 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:33 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:35 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:58:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/29 04:58:38 INFO : A1/two: Deleted 2026/07/29 04:58:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/29 04:58:40 INFO : A1/B1/C1/four: Deleted 2026/07/29 04:58:42 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/29 04:58:42 INFO : A1/one: Deleted 2026/07/29 04:58:43 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/29 04:58:43 INFO : A1/B1/three: Deleted 2026/07/29 04:58:44 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/29 04:58:44 INFO : A1/B1/C2/five: Deleted 2026/07/29 04:58:44 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/29 04:58:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/29 04:58:46 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/29 04:58:47 DEBUG : pacer: Reducing sleep to 200ms 2026/07/29 04:59:11 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:59:11 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:59:11 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:59:12 DEBUG : A3/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 04:59:12 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 04:59:12 DEBUG : A3/two: size = 3 OK 2026/07/29 04:59:12 INFO : A2/two: Copied (new) to: A3/two 2026/07/29 04:59:12 DEBUG : A3/B1/three: size = 5 OK 2026/07/29 04:59:12 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/07/29 04:59:12 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10) 2026/07/29 04:59:13 DEBUG : A3/one: size = 3 OK 2026/07/29 04:59:13 INFO : A2/one: Copied (new) to: A3/one 2026/07/29 04:59:13 DEBUG : A3/B1/C2/five: size = 4 OK 2026/07/29 04:59:13 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/07/29 04:59:14 DEBUG : A3/B1/C1/four: size = 4 OK 2026/07/29 04:59:14 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/07/29 04:59:14 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:14 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/29 04:59:14 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:14 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/29 04:59:14 DEBUG : pacer: Reducing sleep to 600ms 2026/07/29 04:59:14 INFO : A2/two: Deleted 2026/07/29 04:59:14 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:14 DEBUG : pacer: Rate limited, increasing sleep to 1.2s 2026/07/29 04:59:14 DEBUG : pacer: Reducing sleep to 900ms 2026/07/29 04:59:14 INFO : A2/B1/three: Deleted 2026/07/29 04:59:15 DEBUG : pacer: Reducing sleep to 675ms 2026/07/29 04:59:15 INFO : A2/one: Deleted 2026/07/29 04:59:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:15 DEBUG : pacer: Rate limited, increasing sleep to 1.35s 2026/07/29 04:59:16 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/29 04:59:17 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:19 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:21 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:23 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:25 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:27 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:29 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:31 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/29 04:59:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/29 04:59:38 INFO : A2/B1/C1/four: Deleted 2026/07/29 04:59:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/29 04:59:40 INFO : A2/B1/C2/five: Deleted 2026/07/29 04:59:41 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/29 04:59:43 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/29 04:59:43 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/29 04:59:45 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/29 04:59:45 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/29 04:59:46 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/29 04:59:47 DEBUG : pacer: Reducing sleep to 200ms 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/07/29 05:00:02 INFO : Linkbox root 'rclone-test-dobaxoz0kaho': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/07/29 05:00:18 DEBUG : A4/B1/three: size = 5 OK 2026/07/29 05:00:18 INFO : A3/B1/three: Copied (new) to: A4/B1/three 2026/07/29 05:00:18 DEBUG : A4/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 05:00:18 DEBUG : A4/B1/C2/five: size = 4 OK 2026/07/29 05:00:18 INFO : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five 2026/07/29 05:00:19 DEBUG : A4/B1/C1/four: size = 4 OK 2026/07/29 05:00:19 INFO : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four 2026/07/29 05:00:19 DEBUG : A4/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 05:00:19 DEBUG : A4/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 05:00:19 INFO : A3/B1/three: Deleted 2026/07/29 05:00:20 DEBUG : A4/one: size = 3 OK 2026/07/29 05:00:20 INFO : A3/one: Copied (new) to: A4/one 2026/07/29 05:00:20 DEBUG : A4/two: size = 3 OK 2026/07/29 05:00:20 INFO : A3/two: Copied (new) to: A4/two 2026/07/29 05:00:20 INFO : A3/B1/C2/five: Deleted 2026/07/29 05:00:20 INFO : A3/B1/C1/four: Deleted 2026/07/29 05:00:21 INFO : A3/one: Deleted 2026/07/29 05:00:21 INFO : A3/two: Deleted 2026/07/29 05:00:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/29 05:00:29 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:29 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/29 05:00:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:29 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/29 05:00:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:30 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/29 05:00:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:34 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:36 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/29 05:00:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/29 05:00:41 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/29 05:00:42 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/29 05:00:43 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/29 05:00:43 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/29 05:00:44 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/29 05:00:45 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/29 05:00:45 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/29 05:00:45 DEBUG : pacer: Reducing sleep to 200ms --- PASS: TestDirMove (227.71s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 05:01:03 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': File to upload is small (34 bytes), uploading instead of streaming 2026/07/29 05:01:05 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/07/29 05:01:05 NOTICE: Linkbox root 'rclone-test-dobaxoz0kaho': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/07/29 05:01:05 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/07/29 05:01:05 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/29 05:01:07 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/07/29 05:01:07 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 05:01:10 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': File to upload is small (34 bytes), uploading instead of streaming 2026/07/29 05:01:12 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/07/29 05:01:12 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/07/29 05:01:12 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/29 05:01:14 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/07/29 05:01:14 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (14.99s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (7.51s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (7.48s) === RUN TestRcatMetadata run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1563: Skipping as destination doesn't support user metadata --- SKIP: TestRcatMetadata (0.41s) === RUN TestRcatSize run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 05:01:20 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': File to upload is small (60 bytes), uploading instead of streaming 2026/07/29 05:01:22 DEBUG : potato2: size = 60 OK 2026/07/29 05:01:22 DEBUG : potato2: Size of src and dst objects identical 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: TestRcatSize (14.97s) === RUN TestRcatSizeMetadata run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1644: Skipping as destination doesn't support user metadata --- SKIP: TestRcatSizeMetadata (0.57s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" 2026/07/29 05:01:36 DEBUG : potato1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/29 05:01:36 DEBUG : potato1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/29 05:01:36 DEBUG : potato1: 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestRcatSizeUploadHeaders Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 Error: Should be true Test: TestRcatSizeUploadHeaders Messages: Unexpected file "potato1" --- FAIL: TestRcatSizeUploadHeaders (13.20s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1735: Skipping test as remote does not support modtime run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1735 Error: Should be true Test: TestTouchDir Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1735 Error: Should be true Test: TestTouchDir Messages: Unexpected file "potato1" --- FAIL: TestTouchDir (12.21s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1773: Skipping test as remote does not support MkdirMetadata run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1773 Error: Should be true Test: TestMkdirMetadata Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1773 Error: Should be true Test: TestMkdirMetadata Messages: Unexpected file "potato1" --- FAIL: TestMkdirMetadata (12.13s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1792: Skipping test as remote does not support DirSetModTime or MkdirMetadata run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1792 Error: Should be true Test: TestMkdirModTime Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1792 Error: Should be true Test: TestMkdirModTime Messages: Unexpected file "potato1" --- FAIL: TestMkdirModTime (12.07s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1813: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1813 Error: Should be true Test: TestCopyDirMetadata Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1813 Error: Should be true Test: TestCopyDirMetadata Messages: Unexpected file "potato1" --- FAIL: TestCopyDirMetadata (12.11s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1849: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1849 Error: Should be true Test: TestSetDirModTime Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1849 Error: Should be true Test: TestSetDirModTime Messages: Unexpected file "potato1" --- FAIL: TestSetDirModTime (12.16s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1893: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1893 Error: Should be true Test: TestDirsEqual Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1893 Error: Should be true Test: TestDirsEqual Messages: Unexpected file "potato1" --- FAIL: TestDirsEqual (12.12s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-dobaxoz0kaho'", Local "Local file system at /tmp/rclone3503978095", Modify Window "876000h0m0s" operations_test.go:1962: Skipping as remote can't Move run.go:172: removing file "potato1" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato1" 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1962 Error: Should be true Test: TestRemoveExisting Messages: listing wrong, want got potato1 (60) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1259 /usr/local/go/src/testing/testing.go:1235 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1962 Error: Should be true Test: TestRemoveExisting Messages: Unexpected file "potato1" --- FAIL: TestRemoveExisting (12.11s) FAIL 2026/07/29 05:03:12 DEBUG : Linkbox root 'rclone-test-dobaxoz0kaho': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCat|TestCheckSumDownload|TestCopyDirMetadata|TestCopyFileCompareDest|TestCopyURLDownloadHeaders|TestDirMove|TestDirsEqual|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 12m3.930312005s (try 3/5): exit status 1: Failed [TestCheckSumDownload TestMaxDelete TestMaxDeleteSizeLargeFile TestRmdirsNoLeaveRoot TestRcatSizeUploadHeaders TestTouchDir TestMkdirMetadata TestMkdirModTime TestCopyDirMetadata TestSetDirModTime TestDirsEqual TestRemoveExisting]