"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Starting (try 2/5)
2025/07/14 03:19:58 DEBUG : Creating backend with remote "TestPikPak:rclone-test-hixecug0tuwi"
2025/07/14 03:19:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/14 03:20:00 DEBUG : Creating backend with remote "/tmp/rclone2767475997"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-hixecug0tuwi'", Local "Local file system at /tmp/rclone2767475997", Modify Window "876000h0m0s"
2025/07/14 03:20:02 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:20:02 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/14 03:20:04 DEBUG : Creating backend with remote "TestPikPak:rclone-test-mosirad5susu"
    sync_test.go:656: Server side copy (if possible) PikPak root 'rclone-test-hixecug0tuwi' -> PikPak root 'rclone-test-mosirad5susu'
2025/07/14 03:20:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/14 03:20:06 DEBUG : PikPak root 'rclone-test-mosirad5susu': Waiting for checks to finish
2025/07/14 03:20:06 DEBUG : PikPak root 'rclone-test-mosirad5susu': Waiting for transfers to finish
2025/07/14 03:20:09 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/14 03:20:09 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/14 03:20:09 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/14 03:20:10 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:20:10 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/14 03:20:15 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/14 03:20:15 DEBUG : PikPak root 'rclone-test-mosirad5susu': Waiting for checks to finish
2025/07/14 03:20:15 DEBUG : PikPak root 'rclone-test-mosirad5susu': Waiting for transfers to finish
2025/07/14 03:20:17 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-hixecug0tuwi') 17 vs dst(PikPak root 'rclone-test-mosirad5susu') 11
2025/07/14 03:20:17 INFO  : sub dir/hello world: Removing failed copy
    sync_test.go:669: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:669
        	Error:      	Received unexpected error:
        	            	corrupted on transfer: sizes differ src(PikPak root 'rclone-test-hixecug0tuwi') 17 vs dst(PikPak root 'rclone-test-mosirad5susu') 11
        	Test:       	TestServerSideCopyOverSelf
2025/07/14 03:20:18 DEBUG : PikPak root 'rclone-test-mosirad5susu': Purge remote
2025/07/14 03:20:22 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
2025/07/14 03:20:22 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideCopyOverSelf (23.16s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-hixecug0tuwi'", Local "Local file system at /tmp/rclone2767475997", Modify Window "876000h0m0s"
2025/07/14 03:20:24 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:20:24 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/14 03:20:25 DEBUG : Creating backend with remote "TestPikPak:rclone-test-xumucet3hose"
    sync_test.go:701: Server side copy (if possible) PikPak root 'rclone-test-hixecug0tuwi' -> PikPak root 'rclone-test-xumucet3hose'
2025/07/14 03:20:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/14 03:20:28 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for checks to finish
2025/07/14 03:20:28 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for transfers to finish
2025/07/14 03:20:31 DEBUG : pacer: low level retry 1/10 (error copy (copy) is still in PHASE_TYPE_RUNNING)
2025/07/14 03:20:31 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/07/14 03:20:32 DEBUG : pacer: Reducing sleep to 150ms
2025/07/14 03:20:32 DEBUG : pacer: Reducing sleep to 112.5ms
2025/07/14 03:20:32 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/14 03:20:32 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/14 03:20:32 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/14 03:20:33 DEBUG : pacer: Reducing sleep to 100ms
2025/07/14 03:20:33 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:20:33 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/14 03:20:36 DEBUG : PikPak root 'rclone-test-xumucet3hose': Using server-side directory move
2025/07/14 03:20:36 INFO  : PikPak root 'rclone-test-xumucet3hose': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/07/14 03:20:37 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for checks to finish
2025/07/14 03:20:37 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/14 03:20:37 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for transfers to finish
2025/07/14 03:20:38 INFO  : sub dir/hello world: Deleted
2025/07/14 03:20:39 INFO  : sub dir/hello world: Moved (server-side)
2025/07/14 03:20:41 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:20:41 DEBUG : sub dir/hello world: gcid = 56e41ddbaec00ad6eb8e58ba6df699946a8e84e2
2025/07/14 03:20:43 DEBUG : testing file moves
2025/07/14 03:20:44 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/07/14 03:20:44 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for checks to finish
2025/07/14 03:20:44 DEBUG : PikPak root 'rclone-test-xumucet3hose': Waiting for transfers to finish
2025/07/14 03:20:45 INFO  : sub dir/hello world: Deleted
2025/07/14 03:20:46 INFO  : sub dir/hello world: Moved (server-side)
Diff of "logger" and "lsf"
2c2
< ;;24;sub dir/hello world
---
> 2025-07-14 03:20:45;;24;sub dir/hello world
    sync_test.go:3101: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:3101
        	            				/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:727
        	Error:      	Received unexpected error:
        	            	logger does not match lsf! 
        	            	logger: 
        	            	
        	            	;;24;sub dir/hello world 
        	            	lsf: 
        	            	
        	            	2025-07-14 03:20:45;;24;sub dir/hello world
        	Test:       	TestServerSideMoveOverSelf
2025/07/14 03:20:47 DEBUG : PikPak root 'rclone-test-xumucet3hose': Purge remote
2025/07/14 03:20:49 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideMoveOverSelf (27.61s)
FAIL
2025/07/14 03:20:51 DEBUG : PikPak root 'rclone-test-hixecug0tuwi': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Finished ERROR in 54.505429928s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf]