"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Starting (try 2/5)
2025/07/08 04:49:33 DEBUG : Creating backend with remote "TestPikPak:rclone-test-jidiwud1nuri"
2025/07/08 04:49:33 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/07/08 04:49:35 DEBUG : Creating backend with remote "/tmp/rclone3772249481"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-jidiwud1nuri'", Local "Local file system at /tmp/rclone3772249481", Modify Window "876000h0m0s"
2025/07/08 04:49:36 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/08 04:49:36 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/08 04:49:37 DEBUG : Creating backend with remote "TestPikPak:rclone-test-qoyemis6quxe"
    sync_test.go:656: Server side copy (if possible) PikPak root 'rclone-test-jidiwud1nuri' -> PikPak root 'rclone-test-qoyemis6quxe'
2025/07/08 04:49:40 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/08 04:49:40 DEBUG : PikPak root 'rclone-test-qoyemis6quxe': Waiting for checks to finish
2025/07/08 04:49:40 DEBUG : PikPak root 'rclone-test-qoyemis6quxe': Waiting for transfers to finish
2025/07/08 04:49:43 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/08 04:49:43 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/08 04:49:43 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/08 04:49:44 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/08 04:49:44 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/08 04:49:47 DEBUG : PikPak root 'rclone-test-qoyemis6quxe': Waiting for checks to finish
2025/07/08 04:49:47 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/08 04:49:47 DEBUG : PikPak root 'rclone-test-qoyemis6quxe': Waiting for transfers to finish
2025/07/08 04:49:48 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-jidiwud1nuri') 17 vs dst(PikPak root 'rclone-test-qoyemis6quxe') 11
2025/07/08 04:49:48 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-jidiwud1nuri') 17 vs dst(PikPak root 'rclone-test-qoyemis6quxe') 11
        	Test:       	TestServerSideCopyOverSelf
2025/07/08 04:49:49 DEBUG : PikPak root 'rclone-test-qoyemis6quxe': Purge remote
2025/07/08 04:49:53 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
2025/07/08 04:49:53 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideCopyOverSelf (19.26s)
=== RUN   TestServerSideMoveOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-jidiwud1nuri'", Local "Local file system at /tmp/rclone3772249481", Modify Window "876000h0m0s"
2025/07/08 04:49:55 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/08 04:49:55 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/07/08 04:49:56 DEBUG : Creating backend with remote "TestPikPak:rclone-test-teqisot4pute"
    sync_test.go:701: Server side copy (if possible) PikPak root 'rclone-test-jidiwud1nuri' -> PikPak root 'rclone-test-teqisot4pute'
2025/07/08 04:49:58 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/07/08 04:49:58 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for checks to finish
2025/07/08 04:49:58 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for transfers to finish
2025/07/08 04:50:01 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/07/08 04:50:01 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/07/08 04:50:01 INFO  : sub dir/hello world: Copied (server-side copy)
2025/07/08 04:50:01 DEBUG : TestPikPak: Loaded invalid token from config file - ignoring
2025/07/08 04:50:03 DEBUG : TestPikPak: got fatal oauth error: oauth2: "invalid_grant" "invalid refresh token for it may be has been refreshed by other process, more info redis: nil, RefreshToken os.MgaDMQTx2mhsh2MYNcGoQF41iWRrqN4etekIu2rAtJJlxJo1yJfdel2k"
2025/07/08 04:50:05 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file
2025/07/08 04:50:06 DEBUG : Saving config "token" in section "TestPikPak" of the config file
2025/07/08 04:50:06 DEBUG : TestPikPak: Saved new token in config file
2025/07/08 04:50:06 DEBUG : pacer: low level retry 1/10 (error Get "https://api-drive.mypikpak.com/drive/v1/files?filters=%7B%22phase%22%3A%7B%22eq%22%3A%22PHASE_TYPE_COMPLETE%22%7D%2C%22trashed%22%3A%7B%22eq%22%3Afalse%7D%7D&limit=500&page_token=&parent_id=VOUcMcnE4amX0WJCaut9aF3mo2&thumbnail_size=SIZE_MEDIUM&with_audit=true": couldn't fetch token: invalid_grant: maybe token expired? - try refreshing with "rclone config reconnect TestPikPak:")
2025/07/08 04:50:06 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/07/08 04:50:07 DEBUG : pacer: low level retry 2/10 (error Error "captcha_invalid" (9): Verification code is invalid)
2025/07/08 04:50:07 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/07/08 04:50:08 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file
2025/07/08 04:50:08 DEBUG : pacer: Reducing sleep to 300ms
2025/07/08 04:50:08 DEBUG : TestPikPak: Loaded fresh token from config file
2025/07/08 04:50:08 DEBUG : TestPikPak: Loaded new refresh token from config file
2025/07/08 04:50:09 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/08 04:50:09 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/07/08 04:50:11 DEBUG : PikPak root 'rclone-test-teqisot4pute': Using server-side directory move
2025/07/08 04:50:11 INFO  : PikPak root 'rclone-test-teqisot4pute': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2025/07/08 04:50:12 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/07/08 04:50:12 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for checks to finish
2025/07/08 04:50:12 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for transfers to finish
2025/07/08 04:50:13 INFO  : sub dir/hello world: Deleted
2025/07/08 04:50:14 INFO  : sub dir/hello world: Moved (server-side)
2025/07/08 04:50:16 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/07/08 04:50:16 DEBUG : sub dir/hello world: gcid = 56e41ddbaec00ad6eb8e58ba6df699946a8e84e2
2025/07/08 04:50:17 DEBUG : testing file moves
2025/07/08 04:50:17 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for checks to finish
2025/07/08 04:50:17 DEBUG : sub dir/hello world: Sizes differ (src 24 vs dst 17)
2025/07/08 04:50:17 DEBUG : PikPak root 'rclone-test-teqisot4pute': Waiting for transfers to finish
2025/07/08 04:50:19 INFO  : sub dir/hello world: Deleted
2025/07/08 04:50:20 INFO  : sub dir/hello world: Moved (server-side)
Diff of "logger" and "lsf"
2c2
< ;;24;sub dir/hello world
---
> 2025-07-08 04:50:19;;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-08 04:50:19;;24;sub dir/hello world
        	Test:       	TestServerSideMoveOverSelf
2025/07/08 04:50:20 DEBUG : PikPak root 'rclone-test-teqisot4pute': Purge remote
2025/07/08 04:50:22 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
--- FAIL: TestServerSideMoveOverSelf (29.78s)
FAIL
2025/07/08 04:50:24 DEBUG : PikPak root 'rclone-test-jidiwud1nuri': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestServerSideMoveOverSelf)$'" - Finished ERROR in 51.846450803s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf]