"./vfs.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestDirHandleMethods|TestDirHandleReaddir)$'" - Starting (try 3/5)
=== RUN   TestDirHandleMethods
2020/04/15 06:24:37 INFO  : 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': poll-interval is not supported by this remote
2020/04/15 06:24:37 DEBUG : Adding path "vfs/forget" to remote control registry
2020/04/15 06:24:37 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/04/15 06:24:37 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/04/15 06:25:39 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=jjyMieCIpE: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:25:39 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/04/15 06:25:41 DEBUG : pacer: Reducing sleep to 501ms
2020/04/15 06:25:42 DEBUG : pacer: Reducing sleep to 375.75ms
2020/04/15 06:26:43 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=ar59I38OMq: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:26:43 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/04/15 06:26:45 DEBUG : pacer: Reducing sleep to 563.625ms
2020/04/15 06:26:45 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/04/15 06:27:46 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=clgjn1qnOS: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:27:46 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/04/15 06:27:48 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/04/15 06:27:49 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/04/15 06:28:49 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=4JVohvzksd: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:28:49 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/04/15 06:28:51 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/04/15 06:28:52 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/04/15 06:29:53 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=kNTLMDnIa2: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:29:53 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/04/15 06:29:55 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/04/15 06:29:56 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/04/15 06:30:57 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=rzKDqiHG0R: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:30:57 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/04/15 06:30:59 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/04/15 06:31:00 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/04/15 06:32:01 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=jJXcHdhja4: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:32:01 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/04/15 06:32:03 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/04/15 06:32:04 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/04/15 06:33:05 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=NciL59kQMC: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:33:05 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/04/15 06:33:07 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/04/15 06:33:09 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/04/15 06:34:10 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=OQw0jfD05D: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:34:10 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/04/15 06:34:12 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/04/15 06:34:14 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/04/15 06:35:15 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=v3FN4sXaQc: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:35:15 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/04/15 06:35:15 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/04/15 06:35:17 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/04/15 06:35:18 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/04/15 06:35:19 DEBUG : pacer: Reducing sleep to 610.090433ms
2020/04/15 06:35:20 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/04/15 06:35:21 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/04/15 06:35:21 DEBUG : pacer: Reducing sleep to 334ms
--- FAIL: TestDirHandleMethods (644.28s)
    run.go:176: Remote "1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8'", Local "Local file system at /tmp/rclone063046023", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=jjyMieCIpE: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 2/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=ar59I38OMq: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 3/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=clgjn1qnOS: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=4JVohvzksd: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 5/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=kNTLMDnIa2: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 6/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=rzKDqiHG0R: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 7/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=jJXcHdhja4: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 8/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=NciL59kQMC: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 9/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=OQw0jfD05D: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:282: Failed to put "dir/file1" to "1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=v3FN4sXaQc: dial tcp 5.39.224.125:443: i/o timeout
