"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestCopyFileMaxTransfer|TestDirMove|TestMaxDelete|TestMaxDeleteSize|TestMkdirMetadata|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=true$'" - Starting (try 4/5) 2026/07/28 02:44:41 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wenilej6yili" 2026/07/28 02:44:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:44:41 DEBUG : Linkbox root 'rclone-test-wenilej6yili': Using cached web token 2026/07/28 02:44:42 DEBUG : Creating backend with remote "/tmp/rclone2977004239" === RUN TestCheckSumDownload run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:44:42 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wenilej6yili/data" 2026/07/28 02:44:42 DEBUG : Linkbox root 'rclone-test-wenilej6yili/data': Using cached web token 2026/07/28 02:44:49 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:44:50 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:44:50 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) === RUN TestCheckSumDownload/subtest1 2026/07/28 02:44:54 DEBUG : data/potato: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:44:55 DEBUG : data/potato: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:44:55 DEBUG : test.sum: Update: removing old file 2026/07/28 02:44:58 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:44:58 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/28 02:44:59 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:44:59 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/28 02:44:59 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:44:59 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/28 02:45:00 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:00 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/28 02:45:01 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:03 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:05 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:08 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:09 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:11 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) === NAME TestCheckSumDownload run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-wenilej6yili': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/28 02:45:13 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:15 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:17 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:19 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:21 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:23 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:25 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:27 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:29 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:31 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:45:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/28 02:45:37 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/28 02:45:37 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/28 02:45:38 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/28 02:45:39 DEBUG : pacer: Reducing sleep to 474.609375ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 2026/07/28 02:45:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/28 02:45:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/28 02:45:41 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/28 02:45:43 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/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/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (41) got data/banana (13), data/potato (15), test(1).sum (41), test.sum (41) 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/check_test.go:450 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" === RUN TestCheckSumDownload/subtest2 2026/07/28 02:45:49 DEBUG : test.sum: Update: removing old file 2026/07/28 02:45:52 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:45:52 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:45:53 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:45:53 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:45:54 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:45:56 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:45:59 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:46:06 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:46:19 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/28 02:46:19 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:19 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/28 02:46:20 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:20 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/28 02:46:20 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/28 02:46:22 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:24 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:26 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:28 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:30 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:46:32 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) === NAME TestCheckSumDownload run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-wenilej6yili': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/28 02:46:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/28 02:46:36 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/28 02:46:39 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/28 02:46:39 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/28 02:46:39 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:46:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/28 02:46:40 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:46:40 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/28 02:46:41 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/28 02:46:41 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/28 02:46:43 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/check_test.go:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test(1).sum (41), test(2).sum (82), test.sum (82) 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/check_test.go:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" 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/check_test.go:464 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(2).sum" === RUN TestCheckSumDownload/subtest3 2026/07/28 02:46:51 DEBUG : test.sum: Update: removing old file 2026/07/28 02:46:54 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:46:55 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) === NAME TestCheckSumDownload 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/check_test.go:478 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (82) got data/banana (13), data/potato (15), test(1).sum (41), test(2).sum (82), test.sum (82) 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/check_test.go:478 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" 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/check_test.go:478 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(2).sum" === RUN TestCheckSumDownload/subtest4 2026/07/28 02:47:05 DEBUG : test.sum: Update: removing old file 2026/07/28 02:47:08 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:47:08 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:47:09 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:47:09 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:09 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/07/28 02:47:09 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:09 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/07/28 02:47:10 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:10 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/07/28 02:47:11 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:11 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/07/28 02:47:12 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:14 DEBUG : pacer: low level retry 6/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:16 DEBUG : pacer: low level retry 7/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:18 DEBUG : pacer: low level retry 8/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:20 DEBUG : pacer: low level retry 9/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:22 DEBUG : pacer: low level retry 10/10 (error invalid character '<' looking for beginning of value) === NAME TestCheckSumDownload run.go:299: Retry Put of "test.sum" to Linkbox root 'rclone-test-wenilej6yili': 1/10 (Update failed to read object: getting files failed: invalid character '<' looking for beginning of value) 2026/07/28 02:47:24 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:26 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:28 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:30 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:32 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/07/28 02:47:34 DEBUG : pacer: Reducing sleep to 1.5s 2026/07/28 02:47:34 DEBUG : test.sum: Update: removing old file 2026/07/28 02:47:38 DEBUG : pacer: Reducing sleep to 1.125s 2026/07/28 02:47:38 DEBUG : pacer: Reducing sleep to 843.75ms 2026/07/28 02:47:40 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/07/28 02:47:40 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/07/28 02:47:40 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:47:41 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/07/28 02:47:42 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/07/28 02:47:42 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/28 02:47:43 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/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestCheckSumDownload Messages: listing wrong, want data/banana (13), data/potato (15), test.sum (123) got data/banana (13), data/potato (15), test(1).sum (41), test(2).sum (82), test.sum (123) 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/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(1).sum" 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/check_test.go:493 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:541 Error: Should be true Test: TestCheckSumDownload Messages: Unexpected file "test(2).sum" === RUN TestCheckSumDownload/subtest5 2026/07/28 02:47:51 DEBUG : test.sum: Update: removing old file 2026/07/28 02:47:55 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:47:55 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:47:55 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:47:56 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:47:57 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:47:59 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:48:02 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:48:09 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:48:22 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/28 02:48:48 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10) === NAME TestCheckSumDownload run.go:303: Failed to put "test.sum" to "Linkbox root 'rclone-test-wenilej6yili'": object not found --- FAIL: TestCheckSumDownload (306.80s) --- PASS: TestCheckSumDownload/subtest1 (1.13s) --- PASS: TestCheckSumDownload/subtest2 (0.93s) --- PASS: TestCheckSumDownload/subtest3 (1.12s) --- PASS: TestCheckSumDownload/subtest4 (0.90s) --- PASS: TestCheckSumDownload/subtest5 (0.95s) === RUN TestApplyTransforms 2026/07/28 02:49:48 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-wolowom6tetu" 2026/07/28 02:49:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:49:48 DEBUG : Linkbox root 'rclone-test-wolowom6tetu': Using cached web token 2026/07/28 02:49:49 DEBUG : Creating backend with remote "/tmp/rclone1502526787" run.go:198: Remote "Linkbox root 'rclone-test-wolowom6tetu'", Local "Local file system at /tmp/rclone1502526787", Modify Window "876000h0m0s" 2026/07/28 02:49:53 DEBUG : hello, world!: Trying to read object after upload: try again in 100ms (1/10) upper checkfile vs. lower remote (without normalization) 2026/07/28 02:49:54 ERROR : hello, world!: sum not found 2026/07/28 02:49:54 ERROR : HELLO, WORLD!: file not in Linkbox root 'rclone-test-wolowom6tetu' 2026/07/28 02:49:54 NOTICE: Linkbox root 'rclone-test-wolowom6tetu': 1 files missing 2026/07/28 02:49:54 NOTICE: 1 hashes missing 2026/07/28 02:49:54 NOTICE: Linkbox root 'rclone-test-wolowom6tetu': 1 differences found 2026/07/28 02:49:54 NOTICE: Linkbox root 'rclone-test-wolowom6tetu': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/07/28 02:49:55 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:49:55 NOTICE: Linkbox root 'rclone-test-wolowom6tetu': 0 differences found 2026/07/28 02:49:55 NOTICE: Linkbox root 'rclone-test-wolowom6tetu': 1 matching files 2026/07/28 02:49:55 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vowabuy4zepi" 2026/07/28 02:49:55 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:49:55 DEBUG : Linkbox root 'rclone-test-vowabuy4zepi': Using cached web token 2026/07/28 02:49:56 DEBUG : Creating backend with remote "/tmp/rclone2732132448" run.go:198: Remote "Linkbox root 'rclone-test-vowabuy4zepi'", Local "Local file system at /tmp/rclone2732132448", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/07/28 02:50:00 ERROR : HELLO, WORLD!: sum not found 2026/07/28 02:50:00 ERROR : hello, world!: file not in Linkbox root 'rclone-test-vowabuy4zepi' 2026/07/28 02:50:00 NOTICE: Linkbox root 'rclone-test-vowabuy4zepi': 1 files missing 2026/07/28 02:50:00 NOTICE: 1 hashes missing 2026/07/28 02:50:00 NOTICE: Linkbox root 'rclone-test-vowabuy4zepi': 1 differences found 2026/07/28 02:50:00 NOTICE: Linkbox root 'rclone-test-vowabuy4zepi': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/07/28 02:50:00 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:00 NOTICE: Linkbox root 'rclone-test-vowabuy4zepi': 0 differences found 2026/07/28 02:50:00 NOTICE: Linkbox root 'rclone-test-vowabuy4zepi': 1 matching files 2026/07/28 02:50:00 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-sihatoy5mosi" 2026/07/28 02:50:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:00 DEBUG : Linkbox root 'rclone-test-sihatoy5mosi': Using cached web token 2026/07/28 02:50:02 DEBUG : Creating backend with remote "/tmp/rclone243409850" run.go:198: Remote "Linkbox root 'rclone-test-sihatoy5mosi'", Local "Local file system at /tmp/rclone243409850", Modify Window "876000h0m0s" 2026/07/28 02:50:06 DEBUG : HeLlO, wOrLd!: Trying to read object after upload: try again in 100ms (1/10) lower checkfile vs. upperlowermixed remote (without normalization) 2026/07/28 02:50:06 ERROR : hello, world!: file not in Linkbox root 'rclone-test-sihatoy5mosi' 2026/07/28 02:50:06 NOTICE: Linkbox root 'rclone-test-sihatoy5mosi': 1 files missing 2026/07/28 02:50:06 NOTICE: Linkbox root 'rclone-test-sihatoy5mosi': 0 differences found 2026/07/28 02:50:06 NOTICE: Linkbox root 'rclone-test-sihatoy5mosi': 1 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/07/28 02:50:07 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:07 NOTICE: Linkbox root 'rclone-test-sihatoy5mosi': 0 differences found 2026/07/28 02:50:07 NOTICE: Linkbox root 'rclone-test-sihatoy5mosi': 1 matching files 2026/07/28 02:50:07 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-qabofuk4vulo" 2026/07/28 02:50:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:07 DEBUG : Linkbox root 'rclone-test-qabofuk4vulo': Using cached web token 2026/07/28 02:50:08 DEBUG : Creating backend with remote "/tmp/rclone412876549" run.go:198: Remote "Linkbox root 'rclone-test-qabofuk4vulo'", Local "Local file system at /tmp/rclone412876549", Modify Window "876000h0m0s" 2026/07/28 02:50:12 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:50:13 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:50:13 DEBUG : HELLO, WORLD!: Trying to read object after upload: try again in 400ms (3/10) upperlowermixed checkfile vs. upper remote (without normalization) 2026/07/28 02:50:14 ERROR : HELLO, WORLD!: sum not found 2026/07/28 02:50:14 ERROR : HeLlO, wOrLd!: file not in Linkbox root 'rclone-test-qabofuk4vulo' 2026/07/28 02:50:14 NOTICE: Linkbox root 'rclone-test-qabofuk4vulo': 1 files missing 2026/07/28 02:50:14 NOTICE: 1 hashes missing 2026/07/28 02:50:14 NOTICE: Linkbox root 'rclone-test-qabofuk4vulo': 1 differences found 2026/07/28 02:50:14 NOTICE: Linkbox root 'rclone-test-qabofuk4vulo': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/07/28 02:50:15 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:15 NOTICE: Linkbox root 'rclone-test-qabofuk4vulo': 0 differences found 2026/07/28 02:50:15 NOTICE: Linkbox root 'rclone-test-qabofuk4vulo': 1 matching files 2026/07/28 02:50:15 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-yefaqob5wizu" 2026/07/28 02:50:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:15 DEBUG : Linkbox root 'rclone-test-yefaqob5wizu': Using cached web token 2026/07/28 02:50:16 DEBUG : Creating backend with remote "/tmp/rclone2629818682" run.go:198: Remote "Linkbox root 'rclone-test-yefaqob5wizu'", Local "Local file system at /tmp/rclone2629818682", Modify Window "876000h0m0s" 2026/07/28 02:50:20 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:50:20 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) NFD checkfile vs. NFC remote (without normalization) 2026/07/28 02:50:21 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-yefaqob5wizu' 2026/07/28 02:50:21 NOTICE: Linkbox root 'rclone-test-yefaqob5wizu': 1 files missing 2026/07/28 02:50:21 NOTICE: Linkbox root 'rclone-test-yefaqob5wizu': 0 differences found 2026/07/28 02:50:21 NOTICE: Linkbox root 'rclone-test-yefaqob5wizu': 1 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/07/28 02:50:21 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:21 NOTICE: Linkbox root 'rclone-test-yefaqob5wizu': 0 differences found 2026/07/28 02:50:21 NOTICE: Linkbox root 'rclone-test-yefaqob5wizu': 1 matching files 2026/07/28 02:50:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-saremey4vuyu" 2026/07/28 02:50:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:21 DEBUG : Linkbox root 'rclone-test-saremey4vuyu': Using cached web token 2026/07/28 02:50:22 DEBUG : Creating backend with remote "/tmp/rclone2697922525" run.go:198: Remote "Linkbox root 'rclone-test-saremey4vuyu'", Local "Local file system at /tmp/rclone2697922525", Modify Window "876000h0m0s" 2026/07/28 02:50:26 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:50:27 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:50:27 DEBUG : 測試_Русский___ě_áñ: Trying to read object after upload: try again in 400ms (3/10) NFC checkfile vs. NFD remote (without normalization) 2026/07/28 02:50:28 ERROR : 測試_Русский___ě_áñ: sum not found 2026/07/28 02:50:28 ERROR : 測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-saremey4vuyu' 2026/07/28 02:50:28 NOTICE: Linkbox root 'rclone-test-saremey4vuyu': 1 files missing 2026/07/28 02:50:28 NOTICE: 1 hashes missing 2026/07/28 02:50:28 NOTICE: Linkbox root 'rclone-test-saremey4vuyu': 1 differences found 2026/07/28 02:50:28 NOTICE: Linkbox root 'rclone-test-saremey4vuyu': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/07/28 02:50:28 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:28 NOTICE: Linkbox root 'rclone-test-saremey4vuyu': 0 differences found 2026/07/28 02:50:28 NOTICE: Linkbox root 'rclone-test-saremey4vuyu': 1 matching files 2026/07/28 02:50:28 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dafawal3quno" 2026/07/28 02:50:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:28 DEBUG : Linkbox root 'rclone-test-dafawal3quno': Using cached web token 2026/07/28 02:50:30 DEBUG : Creating backend with remote "/tmp/rclone1811764109" run.go:198: Remote "Linkbox root 'rclone-test-dafawal3quno'", Local "Local file system at /tmp/rclone1811764109", Modify Window "876000h0m0s" 2026/07/28 02:50:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:50:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:50:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 400ms (3/10) NFDx2 checkfile vs. both remote (without normalization) 2026/07/28 02:50:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/07/28 02:50:35 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-dafawal3quno' 2026/07/28 02:50:35 NOTICE: Linkbox root 'rclone-test-dafawal3quno': 1 files missing 2026/07/28 02:50:35 NOTICE: 1 hashes missing 2026/07/28 02:50:35 NOTICE: Linkbox root 'rclone-test-dafawal3quno': 1 differences found 2026/07/28 02:50:35 NOTICE: Linkbox root 'rclone-test-dafawal3quno': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/07/28 02:50:36 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:36 NOTICE: Linkbox root 'rclone-test-dafawal3quno': 0 differences found 2026/07/28 02:50:36 NOTICE: Linkbox root 'rclone-test-dafawal3quno': 1 matching files 2026/07/28 02:50:36 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-doqamuf0nuri" 2026/07/28 02:50:36 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:36 DEBUG : Linkbox root 'rclone-test-doqamuf0nuri': Using cached web token 2026/07/28 02:50:36 DEBUG : Creating backend with remote "/tmp/rclone837299458" run.go:198: Remote "Linkbox root 'rclone-test-doqamuf0nuri'", Local "Local file system at /tmp/rclone837299458", Modify Window "876000h0m0s" 2026/07/28 02:50:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) NFCx2 checkfile vs. both remote (without normalization) 2026/07/28 02:50:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/07/28 02:50:41 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-doqamuf0nuri' 2026/07/28 02:50:41 NOTICE: Linkbox root 'rclone-test-doqamuf0nuri': 1 files missing 2026/07/28 02:50:41 NOTICE: 1 hashes missing 2026/07/28 02:50:41 NOTICE: Linkbox root 'rclone-test-doqamuf0nuri': 1 differences found 2026/07/28 02:50:41 NOTICE: Linkbox root 'rclone-test-doqamuf0nuri': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/07/28 02:50:41 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:41 NOTICE: Linkbox root 'rclone-test-doqamuf0nuri': 0 differences found 2026/07/28 02:50:41 NOTICE: Linkbox root 'rclone-test-doqamuf0nuri': 1 matching files 2026/07/28 02:50:41 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-rizapeq8tudi" 2026/07/28 02:50:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:41 DEBUG : Linkbox root 'rclone-test-rizapeq8tudi': Using cached web token 2026/07/28 02:50:42 DEBUG : Creating backend with remote "/tmp/rclone3277759912" run.go:198: Remote "Linkbox root 'rclone-test-rizapeq8tudi'", Local "Local file system at /tmp/rclone3277759912", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/07/28 02:50:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/07/28 02:50:46 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-rizapeq8tudi' 2026/07/28 02:50:46 NOTICE: Linkbox root 'rclone-test-rizapeq8tudi': 1 files missing 2026/07/28 02:50:46 NOTICE: 1 hashes missing 2026/07/28 02:50:46 NOTICE: Linkbox root 'rclone-test-rizapeq8tudi': 1 differences found 2026/07/28 02:50:46 NOTICE: Linkbox root 'rclone-test-rizapeq8tudi': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/07/28 02:50:47 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:47 NOTICE: Linkbox root 'rclone-test-rizapeq8tudi': 0 differences found 2026/07/28 02:50:47 NOTICE: Linkbox root 'rclone-test-rizapeq8tudi': 1 matching files 2026/07/28 02:50:47 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-fuboguy1roju" 2026/07/28 02:50:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/28 02:50:47 DEBUG : Linkbox root 'rclone-test-fuboguy1roju': Using cached web token 2026/07/28 02:50:47 DEBUG : Creating backend with remote "/tmp/rclone1246924439" run.go:198: Remote "Linkbox root 'rclone-test-fuboguy1roju'", Local "Local file system at /tmp/rclone1246924439", Modify Window "876000h0m0s" 2026/07/28 02:50:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: Trying to read object after upload: try again in 100ms (1/10) both checkfile vs. NFCx2 remote (without normalization) 2026/07/28 02:50:52 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/07/28 02:50:52 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Linkbox root 'rclone-test-fuboguy1roju' 2026/07/28 02:50:52 NOTICE: Linkbox root 'rclone-test-fuboguy1roju': 1 files missing 2026/07/28 02:50:52 NOTICE: 1 hashes missing 2026/07/28 02:50:52 NOTICE: Linkbox root 'rclone-test-fuboguy1roju': 1 differences found 2026/07/28 02:50:52 NOTICE: Linkbox root 'rclone-test-fuboguy1roju': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/07/28 02:50:52 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/07/28 02:50:52 NOTICE: Linkbox root 'rclone-test-fuboguy1roju': 0 differences found 2026/07/28 02:50:52 NOTICE: Linkbox root 'rclone-test-fuboguy1roju': 1 matching files 2026/07/28 02:50:52 DEBUG : Linkbox root 'rclone-test-fuboguy1roju': Purge remote 2026/07/28 02:50:54 DEBUG : Linkbox root 'rclone-test-rizapeq8tudi': Purge remote 2026/07/28 02:50:55 DEBUG : Linkbox root 'rclone-test-doqamuf0nuri': Purge remote 2026/07/28 02:50:56 DEBUG : Linkbox root 'rclone-test-dafawal3quno': Purge remote 2026/07/28 02:50:58 DEBUG : Linkbox root 'rclone-test-saremey4vuyu': Purge remote 2026/07/28 02:50:59 DEBUG : Linkbox root 'rclone-test-yefaqob5wizu': Purge remote 2026/07/28 02:51:00 DEBUG : Linkbox root 'rclone-test-qabofuk4vulo': Purge remote 2026/07/28 02:51:02 DEBUG : Linkbox root 'rclone-test-sihatoy5mosi': Purge remote 2026/07/28 02:51:03 DEBUG : Linkbox root 'rclone-test-vowabuy4zepi': Purge remote 2026/07/28 02:51:04 DEBUG : Linkbox root 'rclone-test-wolowom6tetu': Purge remote --- PASS: TestApplyTransforms (77.47s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:51:06 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/07/28 02:51:10 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/07/28 02:51:10 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/07/28 02:51:11 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/07/28 02:51:11 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: Update err in reading file: max transfer limit reached as set by --max-transfer 2026/07/28 02:51:11 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/07/28 02:51:12 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/07/28 02:51:15 DEBUG : TestCopyFileMaxTransfer/file4: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:51:15 DEBUG : TestCopyFileMaxTransfer/file4: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:51:16 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/07/28 02:51:16 INFO : TestCopyFileMaxTransfer/file4: Copied (new) run.go:186: removing dir "TestCopyFileMaxTransfer" failed - try 1/3: sleeping 1s: directory not empty --- PASS: TestCopyFileMaxTransfer (16.08s) === RUN TestMaxDelete run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:51:24 DEBUG : small: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:51:24 DEBUG : small: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:51:25 DEBUG : small: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:51:30 DEBUG : large: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:51:30 DEBUG : large: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:51:30 DEBUG : Waiting for deletions to finish 2026/07/28 02:51:31 ERROR : small: Got fatal error on delete: --max-delete threshold reached 2026/07/28 02:51:32 INFO : large: Deleted 2026/07/28 02:51:32 INFO : medium: Deleted operations_test.go:453: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:453 Error: Not equal: expected: 1 actual : 2 Test: TestMaxDelete run.go:172: removing file "large" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "large" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "large" failed: could not Remove: Linkbox error 403: not permit --- FAIL: TestMaxDelete (15.78s) === RUN TestMaxDeleteSize run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:51:42 DEBUG : medium: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:51:45 DEBUG : Waiting for deletions to finish 2026/07/28 02:51:45 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/07/28 02:51:46 INFO : small: Deleted 2026/07/28 02:51:46 INFO : medium: 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: TestMaxDeleteSize (14.19s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:51:59 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:51:59 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:51:59 INFO : A2: Making directory 2026/07/28 02:52:01 INFO : A1/B2: Making directory 2026/07/28 02:52:03 INFO : A1/B2/C2: Making directory 2026/07/28 02:52:04 INFO : A1/B1/C3: Making directory 2026/07/28 02:52:06 INFO : A3: Making directory 2026/07/28 02:52:08 INFO : A3/B3: Making directory 2026/07/28 02:52:09 INFO : A3/B3/C4: Making directory 2026/07/28 02:52:13 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:52:13 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:52:16 DEBUG : removing 1 level 3 directories 2026/07/28 02:52:16 INFO : A3/B3/C4: Removing directory 2026/07/28 02:52:21 DEBUG : removing 2 level 3 directories 2026/07/28 02:52:21 INFO : A1/B2/C2: Removing directory 2026/07/28 02:52:21 INFO : A1/B1/C3: Removing directory 2026/07/28 02:52:23 DEBUG : removing 2 level 2 directories 2026/07/28 02:52:23 INFO : A3/B3: Removing directory 2026/07/28 02:52:23 INFO : A1/B2: Removing directory 2026/07/28 02:52:25 DEBUG : removing 2 level 1 directories 2026/07/28 02:52:25 INFO : A3: Removing directory 2026/07/28 02:52:25 INFO : A2: Removing directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/07/28 02:52:40 DEBUG : removing 1 level 3 directories 2026/07/28 02:52:40 INFO : A1/B1/C1: Removing directory 2026/07/28 02:52:42 DEBUG : removing 1 level 2 directories 2026/07/28 02:52:42 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: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:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:730 Error: Not equal: expected: []string{} actual : []string{"A1"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=2) "A1" } Test: TestRmdirsNoLeaveRoot Messages: directories --- FAIL: TestRmdirsNoLeaveRoot (62.34s) === RUN TestRmdirsWithFilter run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:52:54 INFO : A1: Making directory 2026/07/28 02:52:56 INFO : A1/B1: Making directory 2026/07/28 02:52:57 INFO : A1/B1/C1: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/07/28 02:53:09 DEBUG : removing 1 level 3 directories 2026/07/28 02:53:09 INFO : A1/B1/C1: Removing directory 2026/07/28 02:53:10 DEBUG : removing 1 level 2 directories 2026/07/28 02:53:10 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 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:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/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: Not equal: expected: []string{} actual : []string{"A1"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=2) "A1" } Test: TestRmdirsWithFilter Messages: directories --- FAIL: TestRmdirsWithFilter (37.35s) === RUN TestDirMove run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:53:35 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:53:36 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:53:36 DEBUG : A1/one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:53:37 DEBUG : A1/one: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:53:38 DEBUG : A1/one: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:53:40 DEBUG : A1/one: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:53:43 DEBUG : A1/one: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:53:50 DEBUG : A1/one: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:54:05 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:54:05 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:54:05 DEBUG : A1/two: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:54:06 DEBUG : A1/two: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:54:07 DEBUG : A1/two: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:54:09 DEBUG : A1/two: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:54:12 DEBUG : A1/two: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:54:19 DEBUG : A1/two: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:54:32 DEBUG : A1/two: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/28 02:55:01 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:55:01 DEBUG : A1/B1/three: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:55:02 DEBUG : A1/B1/three: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:55:02 DEBUG : A1/B1/three: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:55:03 DEBUG : A1/B1/three: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:55:05 DEBUG : A1/B1/three: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:55:09 DEBUG : A1/B1/three: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:55:15 DEBUG : A1/B1/three: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:55:32 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:55:32 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:55:33 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:55:33 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:55:34 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:55:36 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:55:39 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:55:46 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:56:03 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:56:04 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:56:04 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:56:05 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:56:06 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:56:08 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:56:11 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:56:18 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:56:31 INFO : A1/B2: Making directory 2026/07/28 02:56:32 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:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1396 Error: Not equal: expected: []string{"A1", "A1/B1", "A1/B1/C1", "A1/B1/C2", "A1/B1/C3", "A1/B2"} actual : []string{"A1", "A1/B1", "A1/B1/C1", "A1/B1/C2"} Diff: --- Expected +++ Actual @@ -1,2 +1,2 @@ -([]string) (len=6) { +([]string) (len=4) { (string) (len=2) "A1", @@ -4,5 +4,3 @@ (string) (len=8) "A1/B1/C1", - (string) (len=8) "A1/B1/C2", - (string) (len=8) "A1/B1/C3", - (string) (len=5) "A1/B2" + (string) (len=8) "A1/B1/C2" } Test: TestDirMove Messages: directories 2026/07/28 02:57:00 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:57:00 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:57:00 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:57:00 DEBUG : A2/one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:57:00 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:57:01 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:57:01 DEBUG : A2/two: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:57:01 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:57:01 DEBUG : A2/one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:57:01 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:57:02 DEBUG : A2/B1/three: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:57:02 DEBUG : A2/two: Trying to read object after upload: try again in 200ms (2/10) 2026/07/28 02:57:02 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:57:02 DEBUG : A2/one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:57:02 DEBUG : A2/B1/three: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:57:03 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:57:03 DEBUG : A2/two: Trying to read object after upload: try again in 400ms (3/10) 2026/07/28 02:57:03 DEBUG : A2/one: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:57:03 DEBUG : A2/B1/three: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:57:03 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:57:04 DEBUG : A2/two: Trying to read object after upload: try again in 800ms (4/10) 2026/07/28 02:57:04 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:57:04 DEBUG : A2/one: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:57:04 DEBUG : A2/B1/three: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:57:05 DEBUG : A2/two: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/28 02:57:05 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:57:06 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:57:06 DEBUG : A2/one: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:57:06 DEBUG : A2/B1/three: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:57:06 DEBUG : A2/two: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/28 02:57:09 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:57:09 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:57:09 DEBUG : A2/one: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:57:09 DEBUG : A2/B1/three: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:57:10 DEBUG : A2/two: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/28 02:57:15 DEBUG : A2/B1/C1/four: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:57:16 DEBUG : A2/B1/C2/five: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:57:16 DEBUG : A2/one: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:57:16 DEBUG : A2/B1/three: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:57:16 DEBUG : A2/two: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/28 02:57:28 DEBUG : A2/B1/C1/four: size = 4 OK 2026/07/28 02:57:28 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/07/28 02:57:29 DEBUG : A2/B1/C2/five: size = 4 OK 2026/07/28 02:57:29 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/07/28 02:57:29 DEBUG : A2/one: size = 3 OK 2026/07/28 02:57:29 INFO : A1/one: Copied (new) to: A2/one 2026/07/28 02:57:29 DEBUG : A2/B1/three: size = 5 OK 2026/07/28 02:57:29 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/07/28 02:57:30 INFO : A1/B1/C1/four: Deleted 2026/07/28 02:57:30 DEBUG : A2/two: size = 3 OK 2026/07/28 02:57:30 INFO : A1/two: Copied (new) to: A2/two 2026/07/28 02:57:30 INFO : A1/B1/C2/five: Deleted 2026/07/28 02:57:31 INFO : A1/one: Deleted 2026/07/28 02:57:31 INFO : A1/B1/three: Deleted 2026/07/28 02:57:31 INFO : A1/two: Deleted operations_test.go:1411: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1411 Error: Received unexpected error: RenameDir rmdir: directory not empty Test: TestDirMove run.go:172: removing file "A1/one" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "A1/one" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "A1/one" failed: could not Remove: Linkbox error 403: not permit run.go:172: removing file "A1/two" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "A1/two" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "A1/two" failed: could not Remove: Linkbox error 403: not permit --- FAIL: TestDirMove (271.93s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" 2026/07/28 02:58:04 DEBUG : Linkbox root 'rclone-test-wenilej6yili': File to upload is small (34 bytes), uploading instead of streaming 2026/07/28 02:58:06 DEBUG : ignore_checksum_small_file_from_pipe: Trying to read object after upload: try again in 100ms (1/10) 2026/07/28 02:58:06 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/07/28 02:58:06 DEBUG : ignore_checksum_small_file_from_pipe: Sizes identical 2026/07/28 02:58:06 DEBUG : Linkbox root 'rclone-test-wenilej6yili': Target remote doesn't support streaming uploads, creating temporary local FS to spool file 2026/07/28 02:58:08 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/07/28 02:58:08 DEBUG : ignore_checksum_big_file_from_pipe: Sizes identical --- PASS: TestRcat (7.95s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (7.94s) === RUN TestMkdirMetadata run.go:198: Remote "Linkbox root 'rclone-test-wenilej6yili'", Local "Local file system at /tmp/rclone2977004239", Modify Window "876000h0m0s" operations_test.go:1773: Skipping test as remote does not support MkdirMetadata --- SKIP: TestMkdirMetadata (0.41s) FAIL 2026/07/28 02:58:12 DEBUG : Linkbox root 'rclone-test-wenilej6yili': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestApplyTransforms|TestCheckSumDownload|TestCopyFileMaxTransfer|TestDirMove|TestMaxDelete|TestMaxDeleteSize|TestMkdirMetadata|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter)$|^TestRcat$/^withChecksum=false,ignoreChecksum=true$'" - Finished ERROR in 13m32.289129829s (try 4/5): exit status 1: Failed [TestCheckSumDownload TestMaxDelete TestRmdirsNoLeaveRoot TestRmdirsWithFilter TestDirMove]