"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter|TestSyncAfterChangingFilesSizeOnly)$'" - Starting (try 3/5)
2026/08/01 05:58:55 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-pozemot2xudu"
2026/08/01 05:58:55 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/08/01 05:58:55 DEBUG : Linkbox root 'rclone-test-pozemot2xudu': Using cached web token
2026/08/01 05:58:56 DEBUG : Creating backend with remote "/tmp/rclone1232835920"
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:198: Remote "Linkbox root 'rclone-test-pozemot2xudu'", Local "Local file system at /tmp/rclone1232835920", Modify Window "876000h0m0s"
2026/08/01 05:59:00 DEBUG : potato: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:01 DEBUG : potato: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 05:59:02 DEBUG : Linkbox root 'rclone-test-pozemot2xudu': Waiting for checks to finish
2026/08/01 05:59:02 DEBUG : potato: size = 21 (Local file system at /tmp/rclone1232835920)
2026/08/01 05:59:02 DEBUG : potato: size = 60 (Linkbox root 'rclone-test-pozemot2xudu')
2026/08/01 05:59:02 DEBUG : potato: Sizes differ
2026/08/01 05:59:02 DEBUG : Linkbox root 'rclone-test-pozemot2xudu': Waiting for transfers to finish
2026/08/01 05:59:02 DEBUG : potato: Update: removing old file
2026/08/01 05:59:05 DEBUG : potato: size = 21 OK
2026/08/01 05:59:05 INFO  : potato: Copied (replaced existing)
2026/08/01 05:59:05 DEBUG : Waiting for deletions to finish
    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: TestSyncAfterChangingFilesSizeOnly (18.99s)
=== RUN   TestServerSideMove
    run.go:198: Remote "Linkbox root 'rclone-test-pozemot2xudu'", Local "Local file system at /tmp/rclone1232835920", Modify Window "876000h0m0s"
2026/08/01 05:59:15 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-numicix7piwu"
2026/08/01 05:59:15 DEBUG : Linkbox root 'rclone-test-numicix7piwu': Using cached web token
2026/08/01 05:59:18 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:21 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:23 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
    sync_test.go:1736: Server side move (if possible) Linkbox root 'rclone-test-pozemot2xudu' -> Linkbox root 'rclone-test-numicix7piwu'
2026/08/01 05:59:30 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:31 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/01 05:59:31 DEBUG : Linkbox root 'rclone-test-numicix7piwu': Waiting for checks to finish
2026/08/01 05:59:31 DEBUG : empty space: size = 1 OK
2026/08/01 05:59:31 DEBUG : empty space: Sizes identical
2026/08/01 05:59:31 DEBUG : empty space: Unchanged skipping
2026/08/01 05:59:31 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-pozemot2xudu')
2026/08/01 05:59:31 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-numicix7piwu')
2026/08/01 05:59:31 DEBUG : potato3: Sizes differ
2026/08/01 05:59:31 NOTICE: Time may be set wrong - time from "aht.nuplink3.net" is 1202h49m54.692967996s different from this computer
2026/08/01 05:59:31 DEBUG : potato3: Update: removing old file
2026/08/01 05:59:32 INFO  : empty space: Deleted
2026/08/01 05:59:32 DEBUG : Linkbox root 'rclone-test-numicix7piwu': Waiting for transfers to finish
2026/08/01 05:59:34 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:34 DEBUG : potato2: size = 60 OK
2026/08/01 05:59:34 INFO  : potato2: Copied (new)
2026/08/01 05:59:35 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 05:59:35 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 05:59:35 INFO  : potato2: Deleted
2026/08/01 05:59:35 DEBUG : potato3: Trying to read object after upload: try again in 400ms (3/10)
2026/08/01 05:59:36 DEBUG : potato3: Trying to read object after upload: try again in 800ms (4/10)
2026/08/01 05:59:37 DEBUG : potato3: Trying to read object after upload: try again in 1.6s (5/10)
2026/08/01 05:59:39 DEBUG : potato3: Trying to read object after upload: try again in 3.2s (6/10)
2026/08/01 05:59:42 DEBUG : potato3: Trying to read object after upload: try again in 6.4s (7/10)
2026/08/01 05:59:49 DEBUG : potato3: Trying to read object after upload: try again in 12.8s (8/10)
2026/08/01 06:00:02 DEBUG : potato3: Trying to read object after upload: try again in 25.6s (9/10)
2026/08/01 06:00:28 DEBUG : potato3: Trying to read object after upload: try again in 51.2s (10/10)
2026/08/01 06:01:19 ERROR : potato3: Failed to copy: object not found
2026/08/01 06:01:19 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/08/01 06:01:19 DEBUG : Linkbox root 'rclone-test-numicix7piwu': Purge remote
--- FAIL: TestServerSideMove (127.66s)
=== RUN   TestServerSideMoveWithFilter
    run.go:198: Remote "Linkbox root 'rclone-test-pozemot2xudu'", Local "Local file system at /tmp/rclone1232835920", Modify Window "876000h0m0s"
