"./operations.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestListDirSorted|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Starting (try 2/5)
=== RUN   TestMultithreadCopy
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2020/03/09 06:49:51 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=SYm2vpuvxm: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:49:51 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/03/09 06:49:53 DEBUG : pacer: Reducing sleep to 501ms
2020/03/09 06:49:54 DEBUG : pacer: Reducing sleep to 375.75ms
2020/03/09 06:50:54 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=P99UdkXysO: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:50:54 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/03/09 06:50:56 DEBUG : pacer: Reducing sleep to 563.625ms
2020/03/09 06:50:57 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/03/09 06:51:58 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=gWECXycJu9: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:51:58 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/03/09 06:52:00 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/03/09 06:52:00 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/03/09 06:53:01 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=MUktg92rUF: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 06:53:01 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/03/09 06:53:03 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/03/09 06:53:04 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/03/09 06:54:05 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=F52708UtGJ: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:54:05 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/03/09 06:54:07 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/03/09 06:54:08 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/03/09 06:55:09 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=r8OHzt06h3: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:55:09 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/03/09 06:55:11 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/03/09 06:55:12 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/03/09 06:56:13 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=hFQ7XqfjN0: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:56:13 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/03/09 06:56:15 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/03/09 06:56:16 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/03/09 06:57:17 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=yGHyhjzI9W: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:57:17 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/03/09 06:57:19 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/03/09 06:57:21 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/03/09 06:58:22 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=HARFhZIgmh: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:58:22 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/03/09 06:58:24 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/03/09 06:58:25 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/03/09 06:59:27 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=q1kbNvYvka: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 06:59:27 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/03/09 06:59:27 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/03/09 06:59:29 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/03/09 06:59:30 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/03/09 06:59:31 DEBUG : pacer: Reducing sleep to 610.090433ms
--- FAIL: TestMultithreadCopy (641.74s)
    run.go:176: Remote "1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1'", Local "Local file system at /tmp/rclone451798319", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 1/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=SYm2vpuvxm: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=P99UdkXysO: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 3/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=gWECXycJu9: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 4/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=MUktg92rUF: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=F52708UtGJ: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=r8OHzt06h3: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=hFQ7XqfjN0: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=yGHyhjzI9W: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 9/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=HARFhZIgmh: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:282: Failed to put "file1" to "1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=q1kbNvYvka: dial tcp 5.39.224.39:443: i/o timeout
    --- FAIL: TestMultithreadCopy/{size:131071_streams:2} (637.26s)
        testing.go:864: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test
=== RUN   TestListDirSorted
2020/03/09 06:59:32 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/03/09 06:59:33 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/03/09 07:00:33 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=elW2HwrMvI: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:00:33 DEBUG : pacer: Rate limited, increasing sleep to 686.351736ms
2020/03/09 07:00:35 DEBUG : pacer: Reducing sleep to 514.763802ms
2020/03/09 07:00:36 DEBUG : pacer: Reducing sleep to 386.072851ms
2020/03/09 07:01:36 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=BfHOTxjayl: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:01:36 DEBUG : pacer: Rate limited, increasing sleep to 772.145702ms
2020/03/09 07:01:38 DEBUG : pacer: Reducing sleep to 579.109276ms
2020/03/09 07:01:39 DEBUG : pacer: Reducing sleep to 434.331957ms
2020/03/09 07:02:40 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=KHs3MQ1CGx: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:02:40 DEBUG : pacer: Rate limited, increasing sleep to 868.663914ms
2020/03/09 07:02:42 DEBUG : pacer: Reducing sleep to 651.497935ms
2020/03/09 07:02:42 DEBUG : pacer: Reducing sleep to 488.623451ms
2020/03/09 07:03:43 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=XnJTziGBar: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:03:43 DEBUG : pacer: Rate limited, increasing sleep to 977.246902ms
2020/03/09 07:03:45 DEBUG : pacer: Reducing sleep to 732.935176ms
2020/03/09 07:03:46 DEBUG : pacer: Reducing sleep to 549.701382ms
2020/03/09 07:04:47 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=p2OhDwkGWg: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:04:47 DEBUG : pacer: Rate limited, increasing sleep to 1.099402764s
2020/03/09 07:04:49 DEBUG : pacer: Reducing sleep to 824.552073ms
2020/03/09 07:04:50 DEBUG : pacer: Reducing sleep to 618.414054ms
2020/03/09 07:05:51 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=Wkwqlx5zuk: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:05:51 DEBUG : pacer: Rate limited, increasing sleep to 1.236828108s
2020/03/09 07:05:53 DEBUG : pacer: Reducing sleep to 927.621081ms
2020/03/09 07:05:54 DEBUG : pacer: Reducing sleep to 695.71581ms
2020/03/09 07:06:55 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=S9fd2h1E3Z: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:06:55 DEBUG : pacer: Rate limited, increasing sleep to 1.39143162s
2020/03/09 07:06:57 DEBUG : pacer: Reducing sleep to 1.043573715s
2020/03/09 07:06:58 DEBUG : pacer: Reducing sleep to 782.680286ms
2020/03/09 07:07:59 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=gZwu3eruoX: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:07:59 DEBUG : pacer: Rate limited, increasing sleep to 1.565360572s
2020/03/09 07:08:01 DEBUG : pacer: Reducing sleep to 1.174020429s
2020/03/09 07:08:03 DEBUG : pacer: Reducing sleep to 880.515321ms
2020/03/09 07:09:04 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=ewAZNbUPzo: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:09:04 DEBUG : pacer: Rate limited, increasing sleep to 1.761030642s
2020/03/09 07:09:06 DEBUG : pacer: Reducing sleep to 1.320772981s
2020/03/09 07:09:08 DEBUG : pacer: Reducing sleep to 990.579735ms
2020/03/09 07:10:09 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=p02CZCGmOE: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:10:09 DEBUG : pacer: Rate limited, increasing sleep to 1.98115947s
2020/03/09 07:10:09 DEBUG : pacer: Reducing sleep to 1.485869602s
2020/03/09 07:10:11 DEBUG : pacer: Reducing sleep to 1.114402201s
2020/03/09 07:10:13 DEBUG : pacer: Reducing sleep to 835.80165ms
2020/03/09 07:10:14 DEBUG : pacer: Reducing sleep to 626.851237ms
--- FAIL: TestListDirSorted (642.61s)
    run.go:176: Remote "1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1'", Local "Local file system at /tmp/rclone451798319", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=elW2HwrMvI: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=BfHOTxjayl: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 3/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=KHs3MQ1CGx: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=XnJTziGBar: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 5/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=p2OhDwkGWg: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=Wkwqlx5zuk: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=S9fd2h1E3Z: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=gZwu3eruoX: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=ewAZNbUPzo: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "a.txt" to "1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=p02CZCGmOE: dial tcp 5.39.224.39:443: i/o timeout
FAIL
2020/03/09 07:10:15 DEBUG : pacer: Reducing sleep to 470.138427ms
2020/03/09 07:10:15 DEBUG : pacer: Reducing sleep to 352.60382ms
2020/03/09 07:10:15 DEBUG : 1Fichier root 'rclone-test-tijasuk2tewogak8huhikom1': Purge dir ""
2020/03/09 07:10:16 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.735611684s (try 2/5): exit status 1: Failed [TestMultithreadCopy/{size:131071_streams:2} TestListDirSorted]