"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 5/5)
2025/05/06 04:36:39 DEBUG : Creating backend with remote "TestPikPak:rclone-test-wuziyop8jave"
2025/05/06 04:36:39 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/05/06 04:36:41 DEBUG : Creating backend with remote "/tmp/rclone2148075865"
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "PikPak root 'rclone-test-wuziyop8jave'", Local "Local file system at /tmp/rclone2148075865", Modify Window "876000h0m0s"
2025/05/06 04:36:41 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2025/05/06 04:36:43 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2025/05/06 04:36:43 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2025/05/06 04:36:43 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2025/05/06 04:36:44 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2025/05/06 04:36:46 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:46 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/05/06 04:36:49 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:49 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/05/06 04:36:51 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:51 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/05/06 04:36:53 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:53 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/05/06 04:36:56 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:56 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/05/06 04:36:58 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:37:00 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:37:02 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:37:05 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:37:07 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:37:07 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:37:07 DEBUG : TestCopyFileMaxTransfer/file2: gcid = fe800ab53e71d8fe208a1604096a0d6c6311e6b3
2025/05/06 04:37:07 DEBUG : pacer: Reducing sleep to 1.5s
2025/05/06 04:37:07 DEBUG : file2: canceling upload: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2025/05/06 04:37:09 DEBUG : pacer: Reducing sleep to 1.125s
2025/05/06 04:37:11 DEBUG : pacer: Reducing sleep to 843.75ms
2025/05/06 04:37:12 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/05/06 04:37:12 DEBUG : file2: waiting 500ms for the cancellation to be effective
2025/05/06 04:37:12 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:37:13 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/05/06 04:37:13 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/05/06 04:37:14 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/05/06 04:37:14 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2025/05/06 04:37:14 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/05/06 04:37:14 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/05/06 04:37:15 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/05/06 04:37:15 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2025/05/06 04:37:15 DEBUG : pacer: Reducing sleep to 100ms
2025/05/06 04:37:17 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:37:17 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/05/06 04:37:20 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:37:20 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/05/06 04:37:22 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:37:22 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/05/06 04:37:24 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:37:24 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/05/06 04:37:26 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:37:26 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/05/06 04:37:29 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:37:31 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:37:33 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:37:36 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:37:38 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:37:38 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:37:38 DEBUG : TestCopyFileMaxTransfer/file4: gcid = 027294936cecaaada858130432cf5f9faa88cd2d
2025/05/06 04:37:38 DEBUG : pacer: Reducing sleep to 1.5s
2025/05/06 04:37:39 DEBUG : file4: canceling upload: failed to upload: operation error S3: PutObject, https response error StatusCode: 400, RequestID: 681992138E4DB9343708F40F, 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:37:40 DEBUG : pacer: Reducing sleep to 1.125s
2025/05/06 04:37:42 DEBUG : pacer: Reducing sleep to 843.75ms
2025/05/06 04:37:43 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/05/06 04:37:43 DEBUG : file4: waiting 500ms for the cancellation to be effective
2025/05/06 04:37:43 ERROR : TestCopyFileMaxTransfer/file4: Failed to copy: failed to upload: operation error S3: PutObject, https response error StatusCode: 400, RequestID: 681992138E4DB9343708F40F, 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: 681992138E4DB9343708F40F, 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:37:44 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/05/06 04:37:44 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/05/06 04:37:45 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/05/06 04:37:45 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/05/06 04:37:46 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/05/06 04:37:46 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2025/05/06 04:37:46 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/05/06 04:37:47 DEBUG : pacer: Reducing sleep to 100ms
--- FAIL: TestCopyFileMaxTransfer (66.49s)
FAIL
2025/05/06 04:37:47 DEBUG : PikPak root 'rclone-test-wuziyop8jave': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished ERROR in 1m9.087708394s (try 5/5): exit status 1: Failed [TestCopyFileMaxTransfer]