"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncConcurrentDelete)$'" - Starting (try 2/5)
2025/04/12 05:31:56 DEBUG : Creating backend with remote "TestPikPak:rclone-test-tibehuv2fohu"
2025/04/12 05:31:56 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/04/12 05:31:57 DEBUG : Creating backend with remote "/tmp/rclone1585209125"
=== RUN   TestServerSideCopyOverSelf
    run.go:180: Remote "PikPak root 'rclone-test-tibehuv2fohu'", Local "Local file system at /tmp/rclone1585209125", Modify Window "876000h0m0s"
2025/04/12 05:31:59 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/04/12 05:31:59 DEBUG : sub dir/hello world: gcid = 67becf85308acf0261750da1075681ee5c412f05
2025/04/12 05:32:00 DEBUG : Creating backend with remote "TestPikPak:rclone-test-povapin1toma"
    sync_test.go:626: Server side copy (if possible) PikPak root 'rclone-test-tibehuv2fohu' -> PikPak root 'rclone-test-povapin1toma'
2025/04/12 05:32:01 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination
2025/04/12 05:32:01 DEBUG : PikPak root 'rclone-test-povapin1toma': Waiting for checks to finish
2025/04/12 05:32:01 DEBUG : PikPak root 'rclone-test-povapin1toma': Waiting for transfers to finish
2025/04/12 05:32:04 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check
2025/04/12 05:32:04 DEBUG : sub dir/hello world: Src hash empty - aborting Dst hash check
2025/04/12 05:32:04 INFO  : sub dir/hello world: Copied (server-side copy)
    run.go:180: Remote "PikPak root 'rclone-test-tibehuv2fohu'", Local "Local file system at /tmp/rclone1585209125", Modify Window "876000h0m0s"
2025/04/12 05:32:05 DEBUG : sub dir/hello world: calculating gcid: failed to unwrap object from src: sub dir/hello world
2025/04/12 05:32:05 DEBUG : sub dir/hello world: gcid = a1d34f87709d3556f7abea2f38b387d82a25fa1b
2025/04/12 05:32:08 DEBUG : PikPak root 'rclone-test-povapin1toma': Waiting for checks to finish
2025/04/12 05:32:08 DEBUG : sub dir/hello world: Sizes differ (src 17 vs dst 11)
2025/04/12 05:32:08 DEBUG : PikPak root 'rclone-test-povapin1toma': Waiting for transfers to finish
2025/04/12 05:32:10 ERROR : sub dir/hello world: corrupted on transfer: sizes differ src(PikPak root 'rclone-test-tibehuv2fohu') 17 vs dst(PikPak root 'rclone-test-povapin1toma') 11
2025/04/12 05:32:10 INFO  : sub dir/hello world: Removing failed copy
    sync_test.go:639: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:639
        	Error:      	Received unexpected error:
        	            	corrupted on transfer: sizes differ src(PikPak root 'rclone-test-tibehuv2fohu') 17 vs dst(PikPak root 'rclone-test-povapin1toma') 11
        	Test:       	TestServerSideCopyOverSelf
2025/04/12 05:32:11 DEBUG : PikPak root 'rclone-test-povapin1toma': Purge remote
2025/04/12 05:32:14 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
2025/04/12 05:32:14 DEBUG : sub dir: Rmdir: contains trashed file: "hello world"
2025/04/12 05:32:15 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:32:15 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/04/12 05:32:15 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:32:15 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/04/12 05:32:15 DEBUG : pacer: Reducing sleep to 300ms
2025/04/12 05:32:16 DEBUG : pacer: Reducing sleep to 225ms
2025/04/12 05:32:16 DEBUG : pacer: Reducing sleep to 168.75ms
2025/04/12 05:32:16 DEBUG : pacer: Reducing sleep to 126.5625ms
--- FAIL: TestServerSideCopyOverSelf (19.58s)
=== RUN   TestSyncConcurrentDelete
    run.go:180: Remote "PikPak root 'rclone-test-tibehuv2fohu'", Local "Local file system at /tmp/rclone1585209125", Modify Window "876000h0m0s"
2025/04/12 05:32:17 DEBUG : pacer: Reducing sleep to 100ms
2025/04/12 05:32:17 DEBUG : both0: calculating gcid: failed to unwrap object from src: both0
2025/04/12 05:32:17 DEBUG : both0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:18 DEBUG : only0: calculating gcid: failed to unwrap object from src: only0
2025/04/12 05:32:18 DEBUG : only0: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:19 DEBUG : both1: calculating gcid: failed to unwrap object from src: both1
2025/04/12 05:32:19 DEBUG : both1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:20 DEBUG : only1: calculating gcid: failed to unwrap object from src: only1
2025/04/12 05:32:20 DEBUG : only1: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:21 DEBUG : both2: calculating gcid: failed to unwrap object from src: both2
2025/04/12 05:32:21 DEBUG : both2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:22 DEBUG : only2: calculating gcid: failed to unwrap object from src: only2
2025/04/12 05:32:22 DEBUG : only2: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:24 DEBUG : both3: calculating gcid: failed to unwrap object from src: both3
2025/04/12 05:32:24 DEBUG : both3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:25 DEBUG : only3: calculating gcid: failed to unwrap object from src: only3
2025/04/12 05:32:25 DEBUG : only3: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:25 DEBUG : both4: calculating gcid: failed to unwrap object from src: both4
2025/04/12 05:32:25 DEBUG : both4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:26 DEBUG : only4: calculating gcid: failed to unwrap object from src: only4
2025/04/12 05:32:26 DEBUG : only4: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:27 DEBUG : both5: calculating gcid: failed to unwrap object from src: both5
2025/04/12 05:32:27 DEBUG : both5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:28 DEBUG : only5: calculating gcid: failed to unwrap object from src: only5
2025/04/12 05:32:28 DEBUG : only5: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:30 DEBUG : both6: calculating gcid: failed to unwrap object from src: both6
2025/04/12 05:32:30 DEBUG : both6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:30 DEBUG : only6: calculating gcid: failed to unwrap object from src: only6
2025/04/12 05:32:30 DEBUG : only6: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:31 DEBUG : both7: calculating gcid: failed to unwrap object from src: both7
2025/04/12 05:32:31 DEBUG : both7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:32 DEBUG : only7: calculating gcid: failed to unwrap object from src: only7
2025/04/12 05:32:32 DEBUG : only7: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:33 DEBUG : both8: calculating gcid: failed to unwrap object from src: both8
2025/04/12 05:32:33 DEBUG : both8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:35 DEBUG : only8: calculating gcid: failed to unwrap object from src: only8
2025/04/12 05:32:35 DEBUG : only8: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:35 DEBUG : both9: calculating gcid: failed to unwrap object from src: both9
2025/04/12 05:32:35 DEBUG : both9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:38 DEBUG : only9: calculating gcid: failed to unwrap object from src: only9
2025/04/12 05:32:38 DEBUG : only9: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:39 DEBUG : both10: calculating gcid: failed to unwrap object from src: both10
2025/04/12 05:32:39 DEBUG : both10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:41 DEBUG : only10: calculating gcid: failed to unwrap object from src: only10
2025/04/12 05:32:41 DEBUG : only10: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:43 DEBUG : both11: calculating gcid: failed to unwrap object from src: both11
2025/04/12 05:32:43 DEBUG : both11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:44 DEBUG : only11: calculating gcid: failed to unwrap object from src: only11
2025/04/12 05:32:44 DEBUG : only11: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:44 DEBUG : both12: calculating gcid: failed to unwrap object from src: both12
2025/04/12 05:32:44 DEBUG : both12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:45 DEBUG : only12: calculating gcid: failed to unwrap object from src: only12
2025/04/12 05:32:45 DEBUG : only12: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:46 DEBUG : both13: calculating gcid: failed to unwrap object from src: both13
2025/04/12 05:32:46 DEBUG : both13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:47 DEBUG : only13: calculating gcid: failed to unwrap object from src: only13
2025/04/12 05:32:47 DEBUG : only13: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:48 DEBUG : both14: calculating gcid: failed to unwrap object from src: both14
2025/04/12 05:32:48 DEBUG : both14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:50 DEBUG : only14: calculating gcid: failed to unwrap object from src: only14
2025/04/12 05:32:50 DEBUG : only14: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:51 DEBUG : both15: calculating gcid: failed to unwrap object from src: both15
2025/04/12 05:32:51 DEBUG : both15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:52 DEBUG : only15: calculating gcid: failed to unwrap object from src: only15
2025/04/12 05:32:52 DEBUG : only15: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:53 DEBUG : both16: calculating gcid: failed to unwrap object from src: both16
2025/04/12 05:32:53 DEBUG : both16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:54 DEBUG : only16: calculating gcid: failed to unwrap object from src: only16
2025/04/12 05:32:54 DEBUG : only16: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:55 DEBUG : both17: calculating gcid: failed to unwrap object from src: both17
2025/04/12 05:32:55 DEBUG : both17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:56 DEBUG : only17: calculating gcid: failed to unwrap object from src: only17
2025/04/12 05:32:56 DEBUG : only17: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:57 DEBUG : both18: calculating gcid: failed to unwrap object from src: both18
2025/04/12 05:32:57 DEBUG : both18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:58 DEBUG : only18: calculating gcid: failed to unwrap object from src: only18
2025/04/12 05:32:58 DEBUG : only18: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:32:59 DEBUG : both19: calculating gcid: failed to unwrap object from src: both19
2025/04/12 05:32:59 DEBUG : both19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:33:00 DEBUG : only19: calculating gcid: failed to unwrap object from src: only19
2025/04/12 05:33:00 DEBUG : only19: gcid = 2a54a2dadf85005832b477a2cf6b042fbe28d421
2025/04/12 05:33:02 DEBUG : both0: Sizes identical
2025/04/12 05:33:02 DEBUG : both0: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both1: Sizes identical
2025/04/12 05:33:02 DEBUG : both1: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both10: Sizes identical
2025/04/12 05:33:02 DEBUG : both10: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both11: Sizes identical
2025/04/12 05:33:02 DEBUG : both11: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both12: Sizes identical
2025/04/12 05:33:02 DEBUG : both12: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both13: Sizes identical
2025/04/12 05:33:02 DEBUG : both13: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both14: Sizes identical
2025/04/12 05:33:02 DEBUG : both14: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both15: Sizes identical
2025/04/12 05:33:02 DEBUG : both16: Sizes identical
2025/04/12 05:33:02 DEBUG : both17: Sizes identical
2025/04/12 05:33:02 DEBUG : both18: Sizes identical
2025/04/12 05:33:02 DEBUG : PikPak root 'rclone-test-tibehuv2fohu': Waiting for checks to finish
2025/04/12 05:33:02 DEBUG : both15: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both19: Sizes identical
2025/04/12 05:33:02 DEBUG : both18: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both2: Sizes identical
2025/04/12 05:33:02 DEBUG : both2: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both3: Sizes identical
2025/04/12 05:33:02 DEBUG : both3: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both4: Sizes identical
2025/04/12 05:33:02 DEBUG : both4: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both5: Sizes identical
2025/04/12 05:33:02 DEBUG : both5: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both6: Sizes identical
2025/04/12 05:33:02 DEBUG : both6: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both7: Sizes identical
2025/04/12 05:33:02 DEBUG : both7: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both8: Sizes identical
2025/04/12 05:33:02 DEBUG : both16: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both9: Sizes identical
2025/04/12 05:33:02 DEBUG : both19: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both17: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both8: Unchanged skipping
2025/04/12 05:33:02 DEBUG : both9: Unchanged skipping
2025/04/12 05:33:02 DEBUG : PikPak root 'rclone-test-tibehuv2fohu': Waiting for transfers to finish
2025/04/12 05:33:02 DEBUG : Waiting for deletions to finish
2025/04/12 05:33:03 INFO  : only14: Deleted
2025/04/12 05:33:03 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:33:03 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/04/12 05:33:03 DEBUG : pacer: Reducing sleep to 150ms
2025/04/12 05:33:03 DEBUG : pacer: Reducing sleep to 112.5ms
2025/04/12 05:33:03 INFO  : only5: Deleted
2025/04/12 05:33:03 DEBUG : pacer: Reducing sleep to 100ms
2025/04/12 05:33:03 INFO  : only19: Deleted
2025/04/12 05:33:04 INFO  : only4: Deleted
2025/04/12 05:33:05 INFO  : only9: Deleted
2025/04/12 05:33:05 INFO  : only1: Deleted
2025/04/12 05:33:05 INFO  : only3: Deleted
2025/04/12 05:33:05 INFO  : only11: Deleted
2025/04/12 05:33:06 INFO  : only16: Deleted
2025/04/12 05:33:06 INFO  : only2: Deleted
2025/04/12 05:33:06 INFO  : only17: Deleted
2025/04/12 05:33:06 INFO  : only10: Deleted
2025/04/12 05:33:07 INFO  : only18: Deleted
2025/04/12 05:33:07 INFO  : only6: Deleted
2025/04/12 05:33:07 INFO  : only7: Deleted
2025/04/12 05:33:07 INFO  : only8: Deleted
2025/04/12 05:33:08 INFO  : only12: Deleted
2025/04/12 05:33:08 INFO  : only13: Deleted
2025/04/12 05:33:08 INFO  : only0: Deleted
2025/04/12 05:33:08 INFO  : only15: Deleted
    run.go:180: Remote "PikPak root 'rclone-test-tibehuv2fohu'", Local "Local file system at /tmp/rclone1585209125", Modify Window "876000h0m0s"
