"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 2/5)
2026/05/11 01:15:54 DEBUG : Creating backend with remote "TestPikPak:rclone-test-raxined2gavu"
2026/05/11 01:15:54 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/11 01:15:55 DEBUG : Creating backend with remote "/tmp/rclone2297440560"
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "PikPak root 'rclone-test-raxined2gavu'", Local "Local file system at /tmp/rclone2297440560", Modify Window "876000h0m0s"
2026/05/11 01:15:55 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/05/11 01:15:56 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2026/05/11 01:15:57 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/05/11 01:15:57 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2026/05/11 01:15:57 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/05/11 01:15:57 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/05/11 01:15:58 DEBUG : TestCopyFileMaxTransfer/file2: calculating gcid: <nil>
2026/05/11 01:15:58 DEBUG : TestCopyFileMaxTransfer/file2: gcid = 1ae41742a18ef1521df3ce4d3608f7539a5f0b97
2026/05/11 01:15:59 DEBUG : file2: canceling upload: failed to upload: operation error S3: PutObject, exceeded maximum number of attempts, 1, https response error StatusCode: 0, RequestID: , HostID: , request send failed, Put "https://vip-lixian-07.mypikpak.com/upload_tmp/1AE41742A18EF1521DF3CE4D3608F7539A5F0B97_1778462158276353605?x-id=PutObject": max transfer limit reached as set by --max-transfer
2026/05/11 01:16:00 DEBUG : file2: waiting 500ms for the cancellation to be effective
2026/05/11 01:16:00 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload: operation error S3: PutObject, exceeded maximum number of attempts, 1, https response error StatusCode: 0, RequestID: , HostID: , request send failed, Put "https://vip-lixian-07.mypikpak.com/upload_tmp/1AE41742A18EF1521DF3CE4D3608F7539A5F0B97_1778462158276353605?x-id=PutObject": max transfer limit reached as set by --max-transfer
2026/05/11 01:16:01 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/05/11 01:16:02 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/05/11 01:16:02 DEBUG : TestCopyFileMaxTransfer/file4: calculating gcid: <nil>
2026/05/11 01:16:02 DEBUG : TestCopyFileMaxTransfer/file4: gcid = 3f1a43415e32a017d7d2cb7b53a46c0ab7b97156
2026/05/11 01:16:04 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:04 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2026/05/11 01:16:04 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:04 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/05/11 01:16:05 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:05 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/05/11 01:16:05 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:05 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/05/11 01:16:06 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:06 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/05/11 01:16:07 DEBUG : pacer: low level retry 6/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:09 DEBUG : pacer: low level retry 7/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:11 DEBUG : pacer: low level retry 8/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:13 DEBUG : pacer: low level retry 9/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:15 DEBUG : pacer: low level retry 10/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/05/11 01:16:15 DEBUG : file4: canceling upload: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsJajuqTXN5L5GxxIb4bVjYo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3f19d1c5a440), FileID:"VOsJajuXTXN5L5GxxIb4bVjWo2", FileName:"file4", FileSize:"2062", Message:"Pending", CreatedTime:api.Time{wall:0x2498e580, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, UpdatedTime:api.Time{wall:0x259c4bc0, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
2026/05/11 01:16:17 DEBUG : pacer: Reducing sleep to 1.5s
2026/05/11 01:16:19 DEBUG : pacer: Reducing sleep to 1.125s
2026/05/11 01:16:21 DEBUG : pacer: Reducing sleep to 843.75ms
2026/05/11 01:16:21 DEBUG : file4: waiting 500ms for the cancellation to be effective
2026/05/11 01:16:21 ERROR : TestCopyFileMaxTransfer/file4: Failed to copy: can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsJajuqTXN5L5GxxIb4bVjYo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3f19d1c5a440), FileID:"VOsJajuXTXN5L5GxxIb4bVjWo2", FileName:"file4", FileSize:"2062", Message:"Pending", CreatedTime:api.Time{wall:0x2498e580, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, UpdatedTime:api.Time{wall:0x259c4bc0, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
    copy_test.go:531: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:531
        	Error:      	Received unexpected error:
        	            	can't verify the task is completed: &api.Task{Kind:"drive#task", ID:"VOsJajuqTXN5L5GxxIb4bVjYo2", Name:"upload", Type:"upload", UserID:"ZB7KFqZKGnYUJW_K", Statuses:[]interface {}{}, StatusSize:0, Params:(*api.TaskParams)(0x3f19d1c5a440), FileID:"VOsJajuXTXN5L5GxxIb4bVjWo2", FileName:"file4", FileSize:"2062", Message:"Pending", CreatedTime:api.Time{wall:0x2498e580, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, UpdatedTime:api.Time{wall:0x259c4bc0, ext:63914058962, loc:(*time.Location)(0x3f19d1a5f7a0)}, ThirdTaskID:"", Phase:"PHASE_TYPE_PENDING", Progress:0, IconLink:"https://static.mypikpak.com/0ed033dfc7b3862af92a52ef7e3e4d257223df81", Callback:"", ReferenceResource:interface {}(nil), Space:""}
        	Test:       	TestCopyFileMaxTransfer
2026/05/11 01:16:22 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/05/11 01:16:23 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/05/11 01:16:24 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/05/11 01:16:24 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/05/11 01:16:25 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/05/11 01:16:25 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2026/05/11 01:16:25 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/05/11 01:16:26 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/05/11 01:16:26 DEBUG : pacer: Reducing sleep to 100ms
--- FAIL: TestCopyFileMaxTransfer (30.85s)
FAIL
2026/05/11 01:16:26 DEBUG : PikPak root 'rclone-test-raxined2gavu': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished ERROR in 32.791768728s (try 2/5): exit status 1: Failed [TestCopyFileMaxTransfer]