"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Starting (try 3/5)
2025/07/14 03:22:13 DEBUG : Creating backend with remote "TestPikPak:rclone-test-vebajox3kacu"
2025/07/14 03:22:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/14 03:22:15 DEBUG : Creating backend with remote "/tmp/rclone3212096687"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-vebajox3kacu'", Local "Local file system at /tmp/rclone3212096687", Modify Window "876000h0m0s"
2025/07/14 03:22:17 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:22:17 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/14 03:22:19 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bedopih4haya"
    sync_test.go:656: Server side copy (if possible) PikPak root 'rclone-test-vebajox3kacu' -> PikPak root 'rclone-test-bedopih4haya'
2025/07/14 03:22:22 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/14 03:22:22 DEBUG : PikPak root 'rclone-test-bedopih4haya': Waiting for checks to finish
2025/07/14 03:22:22 DEBUG : PikPak root 'rclone-test-bedopih4haya': Waiting for transfers to finish
2025/07/14 03:22:24 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/14 03:22:24 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/14 03:22:24 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/14 03:22:25 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:22:25 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/14 03:22:30 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/14 03:22:30 DEBUG : PikPak root 'rclone-test-bedopih4haya': Waiting for checks to finish
2025/07/14 03:22:30 DEBUG : PikPak root 'rclone-test-bedopih4haya': Waiting for transfers to finish
2025/07/14 03:22:32 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-vebajox3kacu') 17 vs dst(PikPak root 'rclone-test-bedopih4haya') 11
2025/07/14 03:22:32 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-vebajox3kacu') 17 vs dst(PikPak root 'rclone-test-bedopih4haya') 11
        	Test:       	TestServerSideCopyOverSelf
2025/07/14 03:22:33 DEBUG : PikPak root 'rclone-test-bedopih4haya': Purge remote
2025/07/14 03:22:34 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/07/14 03:22:34 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/07/14 03:22:34 DEBUG : pacer: Reducing sleep to 150ms
2025/07/14 03:22:37 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
2025/07/14 03:22:37 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideCopyOverSelf (22.98s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-vebajox3kacu'", Local "Local file system at /tmp/rclone3212096687", Modify Window "876000h0m0s"
2025/07/14 03:22:39 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:22:39 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/14 03:22:40 DEBUG : Creating backend with remote "TestPikPak:rclone-test-cefegap9tumo"
2025/07/14 03:22:40 DEBUG : Config file has changed externally - reloading
    sync_test.go:701: Server side copy (if possible) PikPak root 'rclone-test-vebajox3kacu' -> PikPak root 'rclone-test-cefegap9tumo'
2025/07/14 03:22:43 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/14 03:22:43 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for checks to finish
2025/07/14 03:22:43 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for transfers to finish
2025/07/14 03:22:46 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/14 03:22:46 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/14 03:22:46 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/14 03:22:47 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:22:47 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/14 03:22:50 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Using server-side directory move
2025/07/14 03:22:50 INFO  : PikPak root 'rclone-test-cefegap9tumo': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/07/14 03:22:50 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/14 03:22:50 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for checks to finish
2025/07/14 03:22:50 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for transfers to finish
2025/07/14 03:22:52 INFO  : sub dir/hello world: Deleted
2025/07/14 03:22:53 INFO  : sub dir/hello world: Moved (server-side)
2025/07/14 03:22:55 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/14 03:22:55 DEBUG : sub dir/hello world: gcid = 56e41ddbaec00ad6eb8e58ba6df699946a8e84e2
2025/07/14 03:22:56 DEBUG : testing file moves
2025/07/14 03:22:57 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for checks to finish
2025/07/14 03:22:57 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/07/14 03:22:57 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Waiting for transfers to finish
2025/07/14 03:22:58 INFO  : sub dir/hello world: Deleted
2025/07/14 03:22:59 INFO  : sub dir/hello world: Moved (server-side)
Diff of "logger" and "lsf"
2c2
< ;;24;sub dir/hello world
---
> 2025-07-14 03:22:59;;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:22:59;;24;sub dir/hello world
        	Test:       	TestServerSideMoveOverSelf
2025/07/14 03:23:00 DEBUG : PikPak root 'rclone-test-cefegap9tumo': Purge remote
2025/07/14 03:23:03 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideMoveOverSelf (26.20s)
FAIL
2025/07/14 03:23:04 DEBUG : PikPak root 'rclone-test-vebajox3kacu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Finished ERROR in 52.166365545s (try 3/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf]