"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncCompareDest)$'" - Starting (try 5/5) 2026/07/22 04:36:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-besaheh0jopa" 2026/07/22 04:36:46 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/07/22 04:36:46 DEBUG : Linkbox root 'rclone-test-besaheh0jopa': Using cached web token 2026/07/22 04:36:46 DEBUG : Creating backend with remote "/tmp/rclone4291377300" === RUN TestServerSideMove run.go:198: Remote "Linkbox root 'rclone-test-besaheh0jopa'", Local "Local file system at /tmp/rclone4291377300", Modify Window "876000h0m0s" 2026/07/22 04:36:46 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-lifaric0cavu" 2026/07/22 04:36:46 DEBUG : Linkbox root 'rclone-test-lifaric0cavu': Using cached web token 2026/07/22 04:36:51 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:36:51 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:36:52 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:36:52 DEBUG : potato2: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:36:58 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:36:58 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:36:58 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-besaheh0jopa' -> Linkbox root 'rclone-test-lifaric0cavu' 2026/07/22 04:37:03 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:37:03 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:37:04 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:37:04 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:37:08 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:37:08 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:37:08 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:37:09 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/22 04:37:09 DEBUG : empty space: size = 1 OK 2026/07/22 04:37:09 DEBUG : empty space: Sizes identical 2026/07/22 04:37:09 DEBUG : empty space: Unchanged skipping 2026/07/22 04:37:09 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-besaheh0jopa') 2026/07/22 04:37:09 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-lifaric0cavu') 2026/07/22 04:37:09 DEBUG : potato3: Sizes differ 2026/07/22 04:37:09 DEBUG : Linkbox root 'rclone-test-lifaric0cavu': Waiting for checks to finish 2026/07/22 04:37:09 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 938h18m24.919997885s different from this computer 2026/07/22 04:37:10 DEBUG : potato3: Update: removing old file 2026/07/22 04:37:11 INFO : empty space: Deleted 2026/07/22 04:37:11 DEBUG : Linkbox root 'rclone-test-lifaric0cavu': Waiting for transfers to finish 2026/07/22 04:37:12 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:37:12 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:37:12 DEBUG : potato2: size = 60 OK 2026/07/22 04:37:12 INFO : potato2: Copied (new) 2026/07/22 04:37:13 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:37:13 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:37:14 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:37:14 INFO : potato2: Deleted 2026/07/22 04:37:14 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:37:15 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/22 04:37:17 DEBUG : potato3: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/22 04:37:20 DEBUG : potato3: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/22 04:37:27 DEBUG : potato3: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/22 04:37:40 DEBUG : potato3: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/22 04:38:06 DEBUG : potato3: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/22 04:38:57 ERROR : potato3: Failed to copy: object not found 2026/07/22 04:38:57 ERROR : potato3: Not deleting source as copy failed: object not found sync_test.go:1747: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1912 Error: Received unexpected error: object not found Test: TestServerSideMove 2026/07/22 04:38:57 DEBUG : Linkbox root 'rclone-test-lifaric0cavu': Purge remote 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: TestServerSideMove (141.81s) === RUN TestServerSideMoveWithFilter run.go:198: Remote "Linkbox root 'rclone-test-besaheh0jopa'", Local "Local file system at /tmp/rclone4291377300", Modify Window "876000h0m0s" 2026/07/22 04:39:08 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-guhurib9teso" 2026/07/22 04:39:08 DEBUG : Linkbox root 'rclone-test-guhurib9teso': Using cached web token 2026/07/22 04:39:14 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:14 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:39:14 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:39:15 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10) sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-besaheh0jopa' -> Linkbox root 'rclone-test-guhurib9teso' 2026/07/22 04:39:22 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:22 DEBUG : empty space: 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/22 04:39:33 DEBUG : empty space: Excluded (Size Filter) 2026/07/22 04:39:33 DEBUG : empty space: Excluded 2026/07/22 04:39:33 DEBUG : empty space: Excluded (Size Filter) 2026/07/22 04:39:33 DEBUG : empty space: Excluded 2026/07/22 04:39:33 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/22 04:39:33 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-besaheh0jopa') 2026/07/22 04:39:33 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-guhurib9teso') 2026/07/22 04:39:33 DEBUG : potato3: Sizes differ 2026/07/22 04:39:33 DEBUG : Linkbox root 'rclone-test-guhurib9teso': Waiting for checks to finish 2026/07/22 04:39:33 DEBUG : Linkbox root 'rclone-test-guhurib9teso': Waiting for transfers to finish 2026/07/22 04:39:33 DEBUG : potato3: Update: removing old file 2026/07/22 04:39:35 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:35 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:39:36 DEBUG : potato2: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:39:36 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-besaheh0jopa') 2026/07/22 04:39:36 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-guhurib9teso') 2026/07/22 04:39:36 ERROR : potato3: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-besaheh0jopa') 68 vs dst(Linkbox root 'rclone-test-guhurib9teso') 60 2026/07/22 04:39:36 INFO : potato3: Removing failed copy 2026/07/22 04:39:36 DEBUG : potato2: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:39:37 INFO : potato3: Failed to remove failed copy: could not Remove: Linkbox error 403: not permit 2026/07/22 04:39:37 ERROR : potato3: Not deleting source as copy failed: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-besaheh0jopa') 68 vs dst(Linkbox root 'rclone-test-guhurib9teso') 60 2026/07/22 04:39:37 DEBUG : potato2: size = 60 OK 2026/07/22 04:39:37 INFO : potato2: Copied (new) 2026/07/22 04:39:39 INFO : potato2: Deleted sync_test.go:1747: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1747 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:1925 Error: Received unexpected error: corrupted on transfer: sizes differ src(Linkbox root 'rclone-test-besaheh0jopa') 68 vs dst(Linkbox root 'rclone-test-guhurib9teso') 60 Test: TestServerSideMoveWithFilter 2026/07/22 04:39:39 DEBUG : Linkbox root 'rclone-test-guhurib9teso': Purge remote --- FAIL: TestServerSideMoveWithFilter (35.07s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:198: Remote "Linkbox root 'rclone-test-besaheh0jopa'", Local "Local file system at /tmp/rclone4291377300", Modify Window "876000h0m0s" 2026/07/22 04:39:43 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-sinuwup0giyo" 2026/07/22 04:39:43 DEBUG : Linkbox root 'rclone-test-sinuwup0giyo': Using cached web token 2026/07/22 04:39:46 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:48 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:51 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:51 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:39:52 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:39:52 INFO : tomatoDir: Making directory sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-besaheh0jopa' -> Linkbox root 'rclone-test-sinuwup0giyo' 2026/07/22 04:39:59 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:39:59 DEBUG : empty space: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:40:00 DEBUG : empty space: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:40:00 DEBUG : empty space: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:40:03 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/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/22 04:40:12 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/22 04:40:12 DEBUG : empty space: size = 1 OK 2026/07/22 04:40:12 DEBUG : empty space: Sizes identical 2026/07/22 04:40:12 DEBUG : empty space: Unchanged skipping 2026/07/22 04:40:12 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-besaheh0jopa') 2026/07/22 04:40:12 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-sinuwup0giyo') 2026/07/22 04:40:12 DEBUG : potato3: Sizes differ 2026/07/22 04:40:12 DEBUG : Linkbox root 'rclone-test-sinuwup0giyo': Waiting for checks to finish 2026/07/22 04:40:12 DEBUG : potato3: Update: removing old file 2026/07/22 04:40:14 INFO : empty space: Deleted 2026/07/22 04:40:14 DEBUG : Linkbox root 'rclone-test-sinuwup0giyo': Waiting for transfers to finish 2026/07/22 04:40:14 DEBUG : potato2: size = 60 OK 2026/07/22 04:40:14 INFO : potato2: Copied (new) 2026/07/22 04:40:16 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:16 INFO : potato2: Deleted 2026/07/22 04:40:16 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:40:16 DEBUG : potato3: size = 68 OK 2026/07/22 04:40:16 INFO : potato3: Copied (replaced existing) 2026/07/22 04:40:18 INFO : potato3: Deleted 2026/07/22 04:40:18 INFO : tomatoDir: Removing directory 2026/07/22 04:40:19 DEBUG : Linkbox root 'rclone-test-besaheh0jopa': deleted 1 directories 2026/07/22 04:40:20 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-dazicit3yoda" 2026/07/22 04:40:20 DEBUG : Config file has changed externally - reloading 2026/07/22 04:40:20 DEBUG : Linkbox root 'rclone-test-dazicit3yoda': Using cached web token 2026/07/22 04:40:21 INFO : tomatoDir: Making directory 2026/07/22 04:40:22 DEBUG : empty space: Need to transfer - File not found at Destination 2026/07/22 04:40:22 DEBUG : potato2: Need to transfer - File not found at Destination 2026/07/22 04:40:22 DEBUG : potato3: Need to transfer - File not found at Destination 2026/07/22 04:40:23 DEBUG : Linkbox root 'rclone-test-dazicit3yoda': Waiting for checks to finish 2026/07/22 04:40:23 DEBUG : Linkbox root 'rclone-test-dazicit3yoda': Waiting for transfers to finish 2026/07/22 04:40:27 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:27 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:28 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:28 DEBUG : empty space: size = 1 OK 2026/07/22 04:40:28 INFO : empty space: Copied (new) 2026/07/22 04:40:28 DEBUG : potato2: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:40:28 DEBUG : potato3: size = 68 OK 2026/07/22 04:40:28 INFO : potato3: Copied (new) 2026/07/22 04:40:29 DEBUG : potato2: size = 60 OK 2026/07/22 04:40:29 INFO : potato2: Copied (new) 2026/07/22 04:40:29 INFO : empty space: Deleted 2026/07/22 04:40:30 INFO : potato3: Deleted 2026/07/22 04:40:30 INFO : potato2: Deleted 2026/07/22 04:40:30 INFO : tomatoDir: Removing directory 2026/07/22 04:40:31 DEBUG : Linkbox root 'rclone-test-sinuwup0giyo': deleted 1 directories 2026/07/22 04:40:32 DEBUG : Linkbox root 'rclone-test-dazicit3yoda': Purge remote 2026/07/22 04:40:34 DEBUG : Linkbox root 'rclone-test-sinuwup0giyo': Purge remote --- PASS: TestServerSideMoveDeleteEmptySourceDirs (52.29s) === RUN TestSyncCompareDest run.go:198: Remote "Linkbox root 'rclone-test-besaheh0jopa'", Local "Local file system at /tmp/rclone4291377300", Modify Window "876000h0m0s" 2026/07/22 04:40:36 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-besaheh0jopa/dst" 2026/07/22 04:40:36 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/dst': Using cached web token 2026/07/22 04:40:37 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-besaheh0jopa/CompareDest" 2026/07/22 04:40:37 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/CompareDest': Using cached web token 2026/07/22 04:40:38 DEBUG : one: Need to transfer - File not found at Destination 2026/07/22 04:40:38 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/dst': Waiting for checks to finish 2026/07/22 04:40:38 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/dst': Waiting for transfers to finish 2026/07/22 04:40:42 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:42 DEBUG : one: size = 3 OK 2026/07/22 04:40:42 INFO : one: Copied (new) 2026/07/22 04:40:42 DEBUG : Waiting for deletions to finish 2026/07/22 04:40:43 DEBUG : one: size = 5 (Local file system at /tmp/rclone4291377300) 2026/07/22 04:40:43 DEBUG : one: size = 3 (Linkbox root 'rclone-test-besaheh0jopa/dst') 2026/07/22 04:40:43 DEBUG : one: Sizes differ 2026/07/22 04:40:43 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/dst': Waiting for checks to finish 2026/07/22 04:40:43 DEBUG : Linkbox root 'rclone-test-besaheh0jopa/dst': Waiting for transfers to finish 2026/07/22 04:40:43 DEBUG : one: Update: removing old file 2026/07/22 04:40:47 DEBUG : one: Trying to read object after upload: try again in 100ms (1/10) 2026/07/22 04:40:47 DEBUG : one: Trying to read object after upload: try again in 200ms (2/10) 2026/07/22 04:40:47 DEBUG : one: Trying to read object after upload: try again in 400ms (3/10) 2026/07/22 04:40:48 DEBUG : one: Trying to read object after upload: try again in 800ms (4/10) 2026/07/22 04:40:49 DEBUG : one: Trying to read object after upload: try again in 1.6s (5/10) 2026/07/22 04:40:51 DEBUG : one: Trying to read object after upload: try again in 3.2s (6/10) 2026/07/22 04:40:54 DEBUG : one: Trying to read object after upload: try again in 6.4s (7/10) 2026/07/22 04:41:01 DEBUG : one: Trying to read object after upload: try again in 12.8s (8/10) 2026/07/22 04:41:14 DEBUG : one: Trying to read object after upload: try again in 25.6s (9/10) 2026/07/22 04:41:40 DEBUG : one: Trying to read object after upload: try again in 51.2s (10/10) 2026/07/22 04:42:31 ERROR : one: Failed to copy: object not found 2026/07/22 04:42:31 ERROR : Linkbox root 'rclone-test-besaheh0jopa/dst': not deleting files as there were IO errors 2026/07/22 04:42:31 ERROR : Linkbox root 'rclone-test-besaheh0jopa/dst': not deleting directories as there were IO errors sync_test.go:2132: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:2132 Error: Received unexpected error: object not found Test: TestSyncCompareDest --- FAIL: TestSyncCompareDest (119.41s) FAIL 2026/07/22 04:42:35 DEBUG : Linkbox root 'rclone-test-besaheh0jopa': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveDeleteEmptySourceDirs|TestServerSideMoveWithFilter|TestSyncCompareDest)$'" - Finished ERROR in 5m50.839933479s (try 5/5): exit status 1: Failed [TestServerSideMove TestServerSideMoveWithFilter TestSyncCompareDest]