"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestFileReadAtZeroLength$'" - Starting (try 2/5) 2022/05/21 05:16:09 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-hiyoweq9ratimec4xacuxet5" 2022/05/21 05:16:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/05/21 05:16:09 DEBUG : Creating backend with remote "/tmp/rclone2025415032" === RUN TestFileReadAtZeroLength run.go:181: Remote "premiumize.me root 'rclone-test-hiyoweq9ratimec4xacuxet5'", Local "Local file system at /tmp/rclone2025415032", Modify Window "876000h0m0s" 2022/05/21 05:16:09 INFO : premiumize.me root 'rclone-test-hiyoweq9ratimec4xacuxet5': poll-interval is not supported by this remote 2022/05/21 05:16:09 NOTICE: premiumize.me root 'rclone-test-hiyoweq9ratimec4xacuxet5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2022/05/21 05:16:09 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/05/21 05:16:09 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/05/21 05:16:09 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:16:09 DEBUG : file1: >Open: fd=file1 (w), err= 2022/05/21 05:16:09 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/05/21 05:16:09 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:16:09 DEBUG : premiumize.me root 'rclone-test-hiyoweq9ratimec4xacuxet5': File to upload is small (0 bytes), uploading instead of streaming 2022/05/21 05:16:09 INFO : file1: Copied (new) 2022/05/21 05:16:09 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:16:09 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/05/21 05:16:09 DEBUG : file1: Open: flags=O_RDONLY 2022/05/21 05:16:09 DEBUG : file1: >Open: fd=file1 (r), err= 2022/05/21 05:16:09 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/05/21 05:16:09 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/05/21 05:17:09 DEBUG : pacer: low level retry 1/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:17:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/05/21 05:18:09 DEBUG : pacer: low level retry 2/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:18:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/05/21 05:19:09 DEBUG : pacer: low level retry 3/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:19:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2022/05/21 05:20:09 DEBUG : pacer: low level retry 4/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:20:09 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2022/05/21 05:21:09 DEBUG : pacer: low level retry 5/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:21:09 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2022/05/21 05:22:09 DEBUG : pacer: low level retry 6/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:22:09 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2022/05/21 05:23:09 DEBUG : pacer: low level retry 7/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:23:09 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2022/05/21 05:24:09 DEBUG : pacer: low level retry 8/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:24:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2022/05/21 05:25:09 DEBUG : pacer: low level retry 9/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:26:09 DEBUG : pacer: low level retry 10/10 (error Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) write_test.go:315: Error Trace: write_test.go:315 write_test.go:325 Error: Received unexpected error: Get "https://attractivelion-sto.energycdn.com/dl/y_czyIXstF_GXSzZuj_DGg/1653714969/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout Test: TestFileReadAtZeroLength 2022/05/21 05:26:09 DEBUG : WaitForWriters: timeout=30s 2022/05/21 05:26:09 DEBUG : : Looking for writers 2022/05/21 05:26:09 DEBUG : file1: reading active writers 2022/05/21 05:26:09 DEBUG : >WaitForWriters: 2022/05/21 05:26:10 DEBUG : pacer: Reducing sleep to 1.5s 2022/05/21 05:26:11 DEBUG : pacer: Reducing sleep to 1.125s 2022/05/21 05:26:13 DEBUG : pacer: Reducing sleep to 843.75ms --- FAIL: TestFileReadAtZeroLength (604.21s) FAIL 2022/05/21 05:26:13 DEBUG : premiumize.me root 'rclone-test-hiyoweq9ratimec4xacuxet5': Purge remote 2022/05/21 05:26:14 DEBUG : pacer: Reducing sleep to 632.8125ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestFileReadAtZeroLength$'" - Finished ERROR in 10m5.558024551s (try 2/5): exit status 1: Failed [TestFileReadAtZeroLength]