"./operations.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestListDirSorted|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Starting (try 4/5)
=== RUN   TestMultithreadCopy
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2020/03/09 07:32:45 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=ZQFo1uXevZ: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:32:45 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/03/09 07:32:47 DEBUG : pacer: Reducing sleep to 501ms
2020/03/09 07:32:47 DEBUG : pacer: Reducing sleep to 375.75ms
2020/03/09 07:33:48 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=nxRe7GK7ip: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:33:48 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/03/09 07:33:50 DEBUG : pacer: Reducing sleep to 563.625ms
2020/03/09 07:33:50 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/03/09 07:34:51 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=5dJG63cgJj: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:34:51 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/03/09 07:34:53 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/03/09 07:34:54 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/03/09 07:35:54 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=IhcgupFVhn: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:35:54 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/03/09 07:35:57 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/03/09 07:35:57 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/03/09 07:36:58 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=JUvIl9xZYu: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:36:58 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/03/09 07:37:00 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/03/09 07:37:01 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/03/09 07:38:02 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=Y0msa6XvHU: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:38:02 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/03/09 07:38:04 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/03/09 07:38:05 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/03/09 07:39:06 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=Qu9wIkjiVI: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:39:06 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/03/09 07:39:08 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/03/09 07:39:09 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/03/09 07:40:10 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=Umy9BfMIcG: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:40:10 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/03/09 07:40:13 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/03/09 07:40:14 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/03/09 07:41:15 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=0BRDZ5bjht: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:41:15 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/03/09 07:41:17 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/03/09 07:41:19 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/03/09 07:42:20 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=JXXk8VCi0c: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:42:20 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/03/09 07:42:20 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/03/09 07:42:22 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/03/09 07:42:24 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/03/09 07:42:25 DEBUG : pacer: Reducing sleep to 610.090433ms
--- FAIL: TestMultithreadCopy (641.73s)
    run.go:176: Remote "1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2'", Local "Local file system at /tmp/rclone523172966", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=ZQFo1uXevZ: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 2/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=nxRe7GK7ip: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=5dJG63cgJj: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=IhcgupFVhn: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=JUvIl9xZYu: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=Y0msa6XvHU: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=Qu9wIkjiVI: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 8/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=Umy9BfMIcG: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=0BRDZ5bjht: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "file1" to "1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=JXXk8VCi0c: dial tcp 5.39.224.125:443: i/o timeout
    --- FAIL: TestMultithreadCopy/{size:131071_streams:2} (637.25s)
        testing.go:864: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test
=== RUN   TestListDirSorted
2020/03/09 07:42:25 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/03/09 07:42:26 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/03/09 07:43:27 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=DGyNNnZ3F2: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:43:27 DEBUG : pacer: Rate limited, increasing sleep to 686.351736ms
2020/03/09 07:43:29 DEBUG : pacer: Reducing sleep to 514.763802ms
2020/03/09 07:43:29 DEBUG : pacer: Reducing sleep to 386.072851ms
2020/03/09 07:44:30 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=9ZkhCu5Bj7: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:44:30 DEBUG : pacer: Rate limited, increasing sleep to 772.145702ms
2020/03/09 07:44:32 DEBUG : pacer: Reducing sleep to 579.109276ms
2020/03/09 07:44:32 DEBUG : pacer: Reducing sleep to 434.331957ms
2020/03/09 07:45:33 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=jXxYRQX5xc: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:45:33 DEBUG : pacer: Rate limited, increasing sleep to 868.663914ms
2020/03/09 07:45:35 DEBUG : pacer: Reducing sleep to 651.497935ms
2020/03/09 07:45:36 DEBUG : pacer: Reducing sleep to 488.623451ms
2020/03/09 07:46:37 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=RZNiQhXq0a: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:46:37 DEBUG : pacer: Rate limited, increasing sleep to 977.246902ms
2020/03/09 07:46:39 DEBUG : pacer: Reducing sleep to 732.935176ms
2020/03/09 07:46:40 DEBUG : pacer: Reducing sleep to 549.701382ms
2020/03/09 07:47:40 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=Oc5U9TYadr: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:47:40 DEBUG : pacer: Rate limited, increasing sleep to 1.099402764s
2020/03/09 07:47:42 DEBUG : pacer: Reducing sleep to 824.552073ms
2020/03/09 07:47:43 DEBUG : pacer: Reducing sleep to 618.414054ms
2020/03/09 07:48:44 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=nx4l1KyfBo: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:48:44 DEBUG : pacer: Rate limited, increasing sleep to 1.236828108s
2020/03/09 07:48:46 DEBUG : pacer: Reducing sleep to 927.621081ms
2020/03/09 07:48:47 DEBUG : pacer: Reducing sleep to 695.71581ms
2020/03/09 07:49:48 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=42sQr7U4uX: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:49:48 DEBUG : pacer: Rate limited, increasing sleep to 1.39143162s
2020/03/09 07:49:50 DEBUG : pacer: Reducing sleep to 1.043573715s
2020/03/09 07:49:52 DEBUG : pacer: Reducing sleep to 782.680286ms
2020/03/09 07:50:53 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=MBY9TasuQX: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:50:53 DEBUG : pacer: Rate limited, increasing sleep to 1.565360572s
2020/03/09 07:50:55 DEBUG : pacer: Reducing sleep to 1.174020429s
2020/03/09 07:50:56 DEBUG : pacer: Reducing sleep to 880.515321ms
2020/03/09 07:51:58 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=QjRVSqoWLG: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:51:58 DEBUG : pacer: Rate limited, increasing sleep to 1.761030642s
2020/03/09 07:52:00 DEBUG : pacer: Reducing sleep to 1.320772981s
2020/03/09 07:52:01 DEBUG : pacer: Reducing sleep to 990.579735ms
2020/03/09 07:53:03 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=XUKH1cCkwx: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:53:03 DEBUG : pacer: Rate limited, increasing sleep to 1.98115947s
2020/03/09 07:53:03 DEBUG : pacer: Reducing sleep to 1.485869602s
2020/03/09 07:53:05 DEBUG : pacer: Reducing sleep to 1.114402201s
2020/03/09 07:53:06 DEBUG : pacer: Reducing sleep to 835.80165ms
2020/03/09 07:53:07 DEBUG : pacer: Reducing sleep to 626.851237ms
--- FAIL: TestListDirSorted (642.62s)
    run.go:176: Remote "1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2'", Local "Local file system at /tmp/rclone523172966", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=DGyNNnZ3F2: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=9ZkhCu5Bj7: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=jXxYRQX5xc: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 4/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=RZNiQhXq0a: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=Oc5U9TYadr: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=nx4l1KyfBo: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 7/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=42sQr7U4uX: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=MBY9TasuQX: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=QjRVSqoWLG: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "a.txt" to "1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=XUKH1cCkwx: dial tcp 5.39.224.39:443: i/o timeout
FAIL
2020/03/09 07:53:08 DEBUG : pacer: Reducing sleep to 470.138427ms
2020/03/09 07:53:09 DEBUG : pacer: Reducing sleep to 352.60382ms
2020/03/09 07:53:09 DEBUG : 1Fichier root 'rclone-test-gozazuz1rupadeq5xojegob2': Purge dir ""
2020/03/09 07:53:09 DEBUG : pacer: Reducing sleep to 334ms
"./operations.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestListDirSorted|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Finished ERROR in 21m26.738029072s (try 4/5): exit status 1: Failed [TestMultithreadCopy/{size:131071_streams:2} TestListDirSorted]