"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestDirMove|TestListDirSortedFn)$'" - Starting (try 4/5) 2026/05/04 02:32:14 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-bebemit3tine" 2026/05/04 02:32:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/05/04 02:32:14 DEBUG : Linkbox root 'rclone-test-bebemit3tine': Using cached web token 2026/05/04 02:32:15 DEBUG : Creating backend with remote "/tmp/rclone2084792952" === RUN TestCheckSumDownload run.go:185: Remote "Linkbox root 'rclone-test-bebemit3tine'", Local "Local file system at /tmp/rclone2084792952", Modify Window "876000h0m0s" 2026/05/04 02:32:15 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-bebemit3tine/data" 2026/05/04 02:32:15 DEBUG : Linkbox root 'rclone-test-bebemit3tine/data': Using cached web token 2026/05/04 02:32:20 DEBUG : data/banana: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:32:21 DEBUG : data/banana: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:32:23 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:32:23 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:32:24 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) === RUN TestCheckSumDownload/subtest1 2026/05/04 02:32:28 DEBUG : data/potato: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:32:28 DEBUG : data/potato: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:32:28 DEBUG : data/potato: Trying to read object after upload: try again in 400ms (3/10) 2026/05/04 02:32:29 DEBUG : test.sum: Update: removing old file === 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:345 /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) 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:345 /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" fstest.go:203: Not found "test.sum" fstest.go:206: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:206 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:310 /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:345 /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: Not equal: expected: 0 actual : 1 Test: TestCheckSumDownload Messages: 1 objects not found === RUN TestCheckSumDownload/subtest2 === NAME TestCheckSumDownload check_test.go:409: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:409 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 1 actual : 0 Test: TestCheckSumDownload Messages: wrong error count in run 2 check_test.go:413: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:413 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: "" does not contain "matching files" Test: TestCheckSumDownload Messages: missing matching files in run 2 check_test.go:415: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:415 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: 2 actual : 0 Test: TestCheckSumDownload Messages: wrong number of checks in run 2 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:417 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "- potato", "= banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,5 +1,2 @@ -([]string) (len=3) { - (string) "", - (string) (len=8) "- potato", - (string) (len=8) "= banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong combined result in run 2 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:418 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "potato"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "potato" +([]string) { } Test: TestCheckSumDownload Messages: wrong missingonsrc result in run 2 check_test.go:384: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:384 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:420 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:428 Error: Not equal: expected: []string{"", "banana"} actual : []string{} Diff: --- Expected +++ Actual @@ -1,4 +1,2 @@ -([]string) (len=2) { - (string) "", - (string) (len=6) "banana" +([]string) { } Test: TestCheckSumDownload Messages: wrong match result in run 2 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:345 /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.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:345 /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" === RUN TestCheckSumDownload/subtest3 2026/05/04 02:32:53 DEBUG : test.sum: Update: removing old file 2026/05/04 02:32:56 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:32:56 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:32:57 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/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:345 /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.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:345 /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" === RUN TestCheckSumDownload/subtest4 2026/05/04 02:33:07 DEBUG : test.sum: Update: removing old file 2026/05/04 02:33:10 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:33:11 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:33:11 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10) 2026/05/04 02:33:12 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10) 2026/05/04 02:33:13 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10) 2026/05/04 02:33:14 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10) 2026/05/04 02:33:18 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10) 2026/05/04 02:33:24 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10) 2026/05/04 02:33:37 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10) 2026/05/04 02:34:03 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10) === NAME TestCheckSumDownload run.go:290: Failed to put "test.sum" to "Linkbox root 'rclone-test-bebemit3tine'": object not found run.go:130: removing dir "data" failed - try 1/3: directory not empty --- FAIL: TestCheckSumDownload (168.21s) --- PASS: TestCheckSumDownload/subtest1 (1.02s) --- PASS: TestCheckSumDownload/subtest2 (0.50s) --- PASS: TestCheckSumDownload/subtest3 (0.99s) --- PASS: TestCheckSumDownload/subtest4 (0.91s) === RUN TestListDirSortedFn run.go:185: Remote "Linkbox root 'rclone-test-bebemit3tine'", Local "Local file system at /tmp/rclone2084792952", Modify Window "876000h0m0s" 2026/05/04 02:35:05 DEBUG : a.txt: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:05 DEBUG : a.txt: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:35:06 DEBUG : a.txt: Trying to read object after upload: try again in 400ms (3/10) 2026/05/04 02:35:12 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:14 DEBUG : sub dir/hello world2: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:14 DEBUG : sub dir/hello world2: Trying to read object after upload: try again in 200ms (2/10) run.go:290: Failed to put "sub dir/ignore dir/.ignore" to "Linkbox root 'rclone-test-bebemit3tine'": Update second step failed: Linkbox error 500: The file name needs to include a suffix, such as xxx.mp4 --- FAIL: TestListDirSortedFn (22.81s) === RUN TestDirMove run.go:185: Remote "Linkbox root 'rclone-test-bebemit3tine'", Local "Local file system at /tmp/rclone2084792952", Modify Window "876000h0m0s" 2026/05/04 02:35:29 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:30 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:35:30 DEBUG : A1/one: Trying to read object after upload: try again in 400ms (3/10) 2026/05/04 02:35:31 DEBUG : A1/one: Trying to read object after upload: try again in 800ms (4/10) 2026/05/04 02:35:32 DEBUG : A1/one: Trying to read object after upload: try again in 1.6s (5/10) 2026/05/04 02:35:39 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:42 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:43 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:35:47 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:35:47 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:35:47 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10) 2026/05/04 02:35:48 INFO : A1/B2: Making directory 2026/05/04 02:35:49 INFO : A1/B1/C3: Making directory 2026/05/04 02:36:08 DEBUG : A2/one: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:08 DEBUG : A2/two: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:09 DEBUG : A2/one: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:36:09 DEBUG : A2/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:09 DEBUG : A2/two: size = 3 OK 2026/05/04 02:36:09 INFO : A1/two: Copied (new) to: A2/two 2026/05/04 02:36:09 DEBUG : A2/B1/C2/five: size = 4 OK 2026/05/04 02:36:09 INFO : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five 2026/05/04 02:36:09 DEBUG : A2/B1/C1/four: size = 4 OK 2026/05/04 02:36:09 INFO : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four 2026/05/04 02:36:10 DEBUG : A2/one: size = 3 OK 2026/05/04 02:36:10 INFO : A1/one: Copied (new) to: A2/one 2026/05/04 02:36:10 DEBUG : A2/B1/three: size = 5 OK 2026/05/04 02:36:10 INFO : A1/B1/three: Copied (new) to: A2/B1/three 2026/05/04 02:36:11 INFO : A1/two: Deleted 2026/05/04 02:36:11 INFO : A1/B1/C2/five: Deleted 2026/05/04 02:36:11 INFO : A1/B1/C1/four: Deleted 2026/05/04 02:36:12 INFO : A1/one: Deleted 2026/05/04 02:36:12 INFO : A1/B1/three: Deleted 2026/05/04 02:36:38 DEBUG : A3/B1/three: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:38 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:39 DEBUG : A3/B1/three: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:36:39 DEBUG : A3/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10) 2026/05/04 02:36:39 DEBUG : A3/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10) 2026/05/04 02:36:39 DEBUG : A3/one: size = 3 OK 2026/05/04 02:36:39 INFO : A2/one: Copied (new) to: A3/one 2026/05/04 02:36:39 DEBUG : A3/two: size = 3 OK 2026/05/04 02:36:39 INFO : A2/two: Copied (new) to: A3/two 2026/05/04 02:36:40 DEBUG : A3/B1/three: size = 5 OK 2026/05/04 02:36:40 INFO : A2/B1/three: Copied (new) to: A3/B1/three 2026/05/04 02:36:40 DEBUG : A3/B1/C2/five: size = 4 OK 2026/05/04 02:36:40 INFO : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five 2026/05/04 02:36:40 DEBUG : A3/B1/C1/four: size = 4 OK 2026/05/04 02:36:40 INFO : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four 2026/05/04 02:36:41 INFO : A2/one: Deleted 2026/05/04 02:36:41 INFO : A2/two: Deleted 2026/05/04 02:36:42 INFO : A2/B1/three: Deleted 2026/05/04 02:36:42 INFO : A2/B1/C2/five: Deleted 2026/05/04 02:36:42 INFO : A2/B1/C1/four: Deleted operations_test.go:1436: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1436 Error: Received unexpected error: RenameDir rmdir: directory not empty Test: TestDirMove --- FAIL: TestDirMove (102.91s) FAIL 2026/05/04 02:37:09 DEBUG : Linkbox root 'rclone-test-bebemit3tine': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCheckSumDownload|TestDirMove|TestListDirSortedFn)$'" - Finished ERROR in 4m55.885021041s (try 4/5): exit status 1: Failed [TestCheckSumDownload TestListDirSortedFn TestDirMove]