"./operations.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestListDirSorted|TestMultithreadCopy)$/^{size:131071_streams:2}$'" - Starting (try 5/5)
=== RUN   TestMultithreadCopy
=== RUN   TestMultithreadCopy/{size:131071_streams:2}
2020/03/09 07:54:11 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=MtDdxpmnyG: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:54:11 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/03/09 07:54:13 DEBUG : pacer: Reducing sleep to 501ms
2020/03/09 07:54:14 DEBUG : pacer: Reducing sleep to 375.75ms
2020/03/09 07:55:14 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=xUtiS1f9U7: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:55:14 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/03/09 07:55:16 DEBUG : pacer: Reducing sleep to 563.625ms
2020/03/09 07:55:17 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/03/09 07:56:18 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=CrJW49tOwC: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:56:18 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/03/09 07:56:20 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/03/09 07:56:21 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/03/09 07:57:21 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=41yqdddkbK: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 07:57:21 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/03/09 07:57:23 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/03/09 07:57:24 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/03/09 07:58:25 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=EQ1ipmFvCP: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:58:25 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/03/09 07:58:27 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/03/09 07:58:28 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/03/09 07:59:29 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=oYioUe4lbx: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 07:59:29 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/03/09 07:59:31 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/03/09 07:59:32 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/03/09 08:00:33 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=cWrxvVYA4T: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:00:33 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/03/09 08:00:35 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/03/09 08:00:36 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/03/09 08:01:37 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=lIdxd7mgf1: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:01:37 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/03/09 08:01:39 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/03/09 08:01:41 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/03/09 08:02:42 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=UVHi5r79n0: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:02:42 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/03/09 08:02:44 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/03/09 08:02:46 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/03/09 08:03:47 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=eahvg7uqlQ: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 08:03:47 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/03/09 08:03:47 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/03/09 08:03:49 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/03/09 08:03:50 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/03/09 08:03:51 DEBUG : pacer: Reducing sleep to 610.090433ms
--- FAIL: TestMultithreadCopy (641.73s)
    run.go:176: Remote "1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3'", Local "Local file system at /tmp/rclone563033756", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 1/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=MtDdxpmnyG: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=xUtiS1f9U7: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=CrJW49tOwC: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 4/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=41yqdddkbK: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=EQ1ipmFvCP: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=oYioUe4lbx: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=cWrxvVYA4T: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=lIdxd7mgf1: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "file1" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 9/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=UVHi5r79n0: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:282: Failed to put "file1" to "1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=eahvg7uqlQ: 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 08:03:52 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/03/09 08:03:53 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/03/09 08:04:53 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=qGPrE3dbFY: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 08:04:53 DEBUG : pacer: Rate limited, increasing sleep to 686.351736ms
2020/03/09 08:04:55 DEBUG : pacer: Reducing sleep to 514.763802ms
2020/03/09 08:04:56 DEBUG : pacer: Reducing sleep to 386.072851ms
2020/03/09 08:05:56 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=tGCcWF5OcP: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:05:56 DEBUG : pacer: Rate limited, increasing sleep to 772.145702ms
2020/03/09 08:05:58 DEBUG : pacer: Reducing sleep to 579.109276ms
2020/03/09 08:05:59 DEBUG : pacer: Reducing sleep to 434.331957ms
2020/03/09 08:07:00 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=3oEcwXlCus: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:07:00 DEBUG : pacer: Rate limited, increasing sleep to 868.663914ms
2020/03/09 08:07:02 DEBUG : pacer: Reducing sleep to 651.497935ms
2020/03/09 08:07:03 DEBUG : pacer: Reducing sleep to 488.623451ms
2020/03/09 08:08:03 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=sIY7FCoIad: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:08:03 DEBUG : pacer: Rate limited, increasing sleep to 977.246902ms
2020/03/09 08:08:05 DEBUG : pacer: Reducing sleep to 732.935176ms
2020/03/09 08:08:06 DEBUG : pacer: Reducing sleep to 549.701382ms
2020/03/09 08:09:07 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=qfVwCxWnAP: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:09:07 DEBUG : pacer: Rate limited, increasing sleep to 1.099402764s
2020/03/09 08:09:09 DEBUG : pacer: Reducing sleep to 824.552073ms
2020/03/09 08:09:10 DEBUG : pacer: Reducing sleep to 618.414054ms
2020/03/09 08:10:11 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=UVSm0EuHuH: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:10:11 DEBUG : pacer: Rate limited, increasing sleep to 1.236828108s
2020/03/09 08:10:13 DEBUG : pacer: Reducing sleep to 927.621081ms
2020/03/09 08:10:14 DEBUG : pacer: Reducing sleep to 695.71581ms
2020/03/09 08:11:15 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=YQui6UeJ6B: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:11:15 DEBUG : pacer: Rate limited, increasing sleep to 1.39143162s
2020/03/09 08:11:17 DEBUG : pacer: Reducing sleep to 1.043573715s
2020/03/09 08:11:19 DEBUG : pacer: Reducing sleep to 782.680286ms
2020/03/09 08:12:20 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=DbQ8LSsjFe: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:12:20 DEBUG : pacer: Rate limited, increasing sleep to 1.565360572s
2020/03/09 08:12:22 DEBUG : pacer: Reducing sleep to 1.174020429s
2020/03/09 08:12:23 DEBUG : pacer: Reducing sleep to 880.515321ms
2020/03/09 08:13:24 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=9niipG1Ecf: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 08:13:24 DEBUG : pacer: Rate limited, increasing sleep to 1.761030642s
2020/03/09 08:13:26 DEBUG : pacer: Reducing sleep to 1.320772981s
2020/03/09 08:13:28 DEBUG : pacer: Reducing sleep to 990.579735ms
2020/03/09 08:14:29 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=dm0Abrticq: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 08:14:29 DEBUG : pacer: Rate limited, increasing sleep to 1.98115947s
2020/03/09 08:14:29 DEBUG : pacer: Reducing sleep to 1.485869602s
2020/03/09 08:14:31 DEBUG : pacer: Reducing sleep to 1.114402201s
2020/03/09 08:14:33 DEBUG : pacer: Reducing sleep to 835.80165ms
2020/03/09 08:14:34 DEBUG : pacer: Reducing sleep to 626.851237ms
--- FAIL: TestListDirSorted (642.61s)
    run.go:176: Remote "1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3'", Local "Local file system at /tmp/rclone563033756", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=qGPrE3dbFY: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=tGCcWF5OcP: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 3/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=3oEcwXlCus: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=sIY7FCoIad: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=qfVwCxWnAP: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=UVSm0EuHuH: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=YQui6UeJ6B: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=DbQ8LSsjFe: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "a.txt" to 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=9niipG1Ecf: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "a.txt" to "1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=dm0Abrticq: dial tcp 5.39.224.39:443: i/o timeout
FAIL
2020/03/09 08:14:35 DEBUG : pacer: Reducing sleep to 470.138427ms
2020/03/09 08:14:35 DEBUG : pacer: Reducing sleep to 352.60382ms
2020/03/09 08:14:35 DEBUG : 1Fichier root 'rclone-test-qapuvod9lekabiw9sopalov3': Purge dir ""
2020/03/09 08:14:36 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.727336708s (try 5/5): exit status 1: Failed [TestMultithreadCopy/{size:131071_streams:2} TestListDirSorted]