"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncCompareDest)$'" - Starting (try 4/5) 2026/08/04 04:34:08 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-bubalef3xegi" 2026/08/04 04:34:08 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/04 04:34:08 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Using cached web token 2026/08/04 04:34:09 DEBUG : Creating backend with remote "/tmp/rclone3272976771" === RUN TestMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-bubalef3xegi'", Local "Local file system at /tmp/rclone3272976771", Modify Window "876000h0m0s" 2026/08/04 04:34:14 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:34:15 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:34:15 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:34:16 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Waiting for checks to finish 2026/08/04 04:34:16 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3272976771) 2026/08/04 04:34:16 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-bubalef3xegi') 2026/08/04 04:34:16 DEBUG : sub dir/hello world: Sizes differ 2026/08/04 04:34:16 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Waiting for transfers to finish 2026/08/04 04:34:16 DEBUG : sub dir/hello world: Update: removing old file 2026/08/04 04:34:20 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3272976771) 2026/08/04 04:34:20 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-bubalef3xegi') 2026/08/04 04:34:20 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(Local file system at /tmp/rclone3272976771) 17 vs dst(Linkbox root 'rclone-test-bubalef3xegi') 11 2026/08/04 04:34:20 INFO : sub dir/hello world: Removing failed copy 2026/08/04 04:34:20 INFO : sub dir/hello world: Failed to remove failed copy: could not Remove: Linkbox error 403: not permit 2026/08/04 04:34:20 ERROR : sub dir/hello world: Not deleting source as copy failed: corrupted on transfer: sizes differ src(Local file system at /tmp/rclone3272976771) 17 vs dst(Linkbox root 'rclone-test-bubalef3xegi') 11 sync_test.go:685: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Received unexpected error: corrupted on transfer: sizes differ src(Local file system at /tmp/rclone3272976771) 17 vs dst(Linkbox root 'rclone-test-bubalef3xegi') 11 Test: TestMoveOverSelf run.go:172: removing file "sub dir/hello world" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "sub dir/hello world" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "sub dir/hello world" failed: could not Remove: Linkbox error 403: not permit run.go:186: removing dir "sub dir" failed - try 1/3: sleeping 1s: directory not empty run.go:186: removing dir "sub dir" failed - try 2/3: sleeping 1.5s: directory not empty run.go:186: removing dir "sub dir" failed: directory not empty fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Should be true Test: TestMoveOverSelf Messages: listing wrong, want got sub dir/hello world(1) (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Should be true Test: TestMoveOverSelf Messages: Unexpected file "sub dir/hello world(1)" 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 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:685 Error: Not equal: expected: []string{} actual : []string{"sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=7) "sub dir" } Test: TestMoveOverSelf Messages: directories --- FAIL: TestMoveOverSelf (26.58s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:198: Remote "Linkbox root 'rclone-test-bubalef3xegi'", Local "Local file system at /tmp/rclone3272976771", Modify Window "876000h0m0s" 2026/08/04 04:34:43 DEBUG : c/non empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:34:44 INFO : d: Making directory 2026/08/04 04:34:45 INFO : d/e: Making directory fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1217 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: listing wrong, want b/potato (21), c/non empty space (5) got b/potato (21), c/non empty space (5), sub dir/hello world(1) (17) fstest.go:191: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:308 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1217 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: Unexpected file "sub dir/hello world(1)" 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:378 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1217 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1262 Error: Not equal: expected: []string{"b", "c", "d", "d/e"} actual : []string{"b", "c", "d", "d/e", "sub dir"} Diff: --- Expected +++ Actual @@ -1,2 +1,2 @@ -([]string) (len=4) { +([]string) (len=5) { (string) (len=1) "b", @@ -4,3 +4,4 @@ (string) (len=1) "d", - (string) (len=3) "d/e" + (string) (len=3) "d/e", + (string) (len=7) "sub dir" } Test: TestSyncAfterRemovingAFileAndAddingAFileSubDir Messages: directories 2026/08/04 04:34:59 DEBUG : a/potato2: Need to transfer - File not found at Destination 2026/08/04 04:34:59 DEBUG : c/non empty space: size = 5 OK 2026/08/04 04:34:59 DEBUG : c/non empty space: Sizes identical 2026/08/04 04:34:59 DEBUG : c/non empty space: Unchanged skipping 2026/08/04 04:35:02 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Waiting for checks to finish 2026/08/04 04:35:02 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Waiting for transfers to finish 2026/08/04 04:35:03 DEBUG : a/potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:04 DEBUG : a/potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:04 DEBUG : a/potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:05 DEBUG : a/potato2: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:35:06 DEBUG : a/potato2: size = 60 OK 2026/08/04 04:35:06 INFO : a/potato2: Copied (new) 2026/08/04 04:35:06 DEBUG : Waiting for deletions to finish 2026/08/04 04:35:07 INFO : sub dir/hello world(1): Deleted 2026/08/04 04:35:07 INFO : b/potato: Deleted 2026/08/04 04:35:07 INFO : sub dir: Removing directory 2026/08/04 04:35:09 INFO : d/e: Removing directory 2026/08/04 04:35:10 INFO : d: Removing directory 2026/08/04 04:35:12 INFO : b: Removing directory 2026/08/04 04:35:13 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': deleted 4 directories run.go:186: removing dir "c" failed - try 1/3: sleeping 1s: directory not empty --- FAIL: TestSyncAfterRemovingAFileAndAddingAFileSubDir (46.13s) === RUN TestServerSideMove run.go:198: Remote "Linkbox root 'rclone-test-bubalef3xegi'", Local "Local file system at /tmp/rclone3272976771", Modify Window "876000h0m0s" 2026/08/04 04:35:22 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-powasop1xamu" 2026/08/04 04:35:22 DEBUG : Linkbox root 'rclone-test-powasop1xamu': Using cached web token 2026/08/04 04:35:27 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:27 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:27 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:28 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:35:31 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:31 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:32 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:32 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:35:33 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10) sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-bubalef3xegi' -> Linkbox root 'rclone-test-powasop1xamu' 2026/08/04 04:35:39 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:39 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:40 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:43 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:43 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:43 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:44 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:35:44 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/04 04:35:44 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:35:44 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/04 04:35:44 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:35:44 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/04 04:35:45 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:35:45 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/04 04:35:47 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/04 04:35:49 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/04 04:35:50 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/04 04:35:50 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/04 04:35:50 DEBUG : empty space: size = 1 OK 2026/08/04 04:35:50 DEBUG : empty space: Sizes identical 2026/08/04 04:35:50 DEBUG : empty space: Unchanged skipping 2026/08/04 04:35:50 DEBUG : Linkbox root 'rclone-test-powasop1xamu': Waiting for checks to finish 2026/08/04 04:35:50 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-bubalef3xegi') 2026/08/04 04:35:50 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-powasop1xamu') 2026/08/04 04:35:50 DEBUG : potato3: Sizes differ 2026/08/04 04:35:51 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1273h26m14.004553317s different from this computer 2026/08/04 04:35:51 DEBUG : potato3: Update: removing old file 2026/08/04 04:35:51 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/08/04 04:35:52 INFO : empty space: Deleted 2026/08/04 04:35:52 DEBUG : Linkbox root 'rclone-test-powasop1xamu': Waiting for transfers to finish 2026/08/04 04:35:53 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/04 04:35:53 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/04 04:35:54 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/04 04:35:55 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/04 04:35:55 DEBUG : pacer: Reducing sleep to 200ms 2026/08/04 04:35:55 DEBUG : potato2: size = 60 OK 2026/08/04 04:35:55 INFO : potato2: Copied (new) 2026/08/04 04:35:56 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:35:56 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:35:56 INFO : potato2: Deleted 2026/08/04 04:35:57 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:35:57 DEBUG : potato3: size = 68 OK 2026/08/04 04:35:57 INFO : potato3: Copied (replaced existing) 2026/08/04 04:35:58 INFO : potato3: Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/08/04 04:36:07 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-gujekux7nufi" 2026/08/04 04:36:07 DEBUG : Linkbox root 'rclone-test-gujekux7nufi': Using cached web token 2026/08/04 04:36:08 DEBUG : empty space: Need to transfer - File not found at Destination 2026/08/04 04:36:08 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/04 04:36:08 DEBUG : potato3: Need to transfer - File not found at Destination 2026/08/04 04:36:08 DEBUG : Linkbox root 'rclone-test-gujekux7nufi': Waiting for checks to finish 2026/08/04 04:36:08 DEBUG : Linkbox root 'rclone-test-gujekux7nufi': Waiting for transfers to finish 2026/08/04 04:36:12 DEBUG : empty space: size = 1 OK 2026/08/04 04:36:12 INFO : empty space: Copied (new) 2026/08/04 04:36:13 DEBUG : potato2: size = 60 OK 2026/08/04 04:36:13 INFO : potato2: Copied (new) 2026/08/04 04:36:13 DEBUG : potato3: size = 68 OK 2026/08/04 04:36:13 INFO : potato3: Copied (new) 2026/08/04 04:36:14 INFO : empty space: Deleted 2026/08/04 04:36:14 INFO : potato2: Deleted 2026/08/04 04:36:14 INFO : potato3: Deleted 2026/08/04 04:36:15 DEBUG : Linkbox root 'rclone-test-gujekux7nufi': Purge remote 2026/08/04 04:36:16 DEBUG : Linkbox root 'rclone-test-powasop1xamu': Purge remote --- PASS: TestServerSideMove (55.94s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "Linkbox root 'rclone-test-bubalef3xegi'", Local "Local file system at /tmp/rclone3272976771", Modify Window "876000h0m0s" 2026/08/04 04:36:18 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tebewih1daze" 2026/08/04 04:36:18 DEBUG : Linkbox root 'rclone-test-tebewih1daze': Using cached web token 2026/08/04 04:36:25 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:25 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:25 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-bubalef3xegi' -> Linkbox root 'rclone-test-tebewih1daze' 2026/08/04 04:36:31 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:31 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:32 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:36:34 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:35 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/04 04:36:35 DEBUG : empty space: size = 1 OK 2026/08/04 04:36:35 DEBUG : empty space: Sizes identical 2026/08/04 04:36:35 DEBUG : empty space: Unchanged skipping 2026/08/04 04:36:35 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-bubalef3xegi') 2026/08/04 04:36:35 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-tebewih1daze') 2026/08/04 04:36:35 DEBUG : potato3: Sizes differ 2026/08/04 04:36:35 DEBUG : Linkbox root 'rclone-test-tebewih1daze': Waiting for checks to finish 2026/08/04 04:36:36 DEBUG : potato3: Update: removing old file 2026/08/04 04:36:36 INFO : empty space: Deleted 2026/08/04 04:36:36 DEBUG : Linkbox root 'rclone-test-tebewih1daze': Waiting for transfers to finish 2026/08/04 04:36:38 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:38 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:39 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:36:39 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:39 DEBUG : potato2: size = 60 OK 2026/08/04 04:36:39 INFO : potato2: Copied (new) 2026/08/04 04:36:39 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:40 DEBUG : potato3: size = 68 OK 2026/08/04 04:36:40 INFO : potato3: Copied (replaced existing) 2026/08/04 04:36:41 INFO : potato2: Deleted 2026/08/04 04:36:41 INFO : potato3: Deleted 2026/08/04 04:36:41 INFO : tomatoDir: Removing directory 2026/08/04 04:36:43 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': deleted 1 directories 2026/08/04 04:36:43 DEBUG : pacer: low level retry 1/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:36:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms 2026/08/04 04:36:43 DEBUG : pacer: low level retry 2/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:36:43 DEBUG : pacer: Rate limited, increasing sleep to 800ms 2026/08/04 04:36:43 DEBUG : pacer: low level retry 3/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:36:43 DEBUG : pacer: Rate limited, increasing sleep to 1.6s 2026/08/04 04:36:44 DEBUG : pacer: low level retry 4/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:36:44 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/08/04 04:36:46 DEBUG : pacer: low level retry 5/10 (error invalid character '<' looking for beginning of value) 2026/08/04 04:36:48 DEBUG : pacer: Reducing sleep to 1.5s 2026/08/04 04:36:50 DEBUG : pacer: Reducing sleep to 1.125s 2026/08/04 04:36:50 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-zenazec6selu" 2026/08/04 04:36:50 DEBUG : Linkbox root 'rclone-test-zenazec6selu': Using cached web token 2026/08/04 04:36:51 INFO : tomatoDir: Making directory 2026/08/04 04:36:53 DEBUG : empty space: Need to transfer - File not found at Destination 2026/08/04 04:36:53 DEBUG : potato2: Need to transfer - File not found at Destination 2026/08/04 04:36:53 DEBUG : potato3: Need to transfer - File not found at Destination 2026/08/04 04:36:53 DEBUG : Linkbox root 'rclone-test-zenazec6selu': Waiting for checks to finish 2026/08/04 04:36:53 DEBUG : Linkbox root 'rclone-test-zenazec6selu': Waiting for transfers to finish 2026/08/04 04:36:58 DEBUG : empty space: size = 1 OK 2026/08/04 04:36:58 INFO : empty space: Copied (new) 2026/08/04 04:36:58 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:58 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:36:58 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:58 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:36:59 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:36:59 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:36:59 INFO : empty space: Deleted 2026/08/04 04:36:59 DEBUG : potato2: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:36:59 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:37:00 DEBUG : potato2: Trying to read object after upload: try again in 1.6s (5/10) 2026/08/04 04:37:00 DEBUG : potato3: size = 68 OK 2026/08/04 04:37:00 INFO : potato3: Copied (new) 2026/08/04 04:37:02 INFO : potato3: Deleted 2026/08/04 04:37:02 DEBUG : potato2: size = 60 OK 2026/08/04 04:37:02 INFO : potato2: Copied (new) 2026/08/04 04:37:03 INFO : potato2: Deleted 2026/08/04 04:37:03 INFO : tomatoDir: Removing directory 2026/08/04 04:37:05 DEBUG : Linkbox root 'rclone-test-tebewih1daze': deleted 1 directories 2026/08/04 04:37:06 DEBUG : Linkbox root 'rclone-test-zenazec6selu': Purge remote 2026/08/04 04:37:07 DEBUG : Linkbox root 'rclone-test-tebewih1daze': Purge remote 2026/08/04 04:37:08 DEBUG : pacer: Reducing sleep to 843.75ms 2026/08/04 04:37:09 DEBUG : pacer: Reducing sleep to 632.8125ms --- PASS: TestServerSideMoveDeleteEmptySourceDirs (51.80s) === RUN TestSyncCompareDest run.go:198: Remote "Linkbox root 'rclone-test-bubalef3xegi'", Local "Local file system at /tmp/rclone3272976771", Modify Window "876000h0m0s" 2026/08/04 04:37:09 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-bubalef3xegi/dst" 2026/08/04 04:37:09 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/dst': Using cached web token 2026/08/04 04:37:11 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-bubalef3xegi/CompareDest" 2026/08/04 04:37:11 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/CompareDest': Using cached web token 2026/08/04 04:37:12 DEBUG : one: Need to transfer - File not found at Destination 2026/08/04 04:37:12 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/dst': Waiting for checks to finish 2026/08/04 04:37:12 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/dst': Waiting for transfers to finish 2026/08/04 04:37:16 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:37:16 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:37:17 DEBUG : one: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:37:17 DEBUG : one: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:37:18 DEBUG : one: Trying to read object after upload: try again in 1.6s (5/10) 2026/08/04 04:37:20 DEBUG : one: Trying to read object after upload: try again in 3.2s (6/10) 2026/08/04 04:37:24 DEBUG : one: size = 3 OK 2026/08/04 04:37:24 INFO : one: Copied (new) 2026/08/04 04:37:24 DEBUG : Waiting for deletions to finish 2026/08/04 04:37:24 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/08/04 04:37:24 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/08/04 04:37:25 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/08/04 04:37:25 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/08/04 04:37:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone3272976771) 2026/08/04 04:37:25 DEBUG : one: size = 3 (Linkbox root 'rclone-test-bubalef3xegi/dst') 2026/08/04 04:37:25 DEBUG : one: Sizes differ 2026/08/04 04:37:25 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/dst': Waiting for checks to finish 2026/08/04 04:37:26 DEBUG : Linkbox root 'rclone-test-bubalef3xegi/dst': Waiting for transfers to finish 2026/08/04 04:37:26 DEBUG : one: Update: removing old file 2026/08/04 04:37:29 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:37:29 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:37:30 DEBUG : one: size = 5 OK 2026/08/04 04:37:30 INFO : one: Copied (replaced existing) 2026/08/04 04:37:30 DEBUG : Waiting for deletions to finish 2026/08/04 04:37:30 DEBUG : pacer: Reducing sleep to 200ms 2026/08/04 04:37:30 DEBUG : dst/one: Update: removing old file 2026/08/04 04:37:33 DEBUG : dst/one: Trying to read object after upload: try again in 100ms (1/10) 2026/08/04 04:37:34 DEBUG : dst/one: Trying to read object after upload: try again in 200ms (2/10) 2026/08/04 04:37:34 DEBUG : dst/one: Trying to read object after upload: try again in 400ms (3/10) 2026/08/04 04:37:35 DEBUG : dst/one: Trying to read object after upload: try again in 800ms (4/10) 2026/08/04 04:37:36 DEBUG : dst/one: Trying to read object after upload: try again in 1.6s (5/10) 2026/08/04 04:37:37 DEBUG : dst/one: Trying to read object after upload: try again in 3.2s (6/10) 2026/08/04 04:37:41 DEBUG : dst/one: Trying to read object after upload: try again in 6.4s (7/10) 2026/08/04 04:37:47 DEBUG : dst/one: Trying to read object after upload: try again in 12.8s (8/10) 2026/08/04 04:38:00 DEBUG : dst/one: Trying to read object after upload: try again in 25.6s (9/10) 2026/08/04 04:38:26 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-bubalef3xegi'": 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: TestSyncCompareDest (140.55s) FAIL 2026/08/04 04:39:30 DEBUG : Linkbox root 'rclone-test-bubalef3xegi': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestMoveOverSelf|TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestSyncAfterRemovingAFileAndAddingAFileSubDir|TestSyncCompareDest)$'" - Finished ERROR in 5m23.267229225s (try 4/5): exit status 1: Failed [TestMoveOverSelf TestSyncAfterRemovingAFileAndAddingAFileSubDir TestSyncCompareDest]