"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Starting (try 2/5)
2024/12/25 01:45:01 DEBUG : Creating backend with remote "TestPikPak:rclone-test-kekoxav7cudi"
2024/12/25 01:45:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/25 01:45:02 DEBUG : Creating backend with remote "/tmp/rclone2989065508"
=== RUN   TestCopyFileMaxTransfer
    run.go:180: Remote "PikPak root 'rclone-test-kekoxav7cudi'", Local "Local file system at /tmp/rclone2989065508", Modify Window "876000h0m0s"
2024/12/25 01:45:02 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2024/12/25 01:45:04 DEBUG : TestCopyFileMaxTransfer/file1: gcid = 3823E2B83A3BA8D1137EFC1FE13E3A2E7CFB8D82
2024/12/25 01:45:04 DEBUG : TestCopyFileMaxTransfer/file1: Dst hash empty - aborting Src hash check
2024/12/25 01:45:04 INFO  : TestCopyFileMaxTransfer/file1: Copied (new)
2024/12/25 01:45:05 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2024/12/25 01:45:05 DEBUG : TestCopyFileMaxTransfer/file2: calculating gcid: <nil>
2024/12/25 01:45:05 DEBUG : TestCopyFileMaxTransfer/file2: gcid = a78f592715f3e35d1189cdbc9fd8b80471353913
2024/12/25 01:45:05 DEBUG : file2: canceling upload: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2024/12/25 01:45:07 DEBUG : file2: waiting 500ms for the cancellation to be effective
2024/12/25 01:45:07 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload: read upload data failed: max transfer limit reached as set by --max-transfer
2024/12/25 01:45:08 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2024/12/25 01:45:09 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2024/12/25 01:45:09 DEBUG : TestCopyFileMaxTransfer/file4: calculating gcid: <nil>
2024/12/25 01:45:09 DEBUG : TestCopyFileMaxTransfer/file4: gcid = 92857d6e6e83e8654b5895b4dc647b6ad37f3585
2024/12/25 01:45:11 DEBUG : pacer: low level retry 1/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/12/25 01:45:11 DEBUG : pacer: Rate limited, increasing sleep to 200ms
2024/12/25 01:45:11 DEBUG : pacer: low level retry 2/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/12/25 01:45:11 DEBUG : pacer: Rate limited, increasing sleep to 400ms
2024/12/25 01:45:12 DEBUG : pacer: low level retry 3/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/12/25 01:45:12 DEBUG : pacer: Rate limited, increasing sleep to 800ms
2024/12/25 01:45:12 DEBUG : pacer: low level retry 4/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/12/25 01:45:12 DEBUG : pacer: Rate limited, increasing sleep to 1.6s
2024/12/25 01:45:13 DEBUG : pacer: low level retry 5/10 (error upload (upload) is still in PHASE_TYPE_PENDING)
2024/12/25 01:45:13 DEBUG : pacer: Rate limited, increasing sleep to 2s
2024/12/25 01:45:14 DEBUG : pacer: Reducing sleep to 1.5s
2024/12/25 01:45:14 DEBUG : TestCopyFileMaxTransfer/file4: Dst hash empty - aborting Src hash check
2024/12/25 01:45:14 INFO  : TestCopyFileMaxTransfer/file4: Copied (new)
2024/12/25 01:45:16 DEBUG : pacer: Reducing sleep to 1.125s
2024/12/25 01:45:18 DEBUG : pacer: Reducing sleep to 843.75ms
2024/12/25 01:45:19 DEBUG : pacer: Reducing sleep to 632.8125ms
2024/12/25 01:45:20 DEBUG : pacer: Reducing sleep to 474.609375ms
2024/12/25 01:45:21 DEBUG : pacer: Reducing sleep to 355.957031ms
2024/12/25 01:45:21 DEBUG : pacer: Reducing sleep to 266.967773ms
2024/12/25 01:45:22 DEBUG : pacer: Reducing sleep to 200.225829ms
2024/12/25 01:45:22 DEBUG : pacer: Reducing sleep to 150.169371ms
2024/12/25 01:45:23 DEBUG : pacer: Reducing sleep to 112.627028ms
2024/12/25 01:45:23 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file4"
2024/12/25 01:45:23 DEBUG : TestCopyFileMaxTransfer: Rmdir: contains trashed file: "file1"
2024/12/25 01:45:23 DEBUG : pacer: Reducing sleep to 100ms
--- PASS: TestCopyFileMaxTransfer (21.64s)
PASS
2024/12/25 01:45:24 DEBUG : PikPak root 'rclone-test-kekoxav7cudi': Purge remote
"./operations.test -test.v -test.timeout 1h0m0s -remote TestPikPak: -verbose -test.run '^TestCopyFileMaxTransfer$'" - Finished OK in 23.900401227s (try 2/5)