2026/08/01 06:01:23 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-tevogig7gahe"
2026/08/01 06:01:23 DEBUG : Linkbox root 'rclone-test-tevogig7gahe': Using cached web token
2026/08/01 06:01:28 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:31 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:31 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 06:01:32 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-pozemot2xudu' -> Linkbox root 'rclone-test-tevogig7gahe'
2026/08/01 06:01:36 DEBUG : empty space: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:39 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:39 DEBUG : potato3: Trying to read object after upload: try again in 200ms (2/10)
2026/08/01 06:01:40 DEBUG : empty space: Excluded (Size Filter)
2026/08/01 06:01:40 DEBUG : empty space: Excluded
2026/08/01 06:01:40 DEBUG : empty space: Excluded (Size Filter)
2026/08/01 06:01:40 DEBUG : empty space: Excluded
2026/08/01 06:01:40 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/01 06:01:40 DEBUG : potato3: size = 68 (Linkbox root 'rclone-test-pozemot2xudu')
2026/08/01 06:01:40 DEBUG : potato3: size = 60 (Linkbox root 'rclone-test-tevogig7gahe')
2026/08/01 06:01:40 DEBUG : potato3: Sizes differ
2026/08/01 06:01:40 DEBUG : Linkbox root 'rclone-test-tevogig7gahe': Waiting for checks to finish
2026/08/01 06:01:40 DEBUG : Linkbox root 'rclone-test-tevogig7gahe': Waiting for transfers to finish
2026/08/01 06:01:41 DEBUG : potato3: Update: removing old file
2026/08/01 06:01:43 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:43 DEBUG : potato2: size = 60 OK
2026/08/01 06:01:43 INFO  : potato2: Copied (new)
2026/08/01 06:01:44 DEBUG : potato3: size = 68 OK
2026/08/01 06:01:44 INFO  : potato3: Copied (replaced existing)
2026/08/01 06:01:44 INFO  : potato2: Deleted
2026/08/01 06:01:45 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/01 06:01:53 DEBUG : Creating backend with remote "TestLinkbox:rclone-test-laheqan7rofo"
2026/08/01 06:01:53 DEBUG : Linkbox root 'rclone-test-laheqan7rofo': Using cached web token
2026/08/01 06:01:54 DEBUG : empty space: Excluded (Size Filter)
2026/08/01 06:01:54 DEBUG : empty space: Excluded
2026/08/01 06:01:55 DEBUG : potato2: Need to transfer - File not found at Destination
2026/08/01 06:01:55 DEBUG : potato3: Need to transfer - File not found at Destination
2026/08/01 06:01:55 DEBUG : Linkbox root 'rclone-test-laheqan7rofo': Waiting for checks to finish
2026/08/01 06:01:55 DEBUG : Linkbox root 'rclone-test-laheqan7rofo': Waiting for transfers to finish
2026/08/01 06:01:59 DEBUG : potato2: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:59 DEBUG : potato3: Trying to read object after upload: try again in 100ms (1/10)
2026/08/01 06:01:59 DEBUG : potato2: size = 60 OK
2026/08/01 06:01:59 INFO  : potato2: Copied (new)
2026/08/01 06:01:59 DEBUG : potato3: size = 68 OK
2026/08/01 06:01:59 INFO  : potato3: Copied (new)
2026/08/01 06:02:01 INFO  : potato2: Deleted
2026/08/01 06:02:01 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/01 06:02:09 DEBUG : Linkbox root 'rclone-test-laheqan7rofo': Purge remote
2026/08/01 06:02:10 DEBUG : Linkbox root 'rclone-test-tevogig7gahe': 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
--- PASS: TestServerSideMoveWithFilter (58.06s)
FAIL
2026/08/01 06:02:21 DEBUG : Linkbox root 'rclone-test-pozemot2xudu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestLinkbox: -verbose -test.run '^(TestServerSideMove|TestServerSideMoveWithFilter|TestSyncAfterChangingFilesSizeOnly)$'" - Finished ERROR in 3m27.184062432s (try 3/5): exit status 1: Failed [TestServerSideMove]