"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCat|TestCopyDirMetadata|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDirMove|TestDirsEqual|TestGetFsInfo|TestHashSums|TestLs|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 3/5) 2026/07/25 02:10:40 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-hixukol7kipo" 2026/07/25 02:10:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/25 02:10:40 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': Using cached web token 2026/07/25 02:10:40 DEBUG : Creating backend with remote "/tmp/rclone3602410869" === RUN TestLsd run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:10:46 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:10:46 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:10:46 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir" failed - try 2/3: sleeping 1.5s: directory not empty --- PASS: TestLsd (13.81s) === RUN TestLs run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:10:56 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:10:56 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:10:56 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:10:56 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:10:57 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:10:57 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:10:57 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:10:57 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:10:59 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:01 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:03 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:05 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:07 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:09 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-hixukol7kipo': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/25 02:11:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:13 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:15 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:19 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:21 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:23 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:11:23 DEBUG : potato2: Update: removing old file 2026/07/25 02:11:26 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:11:27 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:11:29 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:11:29 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:11:29 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:11:30 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:11:30 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:11:30 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:11:30 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:11:31 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:11:31 DEBUG : pacer: Reducing sleep to 200ms --- PASS: TestLs (42.36s) === RUN TestLsWithFilesFrom run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:11:38 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:11:39 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:11:39 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:11:42 DEBUG : empty space: 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 2026/07/25 02:11:47 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:47 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:11:47 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:47 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:11:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:11:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:11:50 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:52 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:54 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:56 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:11:58 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:00 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:00 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value --- FAIL: TestLsWithFilesFrom (23.70s) === RUN TestLsLong run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:12:02 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:04 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:06 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:14 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:16 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:18 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:20 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-hixukol7kipo': 1/10 (getting files failed: invalid character '<' looking for beginning of value) 2026/07/25 02:12:22 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:24 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:12:26 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:12:28 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:12:30 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:12:30 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:12:30 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:12:31 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:12:31 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:12:32 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:12:32 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:12:32 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:12:32 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:12:34 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:12:34 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) --- PASS: TestLsLong (38.37s) === RUN TestHashSums run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:12:39 DEBUG : potato2: Update: removing old file 2026/07/25 02:12:39 ERROR : potato2: Update: failed to remove existing file: could not Remove: Linkbox error 403: not permit 2026/07/25 02:12:41 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:12:43 DEBUG : empty space: 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 2026/07/25 02:12:48 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:48 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:12:48 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:48 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:12:48 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:48 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:12:49 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:49 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:12:51 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:53 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:55 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:57 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:12:59 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:01 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:01 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value --- FAIL: TestHashSums (22.23s) === RUN TestCount run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:13:03 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:05 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:19 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:21 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:299: Retry Put of "potato2" to Linkbox root 'rclone-test-hixukol7kipo': 1/10 (getting files failed: invalid character '<' looking for beginning of value) 2026/07/25 02:13:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:13:25 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:13:27 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:13:29 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:13:31 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:13:31 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:13:31 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:13:32 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:13:32 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:13:33 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:13:34 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:13:38 DEBUG : sub dir/potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:13:38 DEBUG : sub dir/potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:13:38 DEBUG : sub dir/potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:13:39 DEBUG : sub dir/potato3: Trying to read object after upload: try again in 800ms (4/10) run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestCount (47.03s) === RUN TestDelete run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:13:50 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:13:50 DEBUG : small: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:13:51 DEBUG : small: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:13:53 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:13:53 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:13:56 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:13:56 DEBUG : large: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:13:57 DEBUG : large: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:13:57 DEBUG : large: Trying to read object after upload: try again in 800ms (4/10) 2026/07/25 02:13:58 DEBUG : Waiting for deletions to finish 2026/07/25 02:13:59 DEBUG : large: Excluded (Size Filter) 2026/07/25 02:13:59 DEBUG : large: Excluded 2026/07/25 02:14:00 INFO : medium: Deleted 2026/07/25 02:14:00 INFO : small: Deleted 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 fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:14:11 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:14:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:11 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:14:11 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:11 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:14:12 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:12 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:14:14 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:16 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:18 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:20 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:22 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:24 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:14:24 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value --- FAIL: TestDelete (35.77s) === RUN TestMaxDelete run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:14:26 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:14:28 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:14:29 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:14:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:14:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:14:32 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:14:33 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:14:34 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:14:35 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:14:35 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:14:35 DEBUG : medium: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:14:37 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:14:38 DEBUG : large: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:14:38 DEBUG : large: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:14:39 DEBUG : Waiting for deletions to finish 2026/07/25 02:14:39 ERROR : medium: Got fatal error on delete: --max-delete threshold reached 2026/07/25 02:14:40 INFO : large: Deleted 2026/07/25 02:14:41 INFO : small: Deleted 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: TestMaxDelete (26.70s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:14:57 DEBUG : Waiting for deletions to finish 2026/07/25 02:14:57 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/07/25 02:14:58 INFO : medium: Deleted 2026/07/25 02:14:58 INFO : small: Deleted 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: TestMaxDeleteSizeLargeFile (13.55s) === RUN TestMaxDeleteSize run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:15:06 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:15:11 DEBUG : Waiting for deletions to finish 2026/07/25 02:15:11 ERROR : small: Got fatal error on delete: --max-delete-size threshold reached 2026/07/25 02:15:12 INFO : large: Deleted 2026/07/25 02:15:12 INFO : medium: Deleted --- PASS: TestMaxDeleteSize (10.93s) === RUN TestReadFile run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:15:17 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1028h48m51.788822663s different from this computer 2026/07/25 02:15:18 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:18 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:15:18 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:18 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:15:18 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:18 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:15:19 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:15:21 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:23 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:25 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:15:28 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:15:29 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:15:30 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:15:31 DEBUG : pacer: Reducing sleep to 632.8125ms --- PASS: TestReadFile (16.64s) === RUN TestCat run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:15:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:15:33 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:15:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:15:35 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:15:35 DEBUG : pacer: Reducing sleep to 200ms --- PASS: TestCat (11.72s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:15:50 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:15:50 INFO : A2: Making directory 2026/07/25 02:15:52 INFO : A1/B2: Making directory 2026/07/25 02:15:53 INFO : A1/B2/C2: Making directory 2026/07/25 02:15:55 INFO : A1/B1/C3: Making directory 2026/07/25 02:15:56 INFO : A3: Making directory 2026/07/25 02:15:58 INFO : A3/B3: Making directory 2026/07/25 02:15:59 INFO : A3/B3/C4: Making directory 2026/07/25 02:16:06 DEBUG : removing 1 level 3 directories 2026/07/25 02:16:06 INFO : A3/B3/C4: Removing directory 2026/07/25 02:16:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:16:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:16:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:16:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:16:11 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:15 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:19 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:21 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:23 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:25 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:16:27 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:16:29 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:16:30 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:16:32 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:16:32 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:16:33 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:16:34 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:16:34 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:16:34 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:16:36 DEBUG : removing 2 level 3 directories 2026/07/25 02:16:36 INFO : A1/B2/C2: Removing directory 2026/07/25 02:16:36 INFO : A1/B1/C3: Removing directory 2026/07/25 02:16:38 DEBUG : removing 2 level 2 directories 2026/07/25 02:16:38 INFO : A3/B3: Removing directory 2026/07/25 02:16:38 INFO : A1/B2: Removing directory 2026/07/25 02:16:40 DEBUG : removing 2 level 1 directories 2026/07/25 02:16:40 INFO : A3: Removing directory 2026/07/25 02:16:40 INFO : A2: Removing directory 2026/07/25 02:16:46 DEBUG : removing 1 level 3 directories 2026/07/25 02:16:46 INFO : A1/B1/C1: Removing directory 2026/07/25 02:16:48 DEBUG : removing 1 level 2 directories 2026/07/25 02:16:48 INFO : A1/B1: Removing directory 2026/07/25 02:16:49 DEBUG : removing 1 level 1 directories 2026/07/25 02:16:49 INFO : A1: Removing directory 2026/07/25 02:16:50 ERROR : A1: Failed to rmdir: directory not empty 2026/07/25 02:16:50 DEBUG : removing 1 level 0 directories 2026/07/25 02:16:50 INFO : Linkbox root 'rclone-test-hixukol7kipo': Removing directory 2026/07/25 02:16:50 ERROR : 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: 2 errors: last error: directory not empty Test: TestRmdirsNoLeaveRoot --- FAIL: TestRmdirsNoLeaveRoot (69.26s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:16:52 INFO : A1: Making directory 2026/07/25 02:16:54 INFO : A1/B1: Making directory 2026/07/25 02:16:55 INFO : A1/B1/C1: Making directory 2026/07/25 02:16:58 DEBUG : removing 1 level 3 directories 2026/07/25 02:16:58 INFO : A1/B1/C1: Removing directory 2026/07/25 02:17:00 DEBUG : removing 1 level 2 directories 2026/07/25 02:17:00 INFO : A1/B1: Removing directory 2026/07/25 02:17:02 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:02 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:17:02 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:02 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:17:02 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:02 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:17:03 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:03 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:17:05 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:07 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:09 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:11 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:13 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:15 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:15 ERROR : A1: error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/25 02:17:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:23 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:25 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:17:27 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:17:29 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:17:31 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:17:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:17:33 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:17:34 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:17:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:17:35 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:292: Sleeping for 6s just to make sure --- FAIL: TestRmdirsLeaveRoot (48.84s) === RUN TestRmdirsWithFilter run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:17:41 INFO : A1: Making directory 2026/07/25 02:17:41 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:17:43 INFO : A1/B1: Making directory 2026/07/25 02:17:44 INFO : A1/B1/C1: Making directory 2026/07/25 02:17:47 DEBUG : removing 1 level 3 directories 2026/07/25 02:17:47 INFO : A1/B1/C1: Removing directory 2026/07/25 02:17:49 DEBUG : removing 1 level 2 directories 2026/07/25 02:17:49 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: TestRmdirsWithFilter (20.45s) === RUN TestCopyURL run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:18:04 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:04 DEBUG : file1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:18:05 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 2026/07/25 02:18:13 DEBUG : filename.txt: File name found in url 2026/07/25 02:18:15 DEBUG : filename.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:15 DEBUG : filename.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:18:16 DEBUG : filename.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:18:17 DEBUG : filename.txt: Trying to read object after upload: try again in 800ms (4/10) 2026/07/25 02:18:18 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/07/25 02:18:20 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:20 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:18:20 DEBUG : headerfilename.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:18:23 DEBUG : file2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:23 DEBUG : file2: 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: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: TestCopyURL Messages: listing wrong, want got file2 (14) 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: TestCopyURL Messages: Unexpected file "file2" --- FAIL: TestCopyURL (34.32s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:18:38 DEBUG : file1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:39 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: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: TestCopyURLDownloadHeaders Messages: listing wrong, want got file1 (14) 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: TestCopyURLDownloadHeaders Messages: Unexpected file "file1" --- FAIL: TestCopyURLDownloadHeaders (14.14s) === RUN TestMoveFile run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:18:51 DEBUG : file1: Need to transfer - File not found at Destination 2026/07/25 02:18:55 DEBUG : sub/file2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:18:55 DEBUG : sub/file2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:18:55 DEBUG : sub/file2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:18:56 DEBUG : sub/file2: size = 14 OK 2026/07/25 02:18:56 INFO : file1: Copied (new) to: sub/file2 2026/07/25 02:18:56 INFO : file1: Deleted 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:965 Error: Should be true Test: TestMoveFile Messages: listing wrong, want sub/file2 (14) got file1 (14), sub/file2 (14) 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:965 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestMoveFile Messages: Unexpected file "file1" 2026/07/25 02:19:05 DEBUG : sub/file2: size = 14 OK 2026/07/25 02:19:05 DEBUG : file1: Sizes identical 2026/07/25 02:19:05 DEBUG : file1: Unchanged skipping 2026/07/25 02:19:05 INFO : file1: Deleted 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:973 Error: Should be true Test: TestMoveFile Messages: listing wrong, want sub/file2 (14) got file1 (14), sub/file2 (14) 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:973 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestMoveFile Messages: Unexpected file "file1" 2026/07/25 02:19:14 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': 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: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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:978 Error: Should be true Test: TestMoveFile Messages: listing wrong, want sub/file2 (14) got file1 (14), sub/file2 (14) 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:978 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestMoveFile Messages: Unexpected file "file1" --- FAIL: TestMoveFile (38.30s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:19:29 DEBUG : file1: Need to transfer - File not found at Destination 2026/07/25 02:19:31 DEBUG : file1: size = 14 OK 2026/07/25 02:19:31 INFO : file1: Copied (new) 2026/07/25 02:19:31 INFO : file1: Deleted 2026/07/25 02:19:31 DEBUG : file1: Destination exists, skipping 2026/07/25 02:19:31 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (4.63s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:19:36 NOTICE: hello: Skipped rename to HELLO as --dry-run is set 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: TestCaseInsensitiveMoveFileDryRun Messages: listing wrong, want got hello (5) 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: TestCaseInsensitiveMoveFileDryRun Messages: Unexpected file "hello" --- FAIL: TestCaseInsensitiveMoveFileDryRun (12.83s) === RUN TestMoveFileBackupDir run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1063: Skipping test as remote does not support server-side move or copy run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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:1063 Error: Should be true Test: TestMoveFileBackupDir Messages: listing wrong, want got hello (5) 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:1063 Error: Should be true Test: TestMoveFileBackupDir Messages: Unexpected file "hello" --- FAIL: TestMoveFileBackupDir (11.89s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:20:02 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:02 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:20:02 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:02 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:20:02 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:02 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:20:03 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:03 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:20:05 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:07 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:09 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:11 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:13 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:15 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:23 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:25 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:27 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:20:29 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:20:32 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:20:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:20:33 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:20:35 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:20:36 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:20:36 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:20:38 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:20:38 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:20:41 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:20:42 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:20:46 INFO : A1/B2: Making directory 2026/07/25 02:20:47 INFO : A1/B1/C3: Making directory 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/fs/operations/operations_test.go:1396 Error: Should be true Test: TestDirMove Messages: listing wrong, want A1/B1/C1/four (4), A1/B1/C2/five (4), A1/B1/three (5), A1/one (3), A1/two (3) got A1/B1/C1/four (4), A1/B1/C2/five (4), A1/B1/three (5), A1/one (3), A1/two (3), hello (5) 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/fs/operations/operations_test.go:1396 Error: Should be true Test: TestDirMove Messages: Unexpected file "hello" 2026/07/25 02:21:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:12 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:21:12 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:21:13 DEBUG : pacer: Reducing sleep to 600ms 2026/07/25 02:21:13 DEBUG : pacer: Reducing sleep to 450ms 2026/07/25 02:21:14 DEBUG : pacer: Reducing sleep to 337.5ms 2026/07/25 02:21:14 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:14 DEBUG : pacer: Rate limited, increasing sleep to 675ms 2026/07/25 02:21:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:15 DEBUG : pacer: Rate limited, increasing sleep to 1.35s 2026/07/25 02:21:15 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:15 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:21:17 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:19 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:21 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:23 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:25 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:21:29 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:21:31 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:21:32 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:21:34 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:21:34 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:21:35 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:21:35 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:21:36 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:21:36 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:21:38 DEBUG : A2/B1/three: size = 5 OK 2026/07/25 02:21:38 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/07/25 02:21:39 DEBUG : A2/one: size = 3 OK 2026/07/25 02:21:39 INFO : A1/one: Copied (new) to: A2/one 2026/07/25 02:21:39 DEBUG : A2/two: size = 3 OK 2026/07/25 02:21:39 INFO : A1/two: Copied (new) to: A2/two 2026/07/25 02:21:40 DEBUG : A2/B1/C1/four: size = 4 OK 2026/07/25 02:21:40 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/07/25 02:21:40 DEBUG : A2/B1/C2/five: size = 4 OK 2026/07/25 02:21:40 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/07/25 02:21:40 INFO : A1/B1/three: Deleted 2026/07/25 02:21:40 INFO : A1/one: Deleted 2026/07/25 02:21:40 INFO : A1/two: Deleted 2026/07/25 02:21:41 INFO : A1/B1/C1/four: Deleted 2026/07/25 02:21:41 INFO : A1/B1/C2/five: Deleted 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/fs/operations/operations_test.go:1417 Error: Should be true Test: TestDirMove Messages: listing wrong, want A2/B1/C1/four (4), A2/B1/C2/five (4), A2/B1/three (5), A2/one (3), A2/two (3) got A2/B1/C1/four (4), A2/B1/C2/five (4), A2/B1/three (5), A2/one (3), A2/two (3), hello (5) 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/fs/operations/operations_test.go:1417 Error: Should be true Test: TestDirMove Messages: Unexpected file "hello" 2026/07/25 02:22:03 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:03 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:22:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:22:04 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:04 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:22:05 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:22:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:10 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:12 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:14 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:16 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:18 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:20 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:22 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:24 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:26 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:28 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:30 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:22:32 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:22:34 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:22:35 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:22:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:22:37 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:22:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:22:39 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:22:41 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:22:51 DEBUG : A3/one: size = 3 OK 2026/07/25 02:22:51 INFO : A2/one: Copied (new) to: A3/one 2026/07/25 02:22:51 DEBUG : A3/two: size = 3 OK 2026/07/25 02:22:51 INFO : A2/two: Copied (new) to: A3/two 2026/07/25 02:22:51 DEBUG : A3/B1/three: size = 5 OK 2026/07/25 02:22:51 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/07/25 02:22:52 DEBUG : A3/B1/C2/five: size = 4 OK 2026/07/25 02:22:52 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/07/25 02:22:52 DEBUG : A3/B1/C1/four: size = 4 OK 2026/07/25 02:22:52 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/07/25 02:22:52 INFO : A2/one: Deleted 2026/07/25 02:22:53 INFO : A2/two: Deleted 2026/07/25 02:22:53 INFO : A2/B1/three: Deleted 2026/07/25 02:22:53 INFO : A2/B1/C2/five: Deleted 2026/07/25 02:22:54 INFO : A2/B1/C1/four: Deleted 2026/07/25 02:22:54 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:54 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:22:54 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:54 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:22:54 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:54 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:22:55 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:55 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:22:57 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:22:59 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:01 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:03 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:05 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:07 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/07/25 02:23:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:11 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:13 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:15 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:17 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:19 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:21 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:23 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:25 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:27 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:27 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value run.go:182: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:182 /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:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1436 Error: Received unexpected error: getting files failed: invalid character '<' looking for beginning of value Test: TestDirMove --- FAIL: TestDirMove (208.96s) === RUN TestGetFsInfo run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:23:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:31 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:23:33 DEBUG : pacer: Reducing sleep to 1.125s run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:23:34 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:23:36 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:23:37 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:23:38 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:23:39 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:23:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:23:41 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:23:59 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:59 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:23:59 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:59 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:23:59 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:23:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:24:00 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:24:02 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:04 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:06 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:08 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:10 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:12 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:186: removing dir "A2/B1/C2" failed - try 1/3: sleeping 1s: getting files failed: invalid character '<' looking for beginning of value 2026/07/25 02:24:14 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:16 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:18 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:20 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:22 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:24 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:26 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:28 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:30 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:24:32 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:24:35 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:24:35 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:24:38 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:24:38 DEBUG : pacer: Reducing sleep to 474.609375ms run.go:186: removing dir "A2/B1" failed - try 1/3: sleeping 1s: directory not empty 2026/07/25 02:24:39 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:24:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:24:41 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:24:42 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: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: TestGetFsInfo Messages: listing wrong, want got hello (5) 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: TestGetFsInfo Messages: Unexpected file "hello" --- FAIL: TestGetFsInfo (83.88s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=false Messages: listing wrong, want got hello (5) 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=false Messages: Unexpected file "hello" 2026/07/25 02:24:59 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': File to upload is small (34 bytes), uploading instead of streaming 2026/07/25 02:25:01 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/07/25 02:25:01 NOTICE: Linkbox root 'rclone-test-hixukol7kipo': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/07/25 02:25:01 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/07/25 02:25:01 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/25 02:25:04 DEBUG : no_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:25:04 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/07/25 02:25:04 DEBUG : no_checksum_big_file_from_pipe: 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:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:25:08 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:08 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:25:08 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:08 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:25:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:25:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:25:11 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:13 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:15 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:17 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:19 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:21 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:21 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/25 02:25:23 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:25 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:27 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:29 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:31 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:25:33 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:25:35 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:25:36 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:25:38 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:25:39 DEBUG : pacer: Reducing sleep to 474.609375ms run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:25:41 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:25:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:25:43 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:25:44 DEBUG : pacer: Reducing sleep to 200ms 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:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1547 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=false Messages: listing wrong, want got hello (5) 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:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1547 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=false Messages: Unexpected file "hello" === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=false Messages: listing wrong, want got hello (5) 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=false Messages: Unexpected file "hello" 2026/07/25 02:26:00 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': File to upload is small (34 bytes), uploading instead of streaming 2026/07/25 02:26:02 DEBUG : with_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:26:02 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/07/25 02:26:02 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/07/25 02:26:02 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/25 02:26:04 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:26:04 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:26:05 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:26:05 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:05 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:26:06 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:06 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:26:06 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:06 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:26:07 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:07 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:26:08 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:10 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:12 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:14 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:16 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:18 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:18 DEBUG : with_checksum_big_file_from_pipe: Received error: Update failed to read object: getting files failed: invalid character '<' looking for beginning of value - low level retry 1/10 2026/07/25 02:26:20 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:22 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:24 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:26 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:28 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:30 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:32 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:26:35 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:26:35 DEBUG : with_checksum_big_file_from_pipe: Update: removing old file 2026/07/25 02:26:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:26:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:26:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:26:41 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:26:41 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:26:41 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:26:41 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:26:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:26:42 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:26:42 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:26:42 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 800ms (4/10) 2026/07/25 02:26:43 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:26:43 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/25 02:26:45 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/25 02:26:48 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/25 02:26:55 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/25 02:27:08 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/25 02:27:34 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:27:34 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:27:34 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:27:34 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:27:34 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:27:34 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:27:35 DEBUG : pacer: Reducing sleep to 1.2s 2026/07/25 02:27:35 DEBUG : with_checksum_big_file_from_pipe: Trying to read object after upload: try again in 51.2s (10/10) operations_test.go:1543: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1543 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Received unexpected error: object not found Test: TestRcat/withChecksum=true,ignoreChecksum=false 2026/07/25 02:28:26 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:28:26 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:28:28 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:28:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:28:32 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:28:34 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:28:36 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:28:38 DEBUG : pacer: Reducing sleep to 1.125s run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:28:39 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:28:41 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:28:43 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:28:44 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:28:45 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:28:46 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:28:46 DEBUG : pacer: Reducing sleep to 200ms 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:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1543 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=false Messages: listing wrong, want got hello (5) 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:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1543 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=false Messages: Unexpected file "hello" === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=true Messages: listing wrong, want got hello (5) 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/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=true Messages: Unexpected file "hello" 2026/07/25 02:29:01 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': File to upload is small (34 bytes), uploading instead of streaming 2026/07/25 02:29:04 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:29:04 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:29:05 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:29:05 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/07/25 02:29:05 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical 2026/07/25 02:29:05 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/25 02:29:07 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:29:07 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:07 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:29:08 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:08 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:29:09 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:29:10 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:12 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:14 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:16 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:18 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:20 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:20 DEBUG : ignore_checksum_big_file_from_pipe: Received error: Update failed to read object: getting files failed: invalid character '<' looking for beginning of value - low level retry 1/10 2026/07/25 02:29:22 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:24 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:26 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:28 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:30 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:32 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:34 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:29:36 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:29:36 DEBUG : ignore_checksum_big_file_from_pipe: Update: removing old file 2026/07/25 02:29:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:29:40 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:29:42 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:29:42 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:29:42 DEBUG : ignore_checksum_big_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:29:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:29:43 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/07/25 02:29:43 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical 2026/07/25 02:29:44 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:29:45 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:29:45 DEBUG : pacer: Reducing sleep to 200ms 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1547 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=true Messages: listing wrong, want ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) got hello (5), ignore_checksum_big_file_from_pipe (102401), ignore_checksum_small_file_from_pipe (34) 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1547 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=true Messages: Unexpected file "hello" run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 Error: Should be true Test: TestRcat/withChecksum=false,ignoreChecksum=true Messages: listing wrong, want got hello (5) 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: TestRcat/withChecksum=false,ignoreChecksum=true Messages: Unexpected file "hello" === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:30:06 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:06 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:30:07 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:07 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:30:07 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:07 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:30:08 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:08 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:30:09 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:11 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:13 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:15 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:17 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:19 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:19 ERROR : error listing: getting files failed: invalid character '<' looking for beginning of value fstest.go:282: Error listing: getting files failed: invalid character '<' looking for beginning of value 2026/07/25 02:30:21 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:23 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:25 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:27 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:29 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:31 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:33 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:35 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:30:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:30:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:30:41 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:30:42 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:30:44 DEBUG : pacer: Reducing sleep to 474.609375ms run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:30:45 DEBUG : pacer: Reducing sleep to 355.957031ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:30:46 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:30:46 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:30:48 DEBUG : pacer: Reducing sleep to 200ms 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:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=true Messages: listing wrong, want got hello (5) 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:1022 /usr/local/go/src/testing/testing.go:1228 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:282 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:344 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1529 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1554 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestRcat/withChecksum=true,ignoreChecksum=true Messages: Unexpected file "hello" --- FAIL: TestRcat (362.11s) --- FAIL: TestRcat/withChecksum=false,ignoreChecksum=false (59.97s) --- FAIL: TestRcat/withChecksum=true,ignoreChecksum=false (182.12s) --- FAIL: TestRcat/withChecksum=false,ignoreChecksum=true (73.71s) --- FAIL: TestRcat/withChecksum=true,ignoreChecksum=true (46.31s) === RUN TestRcatMetadata run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1563: Skipping as destination doesn't support user metadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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:1563 Error: Should be true Test: TestRcatMetadata Messages: listing wrong, want got hello (5) 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:1563 Error: Should be true Test: TestRcatMetadata Messages: Unexpected file "hello" --- FAIL: TestRcatMetadata (12.04s) === RUN TestRcatSize run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:31:07 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': File to upload is small (60 bytes), uploading instead of streaming 2026/07/25 02:31:09 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:31:10 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:31:10 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:10 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:31:10 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:10 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:31:10 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:10 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:31:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:11 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:31:13 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:15 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:17 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:19 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:21 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:23 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:23 DEBUG : potato2: Received error: Update failed to read object: getting files failed: invalid character '<' looking for beginning of value - low level retry 1/10 2026/07/25 02:31:25 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:27 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:29 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:31:37 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:31:37 DEBUG : potato2: Update: removing old file 2026/07/25 02:31:40 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/25 02:31:41 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:31:43 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/25 02:31:43 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:31:43 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:31:44 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:31:44 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:31:44 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:31:44 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:31:45 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:31:45 DEBUG : potato2: Trying to read object after upload: try again in 800ms (4/10) 2026/07/25 02:31:46 DEBUG : pacer: Reducing sleep to 200ms 2026/07/25 02:31:46 DEBUG : potato2: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/25 02:31:47 DEBUG : potato2: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/25 02:31:51 DEBUG : potato2: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/25 02:31:57 DEBUG : potato2: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/25 02:32:10 DEBUG : potato2: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/25 02:32:36 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:32:36 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:32:36 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:32:36 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:32:37 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:32:37 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:32:38 DEBUG : pacer: Reducing sleep to 1.2s 2026/07/25 02:32:38 DEBUG : potato2: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/25 02:33:29 ERROR : potato2: Post request rcat error: object not found operations_test.go:1632: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1632 Error: Received unexpected error: object not found Test: TestRcatSize 2026/07/25 02:33:29 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:33:29 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:33:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:33:32 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:33:34 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:33:36 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:33:38 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:33:40 DEBUG : pacer: Reducing sleep to 1.125s run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:33:42 DEBUG : pacer: Reducing sleep to 843.75ms run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:33:43 DEBUG : pacer: Reducing sleep to 632.8125ms run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:33:45 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:33:47 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/25 02:33:47 DEBUG : pacer: Reducing sleep to 266.967773ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:33:48 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:33:49 DEBUG : pacer: Reducing sleep to 200ms 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:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1632 Error: Should be true Test: TestRcatSize Messages: listing wrong, want got hello (5) 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:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1632 Error: Should be true Test: TestRcatSize Messages: Unexpected file "hello" --- FAIL: TestRcatSize (170.39s) === RUN TestRcatSizeMetadata run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1644: Skipping as destination doesn't support user metadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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:1644 Error: Should be true Test: TestRcatSizeMetadata Messages: listing wrong, want got hello (5) 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:1644 Error: Should be true Test: TestRcatSizeMetadata Messages: Unexpected file "hello" --- FAIL: TestRcatSizeMetadata (12.23s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" 2026/07/25 02:34:10 DEBUG : potato1: Trying to read object after upload: try again in 100ms (1/10) 2026/07/25 02:34:10 DEBUG : potato1: Trying to read object after upload: try again in 200ms (2/10) 2026/07/25 02:34:10 DEBUG : potato1: Trying to read object after upload: try again in 400ms (3/10) 2026/07/25 02:34:11 DEBUG : potato1: Trying to read object after upload: try again in 800ms (4/10) run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:34:15 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:15 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:34:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:15 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:34:16 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:16 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:34:17 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:17 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:34:18 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:20 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:22 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:24 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:26 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:28 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) run.go:172: removing file "hello" failed: could not Remove: invalid character '<' looking for beginning of value 2026/07/25 02:34:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:32 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:34 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:36 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:34:39 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/25 02:34:40 DEBUG : pacer: Reducing sleep to 1.125s fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:34:42 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:34:43 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/07/25 02:34:45 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:34:46 DEBUG : pacer: Reducing sleep to 355.957031ms 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 hello (5) 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 "hello" --- FAIL: TestRcatSizeUploadHeaders (42.51s) === RUN TestTouchDir run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1735: Skipping test as remote does not support modtime 2026/07/25 02:34:50 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:34:50 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:34:51 DEBUG : pacer: Reducing sleep to 200ms run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestTouchDir (11.98s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1773: Skipping test as remote does not support MkdirMetadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestMkdirMetadata (12.20s) === RUN TestMkdirModTime run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1792: Skipping test as remote does not support DirSetModTime or MkdirMetadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestMkdirModTime (12.22s) === RUN TestCopyDirMetadata run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1813: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit 2026/07/25 02:35:30 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:30 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/25 02:35:30 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:30 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/25 02:35:30 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:30 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/25 02:35:31 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/25 02:35:33 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:35 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:37 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/25 02:35:39 DEBUG : pacer: Reducing sleep to 1.5s run.go:172: removing file "hello" failed: could not Remove: Linkbox error 403: not permit 2026/07/25 02:35:41 DEBUG : pacer: Reducing sleep to 1.125s fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/25 02:35:43 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/25 02:35:44 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/07/25 02:35:46 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/25 02:35:46 DEBUG : pacer: Reducing sleep to 355.957031ms 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 hello (5) 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 "hello" --- FAIL: TestCopyDirMetadata (24.13s) === RUN TestSetDirModTime run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1849: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime 2026/07/25 02:35:51 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/25 02:35:51 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/07/25 02:35:51 DEBUG : pacer: Reducing sleep to 200ms run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestSetDirModTime (11.93s) === RUN TestDirsEqual run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1893: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestDirsEqual (12.11s) === RUN TestRemoveExisting run.go:198: Remote "Linkbox root 'rclone-test-hixukol7kipo'", Local "Local file system at /tmp/rclone3602410869", Modify Window "876000h0m0s" operations_test.go:1962: Skipping as remote can't Move run.go:172: removing file "hello" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "hello" 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 hello (5) 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 "hello" --- FAIL: TestRemoveExisting (12.00s) FAIL 2026/07/25 02:36:26 DEBUG : Linkbox root 'rclone-test-hixukol7kipo': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCat|TestCopyDirMetadata|TestCopyURL|TestCopyURLDownloadHeaders|TestCount|TestDelete|TestDirMove|TestDirsEqual|TestGetFsInfo|TestHashSums|TestLs|TestLsLong|TestLsWithFilesFrom|TestLsd|TestMaxDelete|TestMaxDeleteSize|TestMaxDeleteSizeLargeFile|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRcatSizeUploadHeaders|TestReadFile|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 25m48.509123754s (try 3/5): exit status 1: Failed [TestLsWithFilesFrom TestHashSums TestDelete TestRmdirsNoLeaveRoot TestRmdirsLeaveRoot TestCopyURL TestCopyURLDownloadHeaders TestMoveFile TestCaseInsensitiveMoveFileDryRun TestMoveFileBackupDir TestDirMove TestGetFsInfo TestRcat/withChecksum=false,ignoreChecksum=false TestRcat/withChecksum=true,ignoreChecksum=false TestRcat/withChecksum=false,ignoreChecksum=true TestRcat/withChecksum=true,ignoreChecksum=true TestRcatMetadata TestRcatSize TestRcatSizeMetadata TestRcatSizeUploadHeaders TestTouchDir TestMkdirMetadata TestMkdirModTime TestCopyDirMetadata TestSetDirModTime TestDirsEqual TestRemoveExisting]