"./vfs.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestDirHandleMethods|TestDirHandleReaddir)$'" - Starting (try 2/5)
=== RUN   TestDirHandleMethods
2020/03/09 05:31:19 INFO  : 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': poll-interval is not supported by this remote
2020/03/09 05:31:19 DEBUG : Adding path "vfs/forget" to remote control registry
2020/03/09 05:31:19 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/03/09 05:31:19 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/03/09 05:32:21 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=7EdBx1qk88: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:32:21 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/03/09 05:32:23 DEBUG : pacer: Reducing sleep to 501ms
2020/03/09 05:32:24 DEBUG : pacer: Reducing sleep to 375.75ms
2020/03/09 05:33:24 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=dXAok7zDsR: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:33:24 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/03/09 05:33:26 DEBUG : pacer: Reducing sleep to 563.625ms
2020/03/09 05:33:27 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/03/09 05:34:27 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=JFWfWOV26p: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:34:27 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/03/09 05:34:29 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/03/09 05:34:30 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/03/09 05:35:31 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=grV6kMwoSQ: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:35:31 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/03/09 05:35:33 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/03/09 05:35:34 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/03/09 05:36:34 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=6g8hVxv2iA: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:36:34 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/03/09 05:36:37 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/03/09 05:36:38 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/03/09 05:37:38 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=JQvk4xhWdN: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:37:38 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/03/09 05:37:40 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/03/09 05:37:42 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/03/09 05:38:42 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=Cxdg7QS8a6: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:38:42 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/03/09 05:38:45 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/03/09 05:38:46 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/03/09 05:39:47 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=cdQ92OiNNo: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:39:47 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/03/09 05:39:49 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/03/09 05:39:50 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/03/09 05:40:52 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=Orpuoqksw4: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:40:52 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/03/09 05:40:54 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/03/09 05:40:55 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/03/09 05:41:57 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=4XofT59cT2: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:41:57 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/03/09 05:41:57 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/03/09 05:41:58 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/03/09 05:42:00 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/03/09 05:42:01 DEBUG : pacer: Reducing sleep to 610.090433ms
2020/03/09 05:42:02 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/03/09 05:42:02 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/03/09 05:42:03 DEBUG : pacer: Reducing sleep to 334ms
--- FAIL: TestDirHandleMethods (644.28s)
    run.go:176: Remote "1Fichier root 'rclone-test-legihod5buliqap7xakisax7'", Local "Local file system at /tmp/rclone313316152", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=7EdBx1qk88: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 2/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=dXAok7zDsR: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=JFWfWOV26p: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=grV6kMwoSQ: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=6g8hVxv2iA: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=JQvk4xhWdN: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=Cxdg7QS8a6: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 8/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=cdQ92OiNNo: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=Orpuoqksw4: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "dir/file1" to "1Fichier root 'rclone-test-legihod5buliqap7xakisax7'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=4XofT59cT2: dial tcp 5.39.224.125:443: i/o timeout
=== RUN   TestDirHandleReaddir
2020/03/09 05:42:03 INFO  : 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': poll-interval is not supported by this remote
2020/03/09 05:42:03 DEBUG : Adding path "vfs/forget" to remote control registry
2020/03/09 05:42:03 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/03/09 05:42:03 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/03/09 05:43:05 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=CAwk4g9csk: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:43:05 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/03/09 05:43:07 DEBUG : pacer: Reducing sleep to 501ms
2020/03/09 05:43:07 DEBUG : pacer: Reducing sleep to 375.75ms
2020/03/09 05:44:08 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=QNGeNdfoBz: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:44:08 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/03/09 05:44:10 DEBUG : pacer: Reducing sleep to 563.625ms
2020/03/09 05:44:10 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/03/09 05:45:11 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=DTmzWo3XlI: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:45:11 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/03/09 05:45:13 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/03/09 05:45:14 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/03/09 05:46:15 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=D2CBmw5sTA: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:46:15 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/03/09 05:46:17 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/03/09 05:46:17 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/03/09 05:47:18 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=jkGaVkrsOh: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:47:18 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/03/09 05:47:20 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/03/09 05:47:21 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/03/09 05:48:22 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=tYeY25Hirx: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:48:22 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/03/09 05:48:24 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/03/09 05:48:25 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/03/09 05:49:26 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=DT9ma2Oge1: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:49:26 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/03/09 05:49:28 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/03/09 05:49:30 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/03/09 05:50:31 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=ndKMYAy8h6: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:50:31 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/03/09 05:50:33 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/03/09 05:50:34 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/03/09 05:51:35 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=BNdjQGMIYT: dial tcp 5.39.224.125:443: i/o timeout)
2020/03/09 05:51:35 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/03/09 05:51:37 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/03/09 05:51:39 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/03/09 05:52:40 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=AsvPEzORdV: dial tcp 5.39.224.39:443: i/o timeout)
2020/03/09 05:52:40 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/03/09 05:52:40 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/03/09 05:52:42 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/03/09 05:52:44 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/03/09 05:52:45 DEBUG : pacer: Reducing sleep to 610.090433ms
2020/03/09 05:52:46 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/03/09 05:52:46 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/03/09 05:52:47 DEBUG : pacer: Reducing sleep to 334ms
--- FAIL: TestDirHandleReaddir (643.68s)
    run.go:176: Remote "1Fichier root 'rclone-test-legihod5buliqap7xakisax7'", Local "Local file system at /tmp/rclone313316152", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 1/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=CAwk4g9csk: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 2/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=QNGeNdfoBz: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=DTmzWo3XlI: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=D2CBmw5sTA: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 5/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=jkGaVkrsOh: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=tYeY25Hirx: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 7/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=DT9ma2Oge1: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=ndKMYAy8h6: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=BNdjQGMIYT: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "dir/file1" to "1Fichier root 'rclone-test-legihod5buliqap7xakisax7'": couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=AsvPEzORdV: dial tcp 5.39.224.39:443: i/o timeout
FAIL
2020/03/09 05:52:47 DEBUG : 1Fichier root 'rclone-test-legihod5buliqap7xakisax7': Purge dir ""
"./vfs.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestDirHandleMethods|TestDirHandleReaddir)$'" - Finished ERROR in 21m29.41699296s (try 2/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir]