"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 4/5)
2025/05/06 04:35:35 DEBUG : Creating backend with remote "TestPikPak:rclone-test-kuqumib6feso"
2025/05/06 04:35:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/06 04:35:35 DEBUG : Creating backend with remote "/tmp/rclone664734717"
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "PikPak root 'rclone-test-kuqumib6feso'", Local "Local file system at /tmp/rclone664734717", Modify Window "876000h0m0s"
2025/05/06 04:35:35 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2025/05/06 04:35:37 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2025/05/06 04:35:37 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2025/05/06 04:35:37 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2025/05/06 04:35:38 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2025/05/06 04:35:40 DEBUG : pacer: low level retry 1/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:40 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/05/06 04:35:43 DEBUG : pacer: low level retry 2/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/05/06 04:35:45 DEBUG : pacer: low level retry 3/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:45 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/05/06 04:35:45 DEBUG : pacer: low level retry 4/10 (error Error "internal server error: status code: 500" (500))
2025/05/06 04:35:45 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/05/06 04:35:48 DEBUG : pacer: low level retry 5/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:48 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/05/06 04:35:50 DEBUG : pacer: low level retry 6/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:52 DEBUG : pacer: low level retry 7/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:54 DEBUG : pacer: low level retry 8/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:57 DEBUG : pacer: low level retry 9/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:59 DEBUG : pacer: low level retry 10/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:35:59 DEBUG : TestCopyFileMaxTransfer/file2: calculating gcid: Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500)
2025/05/06 04:35:59 DEBUG : TestCopyFileMaxTransfer/file2: gcid = 33aabfbcdbb8fc99001b76fa0f0400573b3b9031
2025/05/06 04:35:59 DEBUG : pacer: Reducing sleep to 1.5s
2025/05/06 04:35:59 DEBUG : file2: canceling upload: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2025/05/06 04:36:01 DEBUG : pacer: Reducing sleep to 1.125s
2025/05/06 04:36:03 DEBUG : pacer: Reducing sleep to 843.75ms
2025/05/06 04:36:04 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/05/06 04:36:04 DEBUG : file2: waiting 500ms for the cancellation to be effective
2025/05/06 04:36:04 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2025/05/06 04:36:05 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/05/06 04:36:05 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/05/06 04:36:06 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/05/06 04:36:06 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2025/05/06 04:36:06 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/05/06 04:36:06 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/05/06 04:36:06 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/05/06 04:36:06 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2025/05/06 04:36:07 DEBUG : pacer: Reducing sleep to 100ms
2025/05/06 04:36:09 DEBUG : pacer: low level retry 1/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:09 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/05/06 04:36:11 DEBUG : pacer: low level retry 2/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/05/06 04:36:13 DEBUG : pacer: low level retry 3/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:13 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/05/06 04:36:15 DEBUG : pacer: low level retry 4/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:15 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/05/06 04:36:18 DEBUG : pacer: low level retry 5/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:18 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/05/06 04:36:20 DEBUG : pacer: low level retry 6/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:20 DEBUG : pacer: low level retry 7/10 (error Error "internal server error: status code: 500" (500))
2025/05/06 04:36:24 DEBUG : pacer: low level retry 8/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:26 DEBUG : pacer: low level retry 9/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:28 DEBUG : pacer: low level retry 10/10 (error Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500))
2025/05/06 04:36:28 DEBUG : TestCopyFileMaxTransfer/file4: calculating gcid: Error "internal server error: Post \"http://idx.shub.mypikpak.com\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" (500)
2025/05/06 04:36:28 DEBUG : TestCopyFileMaxTransfer/file4: gcid = 11bcff8be667283d27184752e77fed6d26f4d482
2025/05/06 04:36:29 DEBUG : pacer: Reducing sleep to 1.5s
2025/05/06 04:36:30 DEBUG : file4: canceling upload: failed to upload: operation error S3: PutObject, https response error StatusCode: 400, RequestID: 681991CE0189C7323346EE72, HostID: vip-lixian-07.mypikpak.com, api error InvalidArgument: aws-chunked encoding is not supported with the specified x-amz-content-sha256 value.
2025/05/06 04:36:31 DEBUG : pacer: Reducing sleep to 1.125s
2025/05/06 04:36:32 DEBUG : pacer: Reducing sleep to 843.75ms
2025/05/06 04:36:33 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/05/06 04:36:33 DEBUG : file4: waiting 500ms for the cancellation to be effective
2025/05/06 04:36:34 ERROR : TestCopyFileMaxTransfer/file4: Failed to copy: failed to upload: operation error S3: PutObject, https response error StatusCode: 400, RequestID: 681991CE0189C7323346EE72, HostID: vip-lixian-07.mypikpak.com, api error InvalidArgument: aws-chunked encoding is not supported with the specified x-amz-content-sha256 value.
    copy_test.go:504: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/copy_test.go:504
        	Error:      	Received unexpected error:
        	            	failed to upload: operation error S3: PutObject, https response error StatusCode: 400, RequestID: 681991CE0189C7323346EE72, HostID: vip-lixian-07.mypikpak.com, api error InvalidArgument: aws-chunked encoding is not supported with the specified x-amz-content-sha256 value.
        	Test:       	TestCopyFileMaxTransfer
2025/05/06 04:36:34 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/05/06 04:36:35 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/05/06 04:36:35 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/05/06 04:36:36 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/05/06 04:36:36 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/05/06 04:36:36 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2025/05/06 04:36:36 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/05/06 04:36:38 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file
2025/05/06 04:36:38 DEBUG : pacer: Reducing sleep to 100ms
--- FAIL: TestCopyFileMaxTransfer (62.94s)
FAIL
2025/05/06 04:36:38 DEBUG : PikPak root 'rclone-test-kuqumib6feso': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished ERROR in 1m4.530467147s (try 4/5): exit status 1: Failed [TestCopyFileMaxTransfer]