Latest rclone Box backend hangs when max-duration hit

export RCLONE_MAX_DURATION
RCLONE_MAX_DURATION=5m

2021/03/19 04:34:59 DEBUG : Using config file from "/home/joel/.config/rclone/rclone.conf"^M
2021/03/19 04:34:59 DEBUG : rclone: Version "v1.55.0-beta.5318.2bdbf00fa" starting with parameters ["rclone-v1.55.0-beta.5318.2bdbf00fa-linux-amd64/rclone" "copy" "-vvvv" "customer_images/" "Box1:test1"]^M
2021/03/19 04:34:59 DEBUG : Creating backend with remote "customer_images/"^M
2021/03/19 04:34:59 DEBUG : fs cache: renaming cache item "customer_images/" to be canonical "/home/joel/customer_images"^M
2021/03/19 04:34:59 DEBUG : Creating backend with remote "Box1:test1"^M
2021/03/19 04:35:00 INFO  : box root 'test1': Transfer session deadline: 2021/03/19 04:40:00^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_cferom_fs_image_128_squbi.w: Size and modification time the same (differ by -768.543917ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_cferom_fs_image_128_squbi.w: Unchanged skipping^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_fs_image_128_puresqubi.w: Size and modification time the same (differ by -529.840008ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_fs_image_128_puresqubi.w: Unchanged skipping^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_fs_image_128_ubi.w: Size and modification time the same (differ by -599.35116ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_nand_fs_image_128_ubi.w: Unchanged skipping^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_cferom_fs_image_128_squbi.w: Size and modification time the same (differ by -55.45464ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_cferom_fs_image_128_squbi.w: Unchanged skipping^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_cferom_fs_image_128_ubi.w: Size and modification time the same (differ by -403.667606ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_cferom_fs_image_128_ubi.w: Unchanged skipping^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_fs_image_128_puresqubi.w: Size and modification time the same (differ by -521.599295ms, within tolerance 1s)^M
2021/03/19 04:35:03 DEBUG : bcm947189GW_OVS_nand_fs_image_128_puresqubi.w: Unchanged skipping^M

Once transfers get going, they are normal...

2021/03/19 04:37:53 DEBUG : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_emmc_bootpartition_flash_image_963138REF_P502.w.gz: SHA-1 = 97a908da808ded55906d03f7485a0206f00a60a2 OK^M
2021/03/19 04:37:53 INFO  : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_emmc_bootpartition_flash_image_963138REF_P502.w.gz: Copied (new)^M
2021/03/19 04:37:56 DEBUG : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_cfe_fs_kernel_emmc_squashfs.gz: SHA-1 = cd504b6aa58ba78fe8a42ad77649f2773978830e OK^M
2021/03/19 04:37:56 INFO  : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_cfe_fs_kernel_emmc_squashfs.gz: Copied (new)^M
2021/03/19 04:37:59 DEBUG : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_emmc_datapartition_flash_image_963138REF_P502_ext4.w.gz: SHA-1 = f4873e8fdb6ffa665b433cc4348709580fed69a0 OK^M
2021/03/19 04:37:59 INFO  : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_emmc_datapartition_flash_image_963138REF_P502_ext4.w.gz: Copied (new)^M
2021/03/19 04:38:00 INFO  : ^M
Transferred:        2.763G / 82.236 GBytes, 3%, 15.977 MBytes/s, ETA 1h24m53s^M
Checks:               569 / 569, 100%^M
Transferred:           74 / 2167, 3%^M
Elapsed time:       3m0.4s^M
Transferring:^M
 * bcm963138BGW_JUMBO_GFA\u202638REF_P502_squash.w.gz:100% /33.958M, 4.246M/s, 0s^M
 * bcm963138BGW_JUMBO_GFA\u2026fs_kernel_emmc_ext4.gz: 86% /41.534M, 3.834M/s, 1s^M
 * bcm963138BGW_JUMBO_GFA\u2026ernel_emmc_squashfs.gz:  0% /33.948M, 0/s, -^M
 * bcm963138BGW_JUMBO_GFA\u2026_image_128_puresqubi.w:  0% /36.125M, 0/s, -^M
^M
2021/03/19 04:38:02 DEBUG : bcm963138BGW_JUMBO_GFAST_CPE_WL19D1_fs_kernel_emmc_ext4.gz: SHA-1 = 20dedec130865d271e66f29d323c4ed762e2d40e OK^M

Until the max-duration is encountered, then it hangs

2021/03/19 04:39:55 INFO  : bcm963138BGW_JUMBO_nand_fs_image_128_puresqubi.w: Copied (new)^M
2021/03/19 04:39:56 DEBUG : bcm963138BGW_JUMBO_nand_fs_image_128_pureubi.w: SHA-1 = 8235e7193d624ef6d6020e8ec487e4404e215875 OK^M
2021/03/19 04:39:56 INFO  : bcm963138BGW_JUMBO_nand_fs_image_128_pureubi.w: Copied (new)^M
2021/03/19 04:40:00 INFO  : ^M
Transferred:        4.416G / 82.236 GBytes, 5%, 15.219 MBytes/s, ETA 1h27m16s^M
Checks:               569 / 569, 100%^M
Transferred:          119 / 2167, 5%^M
Elapsed time:       5m0.4s^M
Transferring:^M
 *  bcm963138BGW_JUMBO_nand_fs_image_128_squbi.w:100% /27.375M, 2.738M/s, 0s^M
 *    bcm963138BGW_JUMBO_nand_fs_image_128_ubi.w:100% /40.125M, 5.799M/s, 0s^M
 * bcm963138BGW_nand_cfer\u2026_image_128_puresqubi.w: 22% /27.250M, 0/s, -^M
 * bcm963138BGW_nand_cfer\u2026fs_image_128_pureubi.w:  0% /40.000M, 0/s, -^M
^M
2021/03/19 04:40:00 ERROR : bcm963138BGW_JUMBO_nand_fs_image_128_ubi.w: Failed to copy: Post "https://upload.box.com/api/2.0/files/content": context deadline exceeded^M
2021/03/19 04:40:00 ERROR : bcm963138BGW_JUMBO_nand_fs_image_128_squbi.w: Failed to copy: Post "https://upload.box.com/api/2.0/files/content": context deadline exceeded^M
2021/03/19 04:40:00 ERROR : bcm963138BGW_nand_cferom_fs_image_128_pureubi.w: Failed to copy: couldn't list files: Get "https://api.box.com/2.0/folders/133767211618/items?fields=type%2Cid%2Csequence_id%2Cetag%2Csha1%2Cname%2Csize%2Ccreated_at%2Cmodified_at%2Ccontent_created_at%2Ccontent_modified_at%2Citem_status%2Cshared_link&limit=1000&offset=0": context deadline exceeded^M
2021/03/19 04:41:00 INFO  : ^M
Transferred:        4.416G / 82.197 GBytes, 5%, 12.662 MBytes/s, ETA 1h44m50s^M

After that, it is just plain stuck...

2021/03/19 04:42:00 INFO  : ^M
Transferred:        4.416G / 82.197 GBytes, 5%, 10.841 MBytes/s, ETA 2h2m27s^M
Errors:                 3 (retrying may help)^M
Checks:               569 / 569, 100%^M
Transferred:          119 / 2164, 5%^M
Elapsed time:       7m0.4s^M
Transferring:^M
 * bcm963138BGW_nand_cfer\u2026_image_128_puresqubi.w: 22% /27.250M, 369/s, 16h46m25s^M
^M
2021/03/19 04:43:00 INFO  : ^M
Transferred:        4.416G / 82.197 GBytes, 5%, 9.477 MBytes/s, ETA 2h20m4s^M
Errors:                 3 (retrying may help)^M
Checks:               569 / 569, 100%^M
Transferred:          119 / 2164, 5%^M
Elapsed time:       8m0.4s^M
Transferring:^M
 * bcm963138BGW_nand_cfer\u2026_image_128_puresqubi.w: 22% /27.250M, 7/s, 806h1m43s^M
^M
2021/03/19 04:44:00 INFO  : ^M
Transferred:        4.416G / 82.197 GBytes, 5%, 8.419 MBytes/s, ETA 2h37m41s^M
Errors:                 3 (retrying may help)^M
Checks:               569 / 569, 100%^M
Transferred:          119 / 2164, 5%^M
Elapsed time:       9m0.4s^M
Transferring:^M
 * bcm963138BGW_nand_cfer\u2026_image_128_puresqubi.w: 22% /27.250M, 0/s, 38732h21m47s^M
^M

Config file contains only....

[Box1]
type = box
token = {"access_token":"6YlXWXXXXkMfxDWwN2TB","token_type":"bearer","refresh_token":"Z8pd6nOJxDIOBeAkXXXXhGL4wk52Pk8i9RA","expiry":"2021-03-19T05:32:08.370117831Z"}

This topic was automatically closed 60 days after the last reply. New replies are no longer allowed.