2025/04/12 05:33:13 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:33:13 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/04/12 05:33:13 DEBUG : pacer: Reducing sleep to 150ms
2025/04/12 05:33:13 DEBUG : pacer: Reducing sleep to 112.5ms
2025/04/12 05:33:14 DEBUG : pacer: Reducing sleep to 100ms
2025/04/12 05:33:29 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:33:29 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/04/12 05:33:29 DEBUG : pacer: low level retry 2/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:33:29 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/04/12 05:33:30 DEBUG : pacer: Reducing sleep to 300ms
2025/04/12 05:33:30 DEBUG : pacer: Reducing sleep to 225ms
2025/04/12 05:33:32 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file
2025/04/12 05:33:32 DEBUG : pacer: Reducing sleep to 168.75ms
2025/04/12 05:33:33 DEBUG : pacer: Reducing sleep to 126.5625ms
2025/04/12 05:33:33 DEBUG : pacer: low level retry 1/10 (error trash (trash) is still in PHASE_TYPE_RUNNING)
2025/04/12 05:33:33 DEBUG : pacer: Rate limited, increasing sleep to 253.125ms
2025/04/12 05:33:34 DEBUG : pacer: Reducing sleep to 189.84375ms
2025/04/12 05:33:34 DEBUG : pacer: Reducing sleep to 142.382812ms
2025/04/12 05:33:35 DEBUG : pacer: Reducing sleep to 106.787109ms
2025/04/12 05:33:36 DEBUG : pacer: Reducing sleep to 100ms
--- PASS: TestSyncConcurrentDelete (80.01s)
FAIL
2025/04/12 05:33:36 DEBUG : PikPak root 'rclone-test-tibehuv2fohu': Purge remote
"./sync.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^(TestServerSideCopyOverSelf|TestSyncConcurrentDelete)$'" - Finished ERROR in 1m41.872047595s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf]