"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterChangingContentsOnly)$'" - Starting (try 5/5) 2026/07/27 08:14:24 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vulayuw7xejo" 2026/07/27 08:14:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/27 08:14:24 DEBUG : Linkbox root 'rclone-test-vulayuw7xejo': Using cached web token 2026/07/27 08:14:24 DEBUG : Creating backend with remote "/tmp/rclone1640231177" === RUN TestServerSideCopyOverSelf run.go:198: Remote "Linkbox root 'rclone-test-vulayuw7xejo'", Local "Local file system at /tmp/rclone1640231177", Modify Window "876000h0m0s" 2026/07/27 08:14:29 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/27 08:14:30 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure 2026/07/27 08:14:38 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-vonecav4rixi" 2026/07/27 08:14:38 DEBUG : Linkbox root 'rclone-test-vonecav4rixi': Using cached web token sync_test.go:656: Server side copy (if possible) Linkbox root 'rclone-test-vulayuw7xejo' -> Linkbox root 'rclone-test-vonecav4rixi' 2026/07/27 08:14:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/27 08:14:40 DEBUG : Linkbox root 'rclone-test-vonecav4rixi': Waiting for checks to finish 2026/07/27 08:14:40 DEBUG : Linkbox root 'rclone-test-vonecav4rixi': Waiting for transfers to finish 2026/07/27 08:14:40 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1062h4m19.223327731s different from this computer 2026/07/27 08:14:45 DEBUG : sub dir/hello world: size = 11 OK 2026/07/27 08:14:45 INFO : sub dir/hello world: Copied (new) 2026/07/27 08:14:46 DEBUG : sub dir/hello world: Update: removing old file 2026/07/27 08:14:49 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/27 08:14:49 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/27 08:14:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/27 08:14:50 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 800ms (4/10) 2026/07/27 08:14:51 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/27 08:14:53 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/27 08:14:57 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/27 08:15:03 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/27 08:15:16 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/27 08:15:42 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 51.2s (10/10) run.go:303: Failed to put "sub dir/hello world" to "Linkbox root 'rclone-test-vulayuw7xejo'": object not found 2026/07/27 08:16:33 DEBUG : Linkbox root 'rclone-test-vonecav4rixi': Purge remote 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 --- FAIL: TestServerSideCopyOverSelf (137.04s) === RUN TestServerSideMoveOverSelf run.go:198: Remote "Linkbox root 'rclone-test-vulayuw7xejo'", Local "Local file system at /tmp/rclone1640231177", Modify Window "876000h0m0s" 2026/07/27 08:16:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-nirakud1kece" 2026/07/27 08:16:46 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Using cached web token sync_test.go:701: Server side copy (if possible) Linkbox root 'rclone-test-vulayuw7xejo' -> Linkbox root 'rclone-test-nirakud1kece' 2026/07/27 08:16:47 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2026/07/27 08:16:47 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for checks to finish 2026/07/27 08:16:47 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for transfers to finish 2026/07/27 08:16:52 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 100ms (1/10) 2026/07/27 08:16:53 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 200ms (2/10) 2026/07/27 08:16:53 DEBUG : sub dir/hello world: Trying to read object after upload: try again in 400ms (3/10) 2026/07/27 08:16:54 DEBUG : sub dir/hello world: size = 11 OK 2026/07/27 08:16:54 INFO : sub dir/hello world: Copied (new) 2026/07/27 08:16:54 DEBUG : sub dir/hello world: Update: removing old file 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/sync/sync_test.go:710 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) 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/fstest.go:350 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Should be true Test: TestServerSideMoveOverSelf Messages: Unexpected file "sub dir/hello world(1)" fstest.go:203: Not found "sub dir/hello world" 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:358 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:710 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Not equal: expected: 0 actual : 1 Test: TestServerSideMoveOverSelf Messages: 1 objects not found 2026/07/27 08:17:07 DEBUG : sub dir/hello world(1): Need to transfer - File not found at Destination 2026/07/27 08:17:07 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for checks to finish 2026/07/27 08:17:07 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for transfers to finish 2026/07/27 08:17:09 DEBUG : sub dir/hello world(1): size = 17 OK 2026/07/27 08:17:09 INFO : sub dir/hello world(1): Copied (new) 2026/07/27 08:17:11 INFO : sub dir/hello world(1): Deleted fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:292: Sleeping for 6s just to make sure fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache 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:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 Error: Should be true Test: TestServerSideMoveOverSelf Messages: listing wrong, want sub dir/hello world (17) got sub dir/hello world (11), sub dir/hello world(1) (17) fstest.go:150: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:150 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:195 /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:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 /usr/local/go/src/runtime/asm_amd64.s:1771 Error: Not equal: expected: 17 actual : 11 Test: TestServerSideMoveOverSelf Messages: sub dir/hello world: size incorrect file=17 vs obj=11 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:356 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:717 Error: Should be true Test: TestServerSideMoveOverSelf Messages: Unexpected file "sub dir/hello world(1)" 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/27 08:17:38 DEBUG : testing file moves 2026/07/27 08:17:39 DEBUG : sub dir/hello world: size = 24 (Linkbox root 'rclone-test-vulayuw7xejo') 2026/07/27 08:17:39 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-nirakud1kece') 2026/07/27 08:17:39 DEBUG : sub dir/hello world: Sizes differ 2026/07/27 08:17:39 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for checks to finish 2026/07/27 08:17:39 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Waiting for transfers to finish 2026/07/27 08:17:39 DEBUG : sub dir/hello world: Update: removing old file 2026/07/27 08:17:42 DEBUG : sub dir/hello world: size = 24 (Linkbox root 'rclone-test-vulayuw7xejo') 2026/07/27 08:17:42 DEBUG : sub dir/hello world: size = 11 (Linkbox root 'rclone-test-nirakud1kece') 2026/07/27 08:17:42 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-vulayuw7xejo') 24 vs dst(Linkbox root 'rclone-test-nirakud1kece') 11 2026/07/27 08:17:42 INFO : sub dir/hello world: Removing failed copy 2026/07/27 08:17:42 INFO : sub dir/hello world: Failed to remove failed copy: could not Remove: Linkbox error 403: not permit 2026/07/27 08:17:42 ERROR : sub dir/hello world: Not deleting source as copy failed: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-vulayuw7xejo') 24 vs dst(Linkbox root 'rclone-test-nirakud1kece') 11 sync_test.go:726: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:726 Error: Received unexpected error: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-vulayuw7xejo') 24 vs dst(Linkbox root 'rclone-test-nirakud1kece') 11 Test: TestServerSideMoveOverSelf 2026/07/27 08:17:42 DEBUG : Linkbox root 'rclone-test-nirakud1kece': Purge remote --- FAIL: TestServerSideMoveOverSelf (65.72s) === RUN TestSyncAfterChangingContentsOnly run.go:198: Remote "Linkbox root 'rclone-test-vulayuw7xejo'", Local "Local file system at /tmp/rclone1640231177", Modify Window "876000h0m0s" sync_test.go:1136: ModTimeNotSupported so forcing file to be a different size 2026/07/27 08:17:50 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1640231177) 2026/07/27 08:17:50 DEBUG : potato: size = 36 (Linkbox root 'rclone-test-vulayuw7xejo') 2026/07/27 08:17:50 DEBUG : Linkbox root 'rclone-test-vulayuw7xejo': Waiting for checks to finish 2026/07/27 08:17:50 DEBUG : potato: Sizes differ 2026/07/27 08:17:50 DEBUG : Linkbox root 'rclone-test-vulayuw7xejo': Waiting for transfers to finish 2026/07/27 08:17:50 DEBUG : potato: Update: removing old file 2026/07/27 08:17:53 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1640231177) 2026/07/27 08:17:53 DEBUG : potato: size = 36 (Linkbox root 'rclone-test-vulayuw7xejo') 2026/07/27 08:17:53 ERROR : potato: corrupted on transfer: sizes differ src(Local file system at /tmp/rclone1640231177) 21 vs dst(Linkbox root 'rclone-test-vulayuw7xejo') 36 2026/07/27 08:17:53 INFO : potato: Removing failed copy 2026/07/27 08:17:53 INFO : potato: Failed to remove failed copy: could not Remove: Linkbox error 403: not permit 2026/07/27 08:17:53 ERROR : Linkbox root 'rclone-test-vulayuw7xejo': not deleting files as there were IO errors 2026/07/27 08:17:53 ERROR : Linkbox root 'rclone-test-vulayuw7xejo': not deleting directories as there were IO errors sync_test.go:1148: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1148 Error: Received unexpected error: corrupted on transfer: sizes differ src(Local file system at /tmp/rclone1640231177) 21 vs dst(Linkbox root 'rclone-test-vulayuw7xejo') 36 Test: TestSyncAfterChangingContentsOnly run.go:172: removing file "potato" failed - try 1/3: sleeping 1s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato" failed - try 2/3: sleeping 1.5s: could not Remove: Linkbox error 403: not permit run.go:172: removing file "potato" failed: could not Remove: Linkbox error 403: not permit fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:301: Flushing the directory cache fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:301: Flushing the directory cache fstest.go:305: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:305 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:191 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 /usr/local/go/src/testing/testing.go:1317 /usr/local/go/src/testing/testing.go:1667 /usr/local/go/src/testing/testing.go:2030 /usr/local/go/src/runtime/panic.go:694 /usr/local/go/src/testing/testing.go:1022 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1148 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: listing wrong, want got potato (36) 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:1148 Error: Should be true Test: TestSyncAfterChangingContentsOnly Messages: Unexpected file "potato" --- FAIL: TestSyncAfterChangingContentsOnly (18.94s) FAIL 2026/07/27 08:18:06 DEBUG : Linkbox root 'rclone-test-vulayuw7xejo': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncAfterChangingContentsOnly)$'" - Finished ERROR in 3m43.986962186s (try 5/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf TestSyncAfterChangingContentsOnly]