"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 2/5)
2025/02/05 03:07:30 DEBUG : Creating backend with remote "TestPikPak:rclone-test-bacapig0xaji"
2025/02/05 03:07:30 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/02/05 03:07:31 DEBUG : Creating backend with remote "/tmp/rclone1700171178"
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "PikPak root 'rclone-test-bacapig0xaji'", Local "Local file system at /tmp/rclone1700171178", Modify Window "876000h0m0s"
2025/02/05 03:07:32 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2025/02/05 03:07:34 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2025/02/05 03:07:34 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2025/02/05 03:07:34 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2025/02/05 03:07:35 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2025/02/05 03:07:36 DEBUG : TestCopyFileMaxTransfer/file2: calculating gcid: <nil>
2025/02/05 03:07:36 DEBUG : TestCopyFileMaxTransfer/file2: gcid = 7c1db8a76869380d31927ca68fafcfe8d9084222
2025/02/05 03:07:36 DEBUG : file2: canceling upload: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2025/02/05 03:07:38 DEBUG : file2: waiting 500ms for the cancellation to be effective
2025/02/05 03:07:38 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2025/02/05 03:07:39 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2025/02/05 03:07:40 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2025/02/05 03:07:41 DEBUG : TestCopyFileMaxTransfer/file4: calculating gcid: <nil>
2025/02/05 03:07:41 DEBUG : TestCopyFileMaxTransfer/file4: gcid = f8c8147fac8388d7a8311a7dec81e3d3ed1c934f
2025/02/05 03:07:43 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/05 03:07:43 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2025/02/05 03:07:43 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/05 03:07:43 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2025/02/05 03:07:44 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/05 03:07:44 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2025/02/05 03:07:44 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/05 03:07:44 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2025/02/05 03:07:45 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2025/02/05 03:07:45 DEBUG : pacer: Rate limited, increasing sleep to 2s
2025/02/05 03:07:47 DEBUG : pacer: Reducing sleep to 1.5s
2025/02/05 03:07:47 DEBUG : TestCopyFileMaxTransfer/file4: Dst hash empty - aborting Src hash check
2025/02/05 03:07:47 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
2025/02/05 03:07:48 DEBUG : pacer: Reducing sleep to 1.125s
2025/02/05 03:07:50 DEBUG : pacer: Reducing sleep to 843.75ms
2025/02/05 03:07:51 DEBUG : pacer: Reducing sleep to 632.8125ms
2025/02/05 03:07:52 DEBUG : pacer: Reducing sleep to 474.609375ms
2025/02/05 03:07:53 DEBUG : pacer: Reducing sleep to 355.957031ms
2025/02/05 03:07:53 DEBUG : pacer: Reducing sleep to 266.967773ms
2025/02/05 03:07:54 DEBUG : pacer: Reducing sleep to 200.225829ms
2025/02/05 03:07:54 DEBUG : pacer: Reducing sleep to 150.169371ms
2025/02/05 03:07:55 DEBUG : pacer: Reducing sleep to 112.627028ms
2025/02/05 03:07:55 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file4"
2025/02/05 03:07:55 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2025/02/05 03:07:55 DEBUG : pacer: Reducing sleep to 100ms
--- PASS: TestCopyFileMaxTransfer (24.63s)
PASS
2025/02/05 03:07:56 DEBUG : PikPak root 'rclone-test-bacapig0xaji': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished OK in 27.093559373s (try 2/5)