"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestFileReadAtZeroLength$'" - Starting (try 5/5) 2022/05/21 05:47:15 DEBUG : Creating backend with remote "TestPremiumizeMe:rclone-test-xamodak6kusezur6soxepad4" 2022/05/21 05:47:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/05/21 05:47:15 DEBUG : Creating backend with remote "/tmp/rclone1953377061" === RUN TestFileReadAtZeroLength run.go:181: Remote "premiumize.me root 'rclone-test-xamodak6kusezur6soxepad4'", Local "Local file system at /tmp/rclone1953377061", Modify Window "876000h0m0s" 2022/05/21 05:47:15 INFO : premiumize.me root 'rclone-test-xamodak6kusezur6soxepad4': poll-interval is not supported by this remote 2022/05/21 05:47:15 NOTICE: premiumize.me root 'rclone-test-xamodak6kusezur6soxepad4': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2022/05/21 05:47:15 DEBUG : file1: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2022/05/21 05:47:15 DEBUG : file1: Open: flags=O_WRONLY|O_CREATE 2022/05/21 05:47:15 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:47:15 DEBUG : file1: >Open: fd=file1 (w), err= 2022/05/21 05:47:15 DEBUG : file1: >OpenFile: fd=file1 (w), err= 2022/05/21 05:47:15 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:47:15 DEBUG : premiumize.me root 'rclone-test-xamodak6kusezur6soxepad4': File to upload is small (0 bytes), uploading instead of streaming 2022/05/21 05:47:16 INFO : file1: Copied (new) 2022/05/21 05:47:16 DEBUG : : Added virtual directory entry vAddFile: "file1" 2022/05/21 05:47:16 DEBUG : file1: OpenFile: flags=O_RDONLY, perm=---------- 2022/05/21 05:47:16 DEBUG : file1: Open: flags=O_RDONLY 2022/05/21 05:47:16 DEBUG : file1: >Open: fd=file1 (r), err= 2022/05/21 05:47:16 DEBUG : file1: >OpenFile: fd=file1 (r), err= 2022/05/21 05:47:16 DEBUG : file1: ChunkedReader.openRange at 0 length 134217728 2022/05/21 05:48:16 DEBUG : pacer: low level retry 1/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:48:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2022/05/21 05:49:16 DEBUG : pacer: low level retry 2/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:49:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2022/05/21 05:50:16 DEBUG : pacer: low level retry 3/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:50:16 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2022/05/21 05:51:16 DEBUG : pacer: low level retry 4/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:51:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2022/05/21 05:52:16 DEBUG : pacer: low level retry 5/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:52:16 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2022/05/21 05:53:16 DEBUG : pacer: low level retry 6/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:53:16 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2022/05/21 05:54:16 DEBUG : pacer: low level retry 7/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:54:16 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2022/05/21 05:55:16 DEBUG : pacer: low level retry 8/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:55:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2022/05/21 05:56:16 DEBUG : pacer: low level retry 9/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout) 2022/05/21 05:57:16 DEBUG : pacer: low level retry 10/10 (error Get "https://attractivelion-sto.energycdn.com/dl/Mz_BOWq3YeDQismfJOO0sQ/1653716836/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/Mz_BOWq3YeDQismfJOO0sQ/1653716836/432376583/5a42d1a93b16f7.97386577/file1": dial tcp 168.119.89.165:443: i/o timeout Test: TestFileReadAtZeroLength 2022/05/21 05:57:16 DEBUG : WaitForWriters: timeout=30s 2022/05/21 05:57:16 DEBUG : : Looking for writers 2022/05/21 05:57:16 DEBUG : file1: reading active writers 2022/05/21 05:57:16 DEBUG : >WaitForWriters: 2022/05/21 05:57:16 DEBUG : pacer: Reducing sleep to 1.5s 2022/05/21 05:57:18 DEBUG : pacer: Reducing sleep to 1.125s 2022/05/21 05:57:19 DEBUG : pacer: Reducing sleep to 843.75ms --- FAIL: TestFileReadAtZeroLength (604.22s) FAIL 2022/05/21 05:57:19 DEBUG : premiumize.me root 'rclone-test-xamodak6kusezur6soxepad4': Purge remote 2022/05/21 05:57:20 DEBUG : pacer: Reducing sleep to 632.8125ms "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPremiumizeMe: -verbose -test.run '^TestFileReadAtZeroLength$'" - Finished ERROR in 10m5.567209808s (try 5/5): exit status 1: Failed [TestFileReadAtZeroLength]