"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 2/5)
2026/09/01 03:18:09 DEBUG : Creating backend with remote "TestPikPak:rclone-test-razihuk4silo"
2026/09/01 03:18:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/09/01 03:18:10 DEBUG : Creating backend with remote "/tmp/rclone1784549230"
=== RUN   TestCopyFileMaxTransfer
    run.go:198: Remote "PikPak root 'rclone-test-razihuk4silo'", Local "Local file system at /tmp/rclone1784549230", Modify Window "876000h0m0s"
2026/09/01 03:18:10 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/09/01 03:18:11 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2026/09/01 03:18:12 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/09/01 03:18:12 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2026/09/01 03:18:12 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2026/09/01 03:18:12 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/09/01 03:18:13 DEBUG : TestCopyFileMaxTransfer/file2: calculating gcid: <nil>
2026/09/01 03:18:13 DEBUG : TestCopyFileMaxTransfer/file2: gcid = cd05b317433958e364e67b245443a1a670d1ee0a
2026/09/01 03:18:14 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/CD05B317433958E364E67B245443A1A670D1EE0A_1788232693456027588?x-id=PutObject": max transfer limit reached as set by --max-transfer
2026/09/01 03:18:15 DEBUG : file2: waiting 500ms for the cancellation to be effective
2026/09/01 03:18:15 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/CD05B317433958E364E67B245443A1A670D1EE0A_1788232693456027588?x-id=PutObject": max transfer limit reached as set by --max-transfer
2026/09/01 03:18:16 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/09/01 03:18:17 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/09/01 03:18:17 DEBUG : TestCopyFileMaxTransfer/file4: calculating gcid: <nil>
2026/09/01 03:18:17 DEBUG : TestCopyFileMaxTransfer/file4: gcid = 8a06a8f7fa9bbb03005bab8fb9ba6d165703a69f
2026/09/01 03:18:19 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/09/01 03:18:19 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2026/09/01 03:18:19 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/09/01 03:18:19 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2026/09/01 03:18:19 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/09/01 03:18:19 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2026/09/01 03:18:20 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/09/01 03:18:20 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2026/09/01 03:18:21 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2026/09/01 03:18:21 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/09/01 03:18:22 DEBUG : pacer: Reducing sleep to 1.5s
2026/09/01 03:18:22 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/09/01 03:18:22 DEBUG : TestCopyFileMaxTransfer/file4: Dst hash empty - aborting Src hash check
2026/09/01 03:18:22 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
2026/09/01 03:18:24 DEBUG : pacer: Reducing sleep to 1.125s
2026/09/01 03:18:26 DEBUG : pacer: Reducing sleep to 843.75ms
2026/09/01 03:18:27 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/09/01 03:18:28 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/09/01 03:18:29 DEBUG : Saving config "captcha_token" in section "TestPikPak" of the config file
2026/09/01 03:18:29 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/09/01 03:18:30 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/09/01 03:18:30 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/09/01 03:18:31 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/09/01 03:18:31 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/09/01 03:18:31 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file4"
2026/09/01 03:18:31 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2026/09/01 03:18:31 DEBUG : pacer: Reducing sleep to 100ms
--- PASS: TestCopyFileMaxTransfer (22.32s)
PASS
2026/09/01 03:18:32 DEBUG : PikPak root 'rclone-test-razihuk4silo': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished OK in 24.284864847s (try 2/5)