"./operations.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestListDirSorted|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Starting (try 3/5)
=== RUN   TestMultithreadCopy
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2020/04/15 07:08:34 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=fNdGBNOVeT: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:08:34 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/04/15 07:08:36 DEBUG : pacer: Reducing sleep to 501ms
2020/04/15 07:08:36 DEBUG : pacer: Reducing sleep to 375.75ms
2020/04/15 07:09:37 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=2dMdpD32vB: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:09:37 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/04/15 07:09:39 DEBUG : pacer: Reducing sleep to 563.625ms
2020/04/15 07:09:39 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/04/15 07:10:40 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=9fEq6jPqBh: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:10:40 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/04/15 07:10:42 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/04/15 07:10:43 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/04/15 07:11:43 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=6taqwyfBVL: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:11:43 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/04/15 07:11:46 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/04/15 07:11:46 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/04/15 07:12:47 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=suXvrsELgO: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:12:47 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/04/15 07:12:49 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/04/15 07:12:50 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/04/15 07:13:51 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=lgwA0SsbUu: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:13:51 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/04/15 07:13:53 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/04/15 07:13:54 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/04/15 07:14:55 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=iF2x937Sdt: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:14:55 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/04/15 07:14:57 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/04/15 07:14:59 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/04/15 07:16:00 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=CMWC6aHxFg: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:16:00 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/04/15 07:16:02 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/04/15 07:16:03 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/04/15 07:17:04 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=Lk1xmPZLhr: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:17:04 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/04/15 07:17:06 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/04/15 07:17:08 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/04/15 07:18:09 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=vXb9QE2g2u: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:18:09 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/04/15 07:18:09 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/04/15 07:18:11 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/04/15 07:18:13 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/04/15 07:18:14 DEBUG : pacer: Reducing sleep to 610.090433ms
--- FAIL: TestMultithreadCopy (641.74s)
    run.go:176: Remote "1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0'", Local "Local file system at /tmp/rclone220729128", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 1/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=fNdGBNOVeT: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=2dMdpD32vB: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=9fEq6jPqBh: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=6taqwyfBVL: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=suXvrsELgO: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=lgwA0SsbUu: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=iF2x937Sdt: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=CMWC6aHxFg: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 9/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=Lk1xmPZLhr: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:282: Failed to put "file1" to "1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=vXb9QE2g2u: dial tcp 5.39.224.39:443: i/o timeout
    --- FAIL: TestMultithreadCopy/{size:131071_streams:2} (637.27s)
        testing.go:864: test executed panic(nil) or runtime.Goexit: subtest may have called FailNow on a parent test
=== RUN   TestListDirSorted
2020/04/15 07:18:14 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/04/15 07:18:15 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/04/15 07:19:16 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=O6GQIrotAx: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:19:16 DEBUG : pacer: Rate limited, increasing sleep to 686.351736ms
2020/04/15 07:19:18 DEBUG : pacer: Reducing sleep to 514.763802ms
2020/04/15 07:19:18 DEBUG : pacer: Reducing sleep to 386.072851ms
2020/04/15 07:20:19 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=XPaAOljSRT: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:20:19 DEBUG : pacer: Rate limited, increasing sleep to 772.145702ms
2020/04/15 07:20:21 DEBUG : pacer: Reducing sleep to 579.109276ms
2020/04/15 07:20:22 DEBUG : pacer: Reducing sleep to 434.331957ms
2020/04/15 07:21:22 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=mznOsOI2GU: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:21:22 DEBUG : pacer: Rate limited, increasing sleep to 868.663914ms
2020/04/15 07:21:24 DEBUG : pacer: Reducing sleep to 651.497935ms
2020/04/15 07:21:25 DEBUG : pacer: Reducing sleep to 488.623451ms
2020/04/15 07:22:26 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=8X8Vm9sorW: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:22:26 DEBUG : pacer: Rate limited, increasing sleep to 977.246902ms
2020/04/15 07:22:28 DEBUG : pacer: Reducing sleep to 732.935176ms
2020/04/15 07:22:29 DEBUG : pacer: Reducing sleep to 549.701382ms
2020/04/15 07:23:29 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=USEwIgAZCA: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:23:29 DEBUG : pacer: Rate limited, increasing sleep to 1.099402764s
2020/04/15 07:23:31 DEBUG : pacer: Reducing sleep to 824.552073ms
2020/04/15 07:23:32 DEBUG : pacer: Reducing sleep to 618.414054ms
2020/04/15 07:24:33 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=MtCp5rpdnl: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:24:33 DEBUG : pacer: Rate limited, increasing sleep to 1.236828108s
2020/04/15 07:24:35 DEBUG : pacer: Reducing sleep to 927.621081ms
2020/04/15 07:24:36 DEBUG : pacer: Reducing sleep to 695.71581ms
2020/04/15 07:25:37 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=Q90rOlHIGc: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:25:37 DEBUG : pacer: Rate limited, increasing sleep to 1.39143162s
2020/04/15 07:25:39 DEBUG : pacer: Reducing sleep to 1.043573715s
2020/04/15 07:25:41 DEBUG : pacer: Reducing sleep to 782.680286ms
2020/04/15 07:26:42 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=4PAcw3FHR0: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:26:42 DEBUG : pacer: Rate limited, increasing sleep to 1.565360572s
2020/04/15 07:26:44 DEBUG : pacer: Reducing sleep to 1.174020429s
2020/04/15 07:26:45 DEBUG : pacer: Reducing sleep to 880.515321ms
2020/04/15 07:27:47 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=B0bSZkrQjW: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 07:27:47 DEBUG : pacer: Rate limited, increasing sleep to 1.761030642s
2020/04/15 07:27:49 DEBUG : pacer: Reducing sleep to 1.320772981s
2020/04/15 07:27:50 DEBUG : pacer: Reducing sleep to 990.579735ms
2020/04/15 07:28:52 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=sCeN5X2dJo: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 07:28:52 DEBUG : pacer: Rate limited, increasing sleep to 1.98115947s
2020/04/15 07:28:52 DEBUG : pacer: Reducing sleep to 1.485869602s
2020/04/15 07:28:54 DEBUG : pacer: Reducing sleep to 1.114402201s
2020/04/15 07:28:55 DEBUG : pacer: Reducing sleep to 835.80165ms
2020/04/15 07:28:56 DEBUG : pacer: Reducing sleep to 626.851237ms
--- FAIL: TestListDirSorted (642.62s)
    run.go:176: Remote "1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0'", Local "Local file system at /tmp/rclone220729128", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 1/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=O6GQIrotAx: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=XPaAOljSRT: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 3/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=mznOsOI2GU: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 4/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=8X8Vm9sorW: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 5/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=USEwIgAZCA: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=MtCp5rpdnl: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 7/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=Q90rOlHIGc: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 8/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=4PAcw3FHR0: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': 9/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=B0bSZkrQjW: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:282: Failed to put "a.txt" to "1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=sCeN5X2dJo: dial tcp 5.39.224.125:443: i/o timeout
FAIL
2020/04/15 07:28:57 DEBUG : pacer: Reducing sleep to 470.138427ms
2020/04/15 07:28:58 DEBUG : pacer: Reducing sleep to 352.60382ms
2020/04/15 07:28:58 DEBUG : 1Fichier root 'rclone-test-pavahis4qofidur3qonilaw0': Purge dir ""
2020/04/15 07:28:58 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.744809153s (try 3/5): exit status 1: Failed [TestMultithreadCopy/{size:131071_streams:2} TestListDirSorted]