=== RUN   TestDirHandleReaddir
2020/04/15 06:35:21 INFO  : 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': poll-interval is not supported by this remote
2020/04/15 06:35:21 DEBUG : Adding path "vfs/forget" to remote control registry
2020/04/15 06:35:21 DEBUG : Adding path "vfs/refresh" to remote control registry
2020/04/15 06:35:21 DEBUG : Adding path "vfs/poll-interval" to remote control registry
2020/04/15 06:36:23 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=nUj4O6mKAf: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:36:23 DEBUG : pacer: Rate limited, increasing sleep to 668ms
2020/04/15 06:36:25 DEBUG : pacer: Reducing sleep to 501ms
2020/04/15 06:36:26 DEBUG : pacer: Reducing sleep to 375.75ms
2020/04/15 06:37:26 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=vY1A7cIKSS: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:37:26 DEBUG : pacer: Rate limited, increasing sleep to 751.5ms
2020/04/15 06:37:28 DEBUG : pacer: Reducing sleep to 563.625ms
2020/04/15 06:37:29 DEBUG : pacer: Reducing sleep to 422.71875ms
2020/04/15 06:38:30 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=tAzepgosk3: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:38:30 DEBUG : pacer: Rate limited, increasing sleep to 845.4375ms
2020/04/15 06:38:32 DEBUG : pacer: Reducing sleep to 634.078125ms
2020/04/15 06:38:32 DEBUG : pacer: Reducing sleep to 475.558593ms
2020/04/15 06:39:33 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=X1Ah0aotiT: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:39:33 DEBUG : pacer: Rate limited, increasing sleep to 951.117186ms
2020/04/15 06:39:35 DEBUG : pacer: Reducing sleep to 713.337889ms
2020/04/15 06:39:36 DEBUG : pacer: Reducing sleep to 535.003416ms
2020/04/15 06:40:37 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=DghOvVr0S3: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:40:37 DEBUG : pacer: Rate limited, increasing sleep to 1.070006832s
2020/04/15 06:40:39 DEBUG : pacer: Reducing sleep to 802.505124ms
2020/04/15 06:40:40 DEBUG : pacer: Reducing sleep to 601.878843ms
2020/04/15 06:41:41 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=hXcK5GS4lr: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:41:41 DEBUG : pacer: Rate limited, increasing sleep to 1.203757686s
2020/04/15 06:41:43 DEBUG : pacer: Reducing sleep to 902.818264ms
2020/04/15 06:41:44 DEBUG : pacer: Reducing sleep to 677.113698ms
2020/04/15 06:42:45 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=3DsNAP9lyX: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:42:45 DEBUG : pacer: Rate limited, increasing sleep to 1.354227396s
2020/04/15 06:42:47 DEBUG : pacer: Reducing sleep to 1.015670547s
2020/04/15 06:42:48 DEBUG : pacer: Reducing sleep to 761.75291ms
2020/04/15 06:43:49 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=7qd2FCl4J1: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:43:49 DEBUG : pacer: Rate limited, increasing sleep to 1.52350582s
2020/04/15 06:43:51 DEBUG : pacer: Reducing sleep to 1.142629365s
2020/04/15 06:43:53 DEBUG : pacer: Reducing sleep to 856.972023ms
2020/04/15 06:44:54 DEBUG : pacer: low level retry 1/1 (error Post https://ru-3.1fichier.com/upload.cgi?id=1aH6M1InTN: dial tcp 5.39.224.39:443: i/o timeout)
2020/04/15 06:44:54 DEBUG : pacer: Rate limited, increasing sleep to 1.713944046s
2020/04/15 06:44:56 DEBUG : pacer: Reducing sleep to 1.285458034s
2020/04/15 06:44:57 DEBUG : pacer: Reducing sleep to 964.093525ms
2020/04/15 06:45:59 DEBUG : pacer: low level retry 1/1 (error Post https://up2.1fichier.com/upload.cgi?id=BXxYAZ6dnN: dial tcp 5.39.224.125:443: i/o timeout)
2020/04/15 06:45:59 DEBUG : pacer: Rate limited, increasing sleep to 1.92818705s
2020/04/15 06:45:59 DEBUG : pacer: Reducing sleep to 1.446140287s
2020/04/15 06:46:01 DEBUG : pacer: Reducing sleep to 1.084605215s
2020/04/15 06:46:02 DEBUG : pacer: Reducing sleep to 813.453911ms
2020/04/15 06:46:03 DEBUG : pacer: Reducing sleep to 610.090433ms
2020/04/15 06:46:04 DEBUG : pacer: Reducing sleep to 457.567824ms
2020/04/15 06:46:05 DEBUG : pacer: Reducing sleep to 343.175868ms
2020/04/15 06:46:05 DEBUG : pacer: Reducing sleep to 334ms
--- FAIL: TestDirHandleReaddir (643.69s)
    run.go:176: Remote "1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8'", Local "Local file system at /tmp/rclone063046023", Modify Window "876000h0m0s"
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 1/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=nUj4O6mKAf: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 2/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=vY1A7cIKSS: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 3/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=tAzepgosk3: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 4/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=X1Ah0aotiT: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 5/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=DghOvVr0S3: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 6/10 (couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=hXcK5GS4lr: dial tcp 5.39.224.125:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 7/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=3DsNAP9lyX: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 8/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=7qd2FCl4J1: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:278: Retry Put of "dir/file1" to 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': 9/10 (couldn't upload file: Post https://ru-3.1fichier.com/upload.cgi?id=1aH6M1InTN: dial tcp 5.39.224.39:443: i/o timeout)
    run.go:282: Failed to put "dir/file1" to "1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8'": couldn't upload file: Post https://up2.1fichier.com/upload.cgi?id=BXxYAZ6dnN: dial tcp 5.39.224.125:443: i/o timeout
FAIL
2020/04/15 06:46:06 DEBUG : 1Fichier root 'rclone-test-jebizeh0xumipeb9liqaqad8': Purge dir ""
"./vfs.test -test.v -test.timeout 30m0s -remote TestFichier: -verbose -test.run '^(TestDirHandleMethods|TestDirHandleReaddir)$'" - Finished ERROR in 21m29.427592141s (try 3/5): exit status 1: Failed [TestDirHandleMethods TestDirHandleReaddir]