"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCheckSumDownload|TestCopyFileCompareDest|TestDirMove|TestParseSumFile|TestRmdirsNoLeaveRoot)$'" - Starting (try 2/5)
2026/08/05 05:29:37 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-famumur7pafi"
2026/08/05 05:29:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/05 05:29:37 DEBUG : Linkbox root 'rclone-test-famumur7pafi': Using cached web token
2026/08/05 05:29:38 DEBUG : Creating backend with remote "/tmp/rclone1381612481"
=== RUN   TestParseSumFile
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:29:42 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:29:42 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:29:43 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1250h19m7.650553293s different from this computer
2026/08/05 05:29:43 NOTICE: test.sum: improperly formatted checksum line 4
2026/08/05 05:29:43 NOTICE: test.sum: improperly formatted checksum line 5
2026/08/05 05:29:43 NOTICE: test.sum: improperly formatted checksum line 6
2026/08/05 05:29:43 NOTICE: test.sum: 2 warning(s) suppressed...
2026/08/05 05:29:44 DEBUG : test.sum: Update: removing old file
2026/08/05 05:29:47 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:29:47 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:29:48 NOTICE: test.sum: improperly formatted checksum line 4
2026/08/05 05:29:48 NOTICE: test.sum: improperly formatted checksum line 5
2026/08/05 05:29:48 NOTICE: test.sum: improperly formatted checksum line 6
2026/08/05 05:29:48 NOTICE: test.sum: 2 warning(s) suppressed...
    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:292: Sleeping for 7s just to make sure
--- PASS: TestParseSumFile (22.57s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:30:01 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-famumur7pafi/data"
2026/08/05 05:30:01 DEBUG : Linkbox root 'rclone-test-famumur7pafi/data': Using cached web token
    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
=== RUN   TestCheckSumDownload/subtest1
2026/08/05 05:30:20 DEBUG : data/potato: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:30:20 DEBUG : data/potato: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:30:21 DEBUG : data/potato: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:30:21 DEBUG : test.sum: Update: removing old file
2026/08/05 05:30:25 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
=== RUN   TestCheckSumDownload/subtest2
2026/08/05 05:30:26 DEBUG : test.sum: Update: removing old file
2026/08/05 05:30:29 DEBUG : test.sum: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:30:30 DEBUG : test.sum: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:30:30 DEBUG : test.sum: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:30:31 DEBUG : test.sum: Trying to read object after upload: try again in 800ms (4/10)
2026/08/05 05:30:32 DEBUG : test.sum: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/05 05:30:33 DEBUG : test.sum: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/05 05:30:37 DEBUG : test.sum: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/05 05:30:43 DEBUG : test.sum: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/05 05:30:56 DEBUG : test.sum: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/05 05:31:22 DEBUG : test.sum: Trying to read object after upload: try again in 51.2s (10/10)
=== NAME  TestCheckSumDownload
    run.go:303: Failed to put "test.sum" to "Linkbox root 'rclone-test-famumur7pafi'": object not found
    run.go:186: removing dir "data" failed - try 1/3: sleeping 1s: directory not empty
--- FAIL: TestCheckSumDownload (140.07s)
    --- PASS: TestCheckSumDownload/subtest1 (1.23s)
    --- PASS: TestCheckSumDownload/subtest2 (0.51s)
=== RUN   TestCopyFileCompareDest
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:32:21 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-famumur7pafi/dst"
2026/08/05 05:32:21 DEBUG : Linkbox root 'rclone-test-famumur7pafi/dst': Using cached web token
2026/08/05 05:32:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-famumur7pafi/CompareDest"
2026/08/05 05:32:23 DEBUG : Linkbox root 'rclone-test-famumur7pafi/CompareDest': Using cached web token
2026/08/05 05:32:24 DEBUG : one: Need to transfer - File not found at Destination
2026/08/05 05:32:28 DEBUG : one: size = 3 OK
2026/08/05 05:32:28 INFO  : one: Copied (new)
2026/08/05 05:32:29 DEBUG : one: size = 5 (Local file system at /tmp/rclone1381612481)
2026/08/05 05:32:29 DEBUG : one: size = 3 (Linkbox root 'rclone-test-famumur7pafi/dst')
2026/08/05 05:32:29 DEBUG : one: Sizes differ
2026/08/05 05:32:30 DEBUG : one: Update: removing old file
2026/08/05 05:32:33 DEBUG : one: size = 5 OK
2026/08/05 05:32:33 INFO  : one: Copied (replaced existing)
2026/08/05 05:32:33 DEBUG : dst/one: Update: removing old file
2026/08/05 05:32:37 DEBUG : dst/one: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:32:37 DEBUG : dst/one: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:32:37 DEBUG : dst/one: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:32:38 DEBUG : dst/one: Trying to read object after upload: try again in 800ms (4/10)
2026/08/05 05:32:39 DEBUG : dst/one: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/05 05:32:41 DEBUG : dst/one: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/05 05:32:44 DEBUG : dst/one: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/05 05:32:51 DEBUG : dst/one: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/05 05:33:04 DEBUG : dst/one: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/05 05:33:30 DEBUG : dst/one: Trying to read object after upload: try again in 51.2s (10/10)
    run.go:303: Failed to put "dst/one" to "Linkbox root 'rclone-test-famumur7pafi'": object not found
    run.go:186: removing dir "dst" failed - try 1/3: sleeping 1s: directory not empty
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- FAIL: TestCopyFileCompareDest (132.89s)
=== RUN   TestRmdirsNoLeaveRoot
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:34:40 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:34:41 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:34:41 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:34:42 DEBUG : A1/B1/C1/one: Trying to read object after upload: try again in 800ms (4/10)
2026/08/05 05:34:43 INFO  : A2: Making directory
2026/08/05 05:34:45 INFO  : A1/B2: Making directory
2026/08/05 05:34:46 INFO  : A1/B2/C2: Making directory
2026/08/05 05:34:48 INFO  : A1/B1/C3: Making directory
2026/08/05 05:34:49 INFO  : A3: Making directory
2026/08/05 05:34:51 INFO  : A3/B3: Making directory
2026/08/05 05:34:52 INFO  : A3/B3/C4: Making directory
2026/08/05 05:34:56 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:34:59 DEBUG : removing 1 level 3 directories
2026/08/05 05:34:59 INFO  : A3/B3/C4: Removing directory
2026/08/05 05:35:04 DEBUG : removing 2 level 3 directories
2026/08/05 05:35:04 INFO  : A1/B2/C2: Removing directory
2026/08/05 05:35:04 INFO  : A1/B1/C3: Removing directory
2026/08/05 05:35:06 DEBUG : removing 2 level 2 directories
2026/08/05 05:35:06 INFO  : A3/B3: Removing directory
2026/08/05 05:35:06 INFO  : A1/B2: Removing directory
2026/08/05 05:35:08 DEBUG : removing 2 level 1 directories
2026/08/05 05:35:08 INFO  : A3: Removing directory
2026/08/05 05:35:08 INFO  : A2: Removing directory
2026/08/05 05:35:15 DEBUG : removing 1 level 3 directories
2026/08/05 05:35:15 INFO  : A1/B1/C1: Removing directory
2026/08/05 05:35:16 DEBUG : removing 1 level 2 directories
2026/08/05 05:35:16 INFO  : A1/B1: Removing directory
2026/08/05 05:35:17 ERROR : A1/B1: Failed to rmdir: directory not empty
2026/08/05 05:35:17 DEBUG : removing 1 level 1 directories
2026/08/05 05:35:17 INFO  : A1: Removing directory
2026/08/05 05:35:17 ERROR : A1: Failed to rmdir: directory not empty
2026/08/05 05:35:17 DEBUG : removing 1 level 0 directories
2026/08/05 05:35:17 INFO  : Linkbox root 'rclone-test-famumur7pafi': Removing directory
2026/08/05 05:35:17 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: 3 errors: last error: directory not empty
        	Test:       	TestRmdirsNoLeaveRoot
    fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3
    fstest.go:301: Flushing the directory cache
    fstest.go:292: Sleeping for 6s just to make sure
--- FAIL: TestRmdirsNoLeaveRoot (54.90s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:35:31 DEBUG : hello: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:35:31 DEBUG : hello: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:35:31 DEBUG : hello: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:35:33 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:292: Sleeping for 6s just to make sure
--- PASS: TestCaseInsensitiveMoveFileDryRun (13.45s)
=== RUN   TestDirMove
    run.go:198: Remote "Linkbox root 'rclone-test-famumur7pafi'", Local "Local file system at /tmp/rclone1381612481", Modify Window "876000h0m0s"
2026/08/05 05:35:46 DEBUG : A1/one: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:35:46 DEBUG : A1/one: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:35:48 DEBUG : A1/two: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:35:49 DEBUG : A1/two: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:35:49 DEBUG : A1/two: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:35:50 DEBUG : A1/two: Trying to read object after upload: try again in 800ms (4/10)
2026/08/05 05:35:54 DEBUG : A1/B1/three: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:35:58 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:35:58 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:35:59 DEBUG : A1/B1/C1/four: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:36:03 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:36:03 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 200ms (2/10)
2026/08/05 05:36:04 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 400ms (3/10)
2026/08/05 05:36:04 DEBUG : A1/B1/C2/five: Trying to read object after upload: try again in 800ms (4/10)
2026/08/05 05:36:05 INFO  : A1/B2: Making directory
2026/08/05 05:36:07 INFO  : A1/B1/C3: Making directory
2026/08/05 05:36:26 DEBUG : A2/two: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:36:26 DEBUG : A2/one: Trying to read object after upload: try again in 100ms (1/10)
2026/08/05 05:36:27 DEBUG : A2/two: size = 3 OK
2026/08/05 05:36:27 INFO  : A1/two: Copied (new) to: A2/two
2026/08/05 05:36:27 DEBUG : A2/one: size = 3 OK
2026/08/05 05:36:27 INFO  : A1/one: Copied (new) to: A2/one
2026/08/05 05:36:27 DEBUG : A2/B1/C1/four: size = 4 OK
2026/08/05 05:36:27 INFO  : A1/B1/C1/four: Copied (new) to: A2/B1/C1/four
2026/08/05 05:36:27 DEBUG : A2/B1/three: size = 5 OK
2026/08/05 05:36:27 INFO  : A1/B1/three: Copied (new) to: A2/B1/three
2026/08/05 05:36:28 DEBUG : A2/B1/C2/five: size = 4 OK
2026/08/05 05:36:28 INFO  : A1/B1/C2/five: Copied (new) to: A2/B1/C2/five
2026/08/05 05:36:28 INFO  : A1/two: Deleted
2026/08/05 05:36:29 INFO  : A1/one: Deleted
2026/08/05 05:36:29 INFO  : A1/B1/C1/four: Deleted
2026/08/05 05:36:29 INFO  : A1/B1/three: Deleted
2026/08/05 05:36:30 INFO  : A1/B1/C2/five: Deleted
2026/08/05 05:36:56 DEBUG : A3/B1/C2/five: size = 4 OK
2026/08/05 05:36:56 INFO  : A2/B1/C2/five: Copied (new) to: A3/B1/C2/five
2026/08/05 05:36:57 DEBUG : A3/one: size = 3 OK
2026/08/05 05:36:57 INFO  : A2/one: Copied (new) to: A3/one
2026/08/05 05:36:57 DEBUG : A3/two: size = 3 OK
2026/08/05 05:36:57 INFO  : A2/two: Copied (new) to: A3/two
2026/08/05 05:36:57 DEBUG : A3/B1/three: size = 5 OK
2026/08/05 05:36:57 INFO  : A2/B1/three: Copied (new) to: A3/B1/three
2026/08/05 05:36:57 INFO  : A2/B1/C2/five: Deleted
2026/08/05 05:36:58 DEBUG : A3/B1/C1/four: size = 4 OK
2026/08/05 05:36:58 INFO  : A2/B1/C1/four: Copied (new) to: A3/B1/C1/four
2026/08/05 05:36:58 INFO  : A2/one: Deleted
2026/08/05 05:36:58 INFO  : A2/two: Deleted
2026/08/05 05:36:59 INFO  : A2/B1/three: Deleted
2026/08/05 05:36:59 INFO  : A2/B1/C1/four: Deleted
2026/08/05 05:37:10 INFO  : Linkbox root 'rclone-test-famumur7pafi': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes
2026/08/05 05:37:26 DEBUG : A4/B1/C1/four: size = 4 OK
2026/08/05 05:37:26 INFO  : A3/B1/C1/four: Copied (new) to: A4/B1/C1/four
2026/08/05 05:37:26 DEBUG : A4/B1/three: size = 5 OK
2026/08/05 05:37:26 INFO  : A3/B1/three: Copied (new) to: A4/B1/three
2026/08/05 05:37:26 DEBUG : A4/B1/C2/five: size = 4 OK
2026/08/05 05:37:26 INFO  : A3/B1/C2/five: Copied (new) to: A4/B1/C2/five
2026/08/05 05:37:27 DEBUG : A4/one: size = 3 OK
2026/08/05 05:37:27 INFO  : A3/one: Copied (new) to: A4/one
2026/08/05 05:37:27 DEBUG : A4/two: size = 3 OK
2026/08/05 05:37:27 INFO  : A3/two: Copied (new) to: A4/two
2026/08/05 05:37:27 INFO  : A3/B1/C1/four: Deleted
2026/08/05 05:37:28 INFO  : A3/B1/three: Deleted
2026/08/05 05:37:28 INFO  : A3/B1/C2/five: Deleted
2026/08/05 05:37:28 INFO  : A3/one: Deleted
2026/08/05 05:37:29 INFO  : A3/two: Deleted
--- PASS: TestDirMove (134.64s)
FAIL
2026/08/05 05:37:57 DEBUG : Linkbox root 'rclone-test-famumur7pafi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestCaseInsensitiveMoveFileDryRun|TestCheckSumDownload|TestCopyFileCompareDest|TestDirMove|TestParseSumFile|TestRmdirsNoLeaveRoot)$'" - Finished ERROR in 8m20.883275544s (try 2/5): exit status 1: Failed [TestCheckSumDownload TestCopyFileCompareDest TestRmdirsNoLeaveRoot]