"./vfs.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Starting (try 2/5) 2025/09/17 05:44:24 DEBUG : Creating backend with remote "TestUlozto:rclone-test-sulayag5yoxu" 2025/09/17 05:44:24 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/17 05:44:27 DEBUG : Creating backend with remote "/tmp/rclone3784650746" === RUN TestDirHandleMethods run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:27 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:27 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:32 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:32 DEBUG : dir: Looking for writers 2025/09/17 05:44:32 DEBUG : Looking for writers 2025/09/17 05:44:32 DEBUG : dir: reading active writers 2025/09/17 05:44:32 DEBUG : >WaitForWriters: --- PASS: TestDirHandleMethods (7.04s) === RUN TestDirHandleReaddir run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:34 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:38 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:38 DEBUG : dir/subdir: Looking for writers 2025/09/17 05:44:38 DEBUG : dir: Looking for writers 2025/09/17 05:44:38 DEBUG : file1: reading active writers 2025/09/17 05:44:38 DEBUG : file2: reading active writers 2025/09/17 05:44:38 DEBUG : subdir: reading active writers 2025/09/17 05:44:38 DEBUG : Looking for writers 2025/09/17 05:44:38 DEBUG : dir: reading active writers 2025/09/17 05:44:38 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddir (6.58s) === RUN TestDirHandleReaddirnames run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:40 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:40 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:43 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:43 DEBUG : dir: Looking for writers 2025/09/17 05:44:43 DEBUG : file1: reading active writers 2025/09/17 05:44:43 DEBUG : Looking for writers 2025/09/17 05:44:43 DEBUG : dir: reading active writers 2025/09/17 05:44:43 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddirnames (3.66s) === RUN TestDirMethods run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:44 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:44 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:47 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:47 DEBUG : dir: Looking for writers 2025/09/17 05:44:47 DEBUG : Looking for writers 2025/09/17 05:44:47 DEBUG : dir: reading active writers 2025/09/17 05:44:47 DEBUG : >WaitForWriters: --- PASS: TestDirMethods (3.98s) === RUN TestDirForgetAll run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:48 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:48 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:50 DEBUG : dir: forgetting directory cache 2025/09/17 05:44:50 DEBUG : forgetting directory cache 2025/09/17 05:44:50 DEBUG : dir: forgetting directory cache 2025/09/17 05:44:50 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:50 DEBUG : Looking for writers 2025/09/17 05:44:50 DEBUG : >WaitForWriters: --- PASS: TestDirForgetAll (3.24s) === RUN TestDirForgetPath run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:51 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:51 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:53 DEBUG : ForgetPath: relativePath="dir/notfound", type=1 2025/09/17 05:44:53 DEBUG : dir: invalidating directory cache 2025/09/17 05:44:53 DEBUG : >ForgetPath: 2025/09/17 05:44:53 DEBUG : ForgetPath: relativePath="dir", type=0 2025/09/17 05:44:53 DEBUG : invalidating directory cache 2025/09/17 05:44:53 DEBUG : dir: forgetting directory cache 2025/09/17 05:44:53 DEBUG : >ForgetPath: 2025/09/17 05:44:53 DEBUG : ForgetPath: relativePath="not/in/cache", type=0 2025/09/17 05:44:53 DEBUG : >ForgetPath: 2025/09/17 05:44:53 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:44:53 DEBUG : dir: Looking for writers 2025/09/17 05:44:53 DEBUG : Looking for writers 2025/09/17 05:44:53 DEBUG : dir: reading active writers 2025/09/17 05:44:53 DEBUG : >WaitForWriters: --- PASS: TestDirForgetPath (3.25s) === RUN TestDirWalk run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:44:54 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:44:54 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:44:59 DEBUG : forgetting directory cache 2025/09/17 05:44:59 DEBUG : dir: forgetting directory cache 2025/09/17 05:45:00 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:00 DEBUG : fil/a/b: Looking for writers 2025/09/17 05:45:00 DEBUG : fil/a: Looking for writers 2025/09/17 05:45:00 DEBUG : b: reading active writers 2025/09/17 05:45:00 DEBUG : fil: Looking for writers 2025/09/17 05:45:00 DEBUG : a: reading active writers 2025/09/17 05:45:00 DEBUG : dir: Looking for writers 2025/09/17 05:45:00 DEBUG : Looking for writers 2025/09/17 05:45:00 DEBUG : dir: reading active writers 2025/09/17 05:45:00 DEBUG : fil: reading active writers 2025/09/17 05:45:00 DEBUG : >WaitForWriters: --- PASS: TestDirWalk (8.15s) === RUN TestDirSetModTime run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:03 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:03 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:05 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:05 DEBUG : dir: Looking for writers 2025/09/17 05:45:05 DEBUG : Looking for writers 2025/09/17 05:45:05 DEBUG : dir: reading active writers 2025/09/17 05:45:05 DEBUG : >WaitForWriters: --- PASS: TestDirSetModTime (3.09s) === RUN TestDirStat run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:06 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:06 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:08 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:08 DEBUG : dir: Looking for writers 2025/09/17 05:45:08 DEBUG : file1: reading active writers 2025/09/17 05:45:08 DEBUG : Looking for writers 2025/09/17 05:45:08 DEBUG : dir: reading active writers 2025/09/17 05:45:08 DEBUG : >WaitForWriters: --- PASS: TestDirStat (3.25s) === RUN TestDirReadDirAll run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:09 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:09 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream === RUN TestDirReadDirAll/Virtual 2025/09/17 05:45:13 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2025/09/17 05:45:13 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2025/09/17 05:45:13 DEBUG : dir: Added virtual directory entry vDel: "file2" 2025/09/17 05:45:13 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2025/09/17 05:45:16 DEBUG : Waiting for deletions to finish 2025/09/17 05:45:16 INFO : dir/subdir/file3: Deleted 2025/09/17 05:45:16 DEBUG : removing 1 level 2 directories 2025/09/17 05:45:16 INFO : dir/subdir: Removing directory 2025/09/17 05:45:16 DEBUG : dir: invalidating directory cache 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2025/09/17 05:45:17 DEBUG : dir/file1: Reset virtual modtime 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2025/09/17 05:45:17 DEBUG : dir/virtualFile: Reset virtual modtime 2025/09/17 05:45:17 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2025/09/17 05:45:17 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2025/09/17 05:45:17 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/17 05:45:17 DEBUG : dir: invalidating directory cache 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2025/09/17 05:45:17 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2025/09/17 05:45:17 DEBUG : dir/virtualFile: Reset virtual modtime 2025/09/17 05:45:17 DEBUG : forgetting directory cache 2025/09/17 05:45:17 DEBUG : dir: forgetting directory cache 2025/09/17 05:45:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 05:45:17 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:17 DEBUG : dir/virtualDir: Looking for writers 2025/09/17 05:45:17 DEBUG : dir: Looking for writers 2025/09/17 05:45:17 DEBUG : file1: reading active writers 2025/09/17 05:45:17 DEBUG : virtualFile2: reading active writers 2025/09/17 05:45:17 DEBUG : virtualFile: reading active writers 2025/09/17 05:45:17 DEBUG : virtualDir: reading active writers 2025/09/17 05:45:17 DEBUG : Looking for writers 2025/09/17 05:45:17 DEBUG : dir: reading active writers 2025/09/17 05:45:17 DEBUG : >WaitForWriters: --- PASS: TestDirReadDirAll (10.12s) --- PASS: TestDirReadDirAll/Virtual (3.52s) === RUN TestDirOpen run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:19 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:19 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:21 ERROR : dir/: Can only open directories read only 2025/09/17 05:45:21 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:21 DEBUG : dir: Looking for writers 2025/09/17 05:45:21 DEBUG : Looking for writers 2025/09/17 05:45:21 DEBUG : dir: reading active writers 2025/09/17 05:45:21 DEBUG : >WaitForWriters: --- PASS: TestDirOpen (3.04s) === RUN TestDirCreate run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:22 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:22 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:24 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2025/09/17 05:45:24 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/09/17 05:45:24 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2025/09/17 05:45:24 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/09/17 05:45:24 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': File to upload is small (5 bytes), uploading instead of streaming 2025/09/17 05:45:25 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/09/17 05:45:25 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2025/09/17 05:45:25 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2025/09/17 05:45:25 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:25 DEBUG : dir: Looking for writers 2025/09/17 05:45:25 DEBUG : file1: reading active writers 2025/09/17 05:45:25 DEBUG : potato: reading active writers 2025/09/17 05:45:25 DEBUG : Looking for writers 2025/09/17 05:45:25 DEBUG : dir: reading active writers 2025/09/17 05:45:25 DEBUG : >WaitForWriters: --- PASS: TestDirCreate (4.26s) === RUN TestDirMkdir run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:26 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:26 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:29 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/09/17 05:45:30 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:30 DEBUG : dir/sub: Looking for writers 2025/09/17 05:45:30 DEBUG : dir: Looking for writers 2025/09/17 05:45:30 DEBUG : file1: reading active writers 2025/09/17 05:45:30 DEBUG : sub: reading active writers 2025/09/17 05:45:30 DEBUG : Looking for writers 2025/09/17 05:45:30 DEBUG : dir: reading active writers 2025/09/17 05:45:30 DEBUG : >WaitForWriters: --- PASS: TestDirMkdir (5.18s) === RUN TestDirMkdirSub run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:32 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:32 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:34 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/09/17 05:45:35 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" 2025/09/17 05:45:36 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:36 DEBUG : dir/sub/subsub: Looking for writers 2025/09/17 05:45:36 DEBUG : dir/sub: Looking for writers 2025/09/17 05:45:36 DEBUG : subsub: reading active writers 2025/09/17 05:45:36 DEBUG : dir: Looking for writers 2025/09/17 05:45:36 DEBUG : file1: reading active writers 2025/09/17 05:45:36 DEBUG : sub: reading active writers 2025/09/17 05:45:36 DEBUG : Looking for writers 2025/09/17 05:45:36 DEBUG : dir: reading active writers 2025/09/17 05:45:36 DEBUG : >WaitForWriters: --- PASS: TestDirMkdirSub (6.30s) === RUN TestDirRemove run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:38 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:38 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:41 ERROR : dir/: Dir.Remove not empty 2025/09/17 05:45:41 DEBUG : dir/file1: Remove: 2025/09/17 05:45:41 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/17 05:45:41 DEBUG : dir/file1: >Remove: err= 2025/09/17 05:45:41 DEBUG : Added virtual directory entry vDel: "dir" 2025/09/17 05:45:42 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:42 DEBUG : Looking for writers 2025/09/17 05:45:42 DEBUG : >WaitForWriters: --- PASS: TestDirRemove (4.27s) === RUN TestDirRemoveAll run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:42 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:42 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:44 DEBUG : dir/file1: Remove: 2025/09/17 05:45:44 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/17 05:45:44 DEBUG : dir/file1: >Remove: err= 2025/09/17 05:45:45 DEBUG : Added virtual directory entry vDel: "dir" 2025/09/17 05:45:45 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:45 DEBUG : Looking for writers 2025/09/17 05:45:45 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveAll (3.28s) === RUN TestDirRemoveName run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:45 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:45 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:49 DEBUG : dir/file1: Remove: 2025/09/17 05:45:49 DEBUG : dir: Added virtual directory entry vDel: "file1" 2025/09/17 05:45:49 DEBUG : dir/file1: >Remove: err= 2025/09/17 05:45:49 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:49 DEBUG : dir: Looking for writers 2025/09/17 05:45:49 DEBUG : Looking for writers 2025/09/17 05:45:49 DEBUG : dir: reading active writers 2025/09/17 05:45:49 DEBUG : >WaitForWriters: --- PASS: TestDirRemoveName (4.97s) === RUN TestDirRename run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:50 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:50 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:45:54 ERROR : dir/not found: Dir.Rename error: file does not exist 2025/09/17 05:45:54 DEBUG : dir: Updating dir with dir2 0xc0009dd860 2025/09/17 05:45:54 DEBUG : dir: forgetting directory cache 2025/09/17 05:45:54 DEBUG : Added virtual directory entry vDel: "dir" 2025/09/17 05:45:54 DEBUG : Added virtual directory entry vAddDir: "dir2" 2025/09/17 05:45:55 INFO : dir2/file1: Moved (server-side) to: file2 2025/09/17 05:45:55 DEBUG : file2: Updating file with file2 0xc00046d380 2025/09/17 05:45:55 DEBUG : dir2: Added virtual directory entry vDel: "file1" 2025/09/17 05:45:55 DEBUG : Added virtual directory entry vAddFile: "file2" 2025/09/17 05:45:56 INFO : dir2/file3: Deleted 2025/09/17 05:45:56 INFO : file2: Moved (server-side) to: dir2/file3 2025/09/17 05:45:56 DEBUG : dir2/file3: Updating file with dir2/file3 0xc00046d380 2025/09/17 05:45:56 DEBUG : Added virtual directory entry vDel: "file2" 2025/09/17 05:45:56 DEBUG : dir2: Added virtual directory entry vAddFile: "file3" 2025/09/17 05:45:57 DEBUG : Added virtual directory entry vAddDir: "empty directory" 2025/09/17 05:45:57 DEBUG : empty directory: Updating dir with renamed empty directory 0xc000a30dd0 2025/09/17 05:45:57 DEBUG : empty directory: forgetting directory cache 2025/09/17 05:45:57 DEBUG : Added virtual directory entry vDel: "empty directory" 2025/09/17 05:45:57 DEBUG : Added virtual directory entry vAddDir: "renamed empty directory" 2025/09/17 05:45:57 DEBUG : dir2: Renaming to "dir3" 2025/09/17 05:45:57 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:45:57 DEBUG : dir3: Looking for writers 2025/09/17 05:45:57 DEBUG : file3: reading active writers 2025/09/17 05:45:57 DEBUG : renamed empty directory: Looking for writers 2025/09/17 05:45:57 DEBUG : Looking for writers 2025/09/17 05:45:57 DEBUG : dir3: reading active writers 2025/09/17 05:45:57 DEBUG : renamed empty directory: reading active writers 2025/09/17 05:45:57 DEBUG : >WaitForWriters: --- PASS: TestDirRename (8.64s) === RUN TestDirStructSize dir_test.go:611: Dir struct has size 200 bytes --- PASS: TestDirStructSize (0.00s) === RUN TestDirFileOpen run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:45:59 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:45:59 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:01 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2025/09/17 05:46:01 DEBUG : dir/sub/file0: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/09/17 05:46:02 DEBUG : dir/sub/file0: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/09/17 05:46:02 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/09/17 05:46:02 DEBUG : dir/sub/file0: >Open: fd=dir/sub/file0 (w), err= 2025/09/17 05:46:02 DEBUG : dir/sub/file0: >OpenFile: fd=dir/sub/file0 (w), err= 2025/09/17 05:46:02 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/09/17 05:46:02 DEBUG : dir/sub/file2: OpenFile: flags=O_RDWR|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2025/09/17 05:46:02 DEBUG : dir/sub/file2: Open: flags=O_RDWR|O_CREATE|O_TRUNC 2025/09/17 05:46:02 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/09/17 05:46:02 DEBUG : dir/sub/file2: >Open: fd=dir/sub/file2 (w), err= 2025/09/17 05:46:02 DEBUG : dir/sub/file2: >OpenFile: fd=dir/sub/file2 (w), err= 2025/09/17 05:46:02 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/09/17 05:46:02 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': File to upload is small (12 bytes), uploading instead of streaming 2025/09/17 05:46:02 DEBUG : dir/sub/file2: md5 = fc3ff98e8c6a0d3087d515c0473f8677 OK 2025/09/17 05:46:02 DEBUG : dir/sub/file2: Size and md5 of src and dst objects identical 2025/09/17 05:46:02 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file2" 2025/09/17 05:46:02 DEBUG : forgetting directory cache 2025/09/17 05:46:02 DEBUG : dir: forgetting directory cache 2025/09/17 05:46:02 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:46:02 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file2" 2025/09/17 05:46:02 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/09/17 05:46:02 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': File to upload is small (5 bytes), uploading instead of streaming 2025/09/17 05:46:03 DEBUG : dir/sub/file0: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/09/17 05:46:03 DEBUG : dir/sub/file0: Size and md5 of src and dst objects identical 2025/09/17 05:46:03 DEBUG : dir/sub: Added virtual directory entry vAddFile: "file0" 2025/09/17 05:46:03 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:03 DEBUG : dir/sub: Looking for writers 2025/09/17 05:46:03 DEBUG : file0: reading active writers 2025/09/17 05:46:03 DEBUG : file2: reading active writers 2025/09/17 05:46:03 DEBUG : dir: Looking for writers 2025/09/17 05:46:03 DEBUG : file1: reading active writers 2025/09/17 05:46:03 DEBUG : sub: reading active writers 2025/09/17 05:46:03 DEBUG : Looking for writers 2025/09/17 05:46:03 DEBUG : dir: reading active writers 2025/09/17 05:46:03 DEBUG : >WaitForWriters: --- PASS: TestDirFileOpen (6.31s) === RUN TestDirEntryModTimeInvalidation run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:05 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:05 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream dir_test.go:666: Need DirModTimeUpdatesOnWrite 2025/09/17 05:46:05 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:05 DEBUG : Looking for writers 2025/09/17 05:46:05 DEBUG : >WaitForWriters: --- SKIP: TestDirEntryModTimeInvalidation (0.44s) === RUN TestDirMetadataExtension run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:06 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:06 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:08 DEBUG : dir: Added virtual directory entry vAddFile: "file1.metadata" 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: Open: flags=O_RDONLY 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: >Open: fd=dir/file1.metadata (r), err= 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: >OpenFile: fd=dir/file1.metadata (r), err= 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: ChunkedReader.openRange at 0 length 134217728 2025/09/17 05:46:08 DEBUG : dir/file1.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/09/17 05:46:08 DEBUG : Added virtual directory entry vAddFile: "dir.metadata" 2025/09/17 05:46:08 DEBUG : dir.metadata: OpenFile: flags=O_RDONLY, perm=---------- 2025/09/17 05:46:08 DEBUG : dir.metadata: Open: flags=O_RDONLY 2025/09/17 05:46:08 DEBUG : dir.metadata: >Open: fd=dir.metadata (r), err= 2025/09/17 05:46:08 DEBUG : dir.metadata: >OpenFile: fd=dir.metadata (r), err= 2025/09/17 05:46:08 DEBUG : dir.metadata: ChunkedReader.openRange at 0 length 134217728 2025/09/17 05:46:08 DEBUG : dir.metadata: ChunkedReader.Read at 0 length 512 chunkOffset 0 chunkSize 134217728 2025/09/17 05:46:08 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:08 DEBUG : dir: Looking for writers 2025/09/17 05:46:08 DEBUG : file1: reading active writers 2025/09/17 05:46:08 DEBUG : file1.metadata: reading active writers 2025/09/17 05:46:08 DEBUG : Looking for writers 2025/09/17 05:46:08 DEBUG : dir.metadata: reading active writers 2025/09/17 05:46:08 DEBUG : dir: reading active writers 2025/09/17 05:46:08 DEBUG : >WaitForWriters: --- PASS: TestDirMetadataExtension (3.26s) === RUN TestErrorError --- PASS: TestErrorError (0.00s) === RUN TestFileMethods run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:09 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:09 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:11 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:11 DEBUG : dir: Looking for writers 2025/09/17 05:46:11 DEBUG : file1: reading active writers 2025/09/17 05:46:11 DEBUG : Looking for writers 2025/09/17 05:46:11 DEBUG : dir: reading active writers 2025/09/17 05:46:11 DEBUG : >WaitForWriters: --- PASS: TestFileMethods (3.18s) === RUN TestFileSetModTime === RUN TestFileSetModTime/cache=off,open=false,write=false run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:12 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:12 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:15 DEBUG : Can set mod time: true 2025/09/17 05:46:16 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/09/17 05:46:16 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:16 DEBUG : dir: Looking for writers 2025/09/17 05:46:16 DEBUG : file1: reading active writers 2025/09/17 05:46:16 DEBUG : Looking for writers 2025/09/17 05:46:16 DEBUG : dir: reading active writers 2025/09/17 05:46:16 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=false run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:17 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:17 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:19 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/09/17 05:46:19 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/09/17 05:46:19 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/17 05:46:19 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': File to upload is small (0 bytes), uploading instead of streaming 2025/09/17 05:46:20 DEBUG : dir/file1: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/17 05:46:20 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/09/17 05:46:20 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/09/17 05:46:20 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/17 05:46:20 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:20 DEBUG : dir: Looking for writers 2025/09/17 05:46:20 DEBUG : file1: reading active writers 2025/09/17 05:46:20 DEBUG : Looking for writers 2025/09/17 05:46:20 DEBUG : dir: reading active writers 2025/09/17 05:46:20 DEBUG : >WaitForWriters: 2025/09/17 05:46:21 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:21 DEBUG : dir: Looking for writers 2025/09/17 05:46:21 DEBUG : file1: reading active writers 2025/09/17 05:46:21 DEBUG : Looking for writers 2025/09/17 05:46:21 DEBUG : dir: reading active writers 2025/09/17 05:46:21 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=off,open=true,write=true run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:22 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:22 NOTICE: uloz.to root 'rclone-test-sulayag5yoxu': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2025/09/17 05:46:24 DEBUG : dir/file1: Open: flags=O_WRONLY|O_TRUNC 2025/09/17 05:46:24 DEBUG : dir/file1: >Open: fd=dir/file1 (w), err= 2025/09/17 05:46:24 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/17 05:46:24 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': File to upload is small (5 bytes), uploading instead of streaming 2025/09/17 05:46:25 DEBUG : dir/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2025/09/17 05:46:25 DEBUG : dir/file1: Size and md5 of src and dst objects identical 2025/09/17 05:46:25 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/09/17 05:46:25 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/17 05:46:25 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:25 DEBUG : dir: Looking for writers 2025/09/17 05:46:25 DEBUG : file1: reading active writers 2025/09/17 05:46:25 DEBUG : Looking for writers 2025/09/17 05:46:25 DEBUG : dir: reading active writers 2025/09/17 05:46:25 DEBUG : >WaitForWriters: 2025/09/17 05:46:25 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:25 DEBUG : dir: Looking for writers 2025/09/17 05:46:25 DEBUG : file1: reading active writers 2025/09/17 05:46:25 DEBUG : Looking for writers 2025/09/17 05:46:25 DEBUG : dir: reading active writers 2025/09/17 05:46:25 DEBUG : >WaitForWriters: === RUN TestFileSetModTime/cache=full,open=false,write=false run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:27 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:27 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: root is "/home/rclone/.cache/rclone" 2025/09/17 05:46:27 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:27 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:27 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/09/17 05:46:29 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/09/17 05:46:29 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:29 DEBUG : dir: Looking for writers 2025/09/17 05:46:29 DEBUG : file1: reading active writers 2025/09/17 05:46:29 DEBUG : Looking for writers 2025/09/17 05:46:29 DEBUG : dir: reading active writers 2025/09/17 05:46:29 DEBUG : >WaitForWriters: 2025/09/17 05:46:29 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=false run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:30 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:30 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: root is "/home/rclone/.cache/rclone" 2025/09/17 05:46:30 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:30 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:30 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/09/17 05:46:32 DEBUG : dir/file1: Open: flags=O_WRONLY 2025/09/17 05:46:32 DEBUG : dir/file1: newRWFileHandle: 2025/09/17 05:46:32 DEBUG : dir/file1: >newRWFileHandle: err= 2025/09/17 05:46:32 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err= 2025/09/17 05:46:32 DEBUG : dir/file1(0xc0008d0b00): close: 2025/09/17 05:46:32 DEBUG : dir/file1: Applied pending mod time 2011-12-25 12:59:59.123456789 +0000 UTC OK 2025/09/17 05:46:32 DEBUG : dir/file1(0xc0008d0b00): >close: err= 2025/09/17 05:46:32 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:32 DEBUG : dir: Looking for writers 2025/09/17 05:46:32 DEBUG : file1: reading active writers 2025/09/17 05:46:32 DEBUG : Looking for writers 2025/09/17 05:46:32 DEBUG : dir: reading active writers 2025/09/17 05:46:32 DEBUG : >WaitForWriters: 2025/09/17 05:46:33 DEBUG : WaitForWriters: timeout=30s 2025/09/17 05:46:33 DEBUG : dir: Looking for writers 2025/09/17 05:46:33 DEBUG : file1: reading active writers 2025/09/17 05:46:33 DEBUG : Looking for writers 2025/09/17 05:46:33 DEBUG : dir: reading active writers 2025/09/17 05:46:33 DEBUG : >WaitForWriters: 2025/09/17 05:46:33 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaner exiting === RUN TestFileSetModTime/cache=full,open=true,write=true run.go:180: Remote "uloz.to root 'rclone-test-sulayag5yoxu'", Local "Local file system at /tmp/rclone3784650746", Modify Window "1µs" 2025/09/17 05:46:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': poll-interval is not supported by this remote 2025/09/17 05:46:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: root is "/home/rclone/.cache/rclone" 2025/09/17 05:46:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: data root is "/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: metadata root is "/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfs/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 DEBUG : Creating backend with remote ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 DEBUG : :local: detected overridden config - adding "{8un-i}" suffix to name 2025/09/17 05:46:34 DEBUG : fs cache: renaming cache item ":local,encoding='Slash,Dot',links=false:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" to be canonical ":local{8un-i}:/home/rclone/.cache/rclone/vfsMeta/TestUlozto/rclone-test-sulayag5yoxu" 2025/09/17 05:46:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 0 (was 0) in use 0, to upload 0, uploading 0, total size 0 (was 0) 2025/09/17 05:46:36 DEBUG : dir/file1: Open: flags=O_WRONLY 2025/09/17 05:46:36 DEBUG : dir/file1: newRWFileHandle: 2025/09/17 05:46:36 DEBUG : dir/file1: >newRWFileHandle: err= 2025/09/17 05:46:36 DEBUG : dir/file1: >Open: fd=dir/file1 (rw), err= 2025/09/17 05:46:36 DEBUG : dir/file1(0xc000447300): _writeAt: size=5, off=0 2025/09/17 05:46:36 DEBUG : dir/file1(0xc000447300): openPending: 2025/09/17 05:46:36 DEBUG : dir/file1: vfs cache: checking remote fingerprint "14,2001-02-03 04:05:06.499999 +0000 UTC,0ef726ce9b1a7692357ff70dd321d595" against cached fingerprint "" 2025/09/17 05:46:36 DEBUG : dir/file1: vfs cache: truncate to size=14 2025/09/17 05:46:36 DEBUG : dir: Added virtual directory entry vAddFile: "file1" 2025/09/17 05:46:36 DEBUG : dir/file1(0xc000447300): >openPending: err= 2025/09/17 05:46:36 DEBUG : dir/file1(0xc000447300): >_writeAt: n=5, err= 2025/09/17 05:46:36 DEBUG : dir/file1: vfs cache: setting modification time to 2011-12-25 12:59:59.123456789 +0000 UTC 2025/09/17 05:46:36 DEBUG : dir/file1(0xc000447300): close: 2025/09/17 05:46:36 DEBUG : vfs cache: looking for range={Pos:0 Size:14} in [{Pos:0 Size:5}] - present false 2025/09/17 05:46:36 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 05:46:36 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 05:46:36 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 05:47:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:47:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:47:36 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:47:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2025/09/17 05:48:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:48:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:49:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:49:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:50:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:50:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:51:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:51:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:52:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:52:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:53:12 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: timeout awaiting response headers) 2025/09/17 05:53:12 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2025/09/17 05:53:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:53:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:54:13 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:54:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2025/09/17 05:54:32 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:32 DEBUG : forgetting directory cache 2025/09/17 05:54:32 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:54:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:54:38 DEBUG : forgetting directory cache 2025/09/17 05:54:38 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:38 DEBUG : dir/subdir: forgetting directory cache 2025/09/17 05:54:43 DEBUG : forgetting directory cache 2025/09/17 05:54:43 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:47 DEBUG : forgetting directory cache 2025/09/17 05:54:47 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:47 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:53 DEBUG : forgetting directory cache 2025/09/17 05:54:53 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:59 DEBUG : forgetting directory cache 2025/09/17 05:54:59 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:59 DEBUG : dir: forgetting directory cache 2025/09/17 05:54:59 DEBUG : fil: forgetting directory cache 2025/09/17 05:54:59 DEBUG : fil/a: forgetting directory cache 2025/09/17 05:54:59 DEBUG : fil/a/b: forgetting directory cache 2025/09/17 05:55:05 DEBUG : forgetting directory cache 2025/09/17 05:55:05 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:05 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:08 DEBUG : forgetting directory cache 2025/09/17 05:55:08 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:13 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:55:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2025/09/17 05:55:13 DEBUG : dir/subdir: forgetting directory cache 2025/09/17 05:55:17 DEBUG : dir/virtualDir2: forgetting directory cache 2025/09/17 05:55:17 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 05:55:17 DEBUG : forgetting directory cache 2025/09/17 05:55:17 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 05:55:21 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:21 DEBUG : forgetting directory cache 2025/09/17 05:55:21 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:24 DEBUG : forgetting directory cache 2025/09/17 05:55:24 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:24 DEBUG : dir: Removed virtual directory entry vAddFile: "potato" 2025/09/17 05:55:28 DEBUG : forgetting directory cache 2025/09/17 05:55:28 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:28 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:55:28 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/09/17 05:55:33 DEBUG : forgetting directory cache 2025/09/17 05:55:33 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:33 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:55:33 DEBUG : dir/sub/subsub: forgetting directory cache 2025/09/17 05:55:33 DEBUG : dir/sub: Removed virtual directory entry vAddDir: "subsub" 2025/09/17 05:55:33 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2025/09/17 05:55:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:55:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:55:40 DEBUG : forgetting directory cache 2025/09/17 05:55:40 DEBUG : Removed virtual directory entry vDel: "dir" 2025/09/17 05:55:41 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:41 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2025/09/17 05:55:44 DEBUG : forgetting directory cache 2025/09/17 05:55:44 DEBUG : Removed virtual directory entry vDel: "dir" 2025/09/17 05:55:44 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:44 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2025/09/17 05:55:48 DEBUG : forgetting directory cache 2025/09/17 05:55:48 DEBUG : dir: forgetting directory cache 2025/09/17 05:55:48 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2025/09/17 05:55:52 DEBUG : forgetting directory cache 2025/09/17 05:55:52 DEBUG : dir3: forgetting directory cache 2025/09/17 05:55:52 DEBUG : dir3: Removed virtual directory entry vDel: "file1" 2025/09/17 05:55:52 DEBUG : dir3: Removed virtual directory entry vAddFile: "file3" 2025/09/17 05:55:52 DEBUG : renamed empty directory: forgetting directory cache 2025/09/17 05:55:52 DEBUG : Removed virtual directory entry vDel: "dir" 2025/09/17 05:55:52 DEBUG : Removed virtual directory entry vAddDir: "dir2" 2025/09/17 05:55:52 DEBUG : Removed virtual directory entry vDel: "file2" 2025/09/17 05:55:52 DEBUG : Removed virtual directory entry vDel: "empty directory" 2025/09/17 05:55:52 DEBUG : Removed virtual directory entry vAddDir: "renamed empty directory" 2025/09/17 05:56:02 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:56:02 DEBUG : dir/sub: Removed virtual directory entry vAddFile: "file0" 2025/09/17 05:56:02 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:02 DEBUG : forgetting directory cache 2025/09/17 05:56:02 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:56:02 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:02 DEBUG : dir/sub: forgetting directory cache 2025/09/17 05:56:05 DEBUG : forgetting directory cache 2025/09/17 05:56:08 DEBUG : forgetting directory cache 2025/09/17 05:56:08 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:08 DEBUG : dir: Removed virtual directory entry vAddFile: "file1.metadata" 2025/09/17 05:56:08 DEBUG : Removed virtual directory entry vAddFile: "dir.metadata" 2025/09/17 05:56:11 DEBUG : forgetting directory cache 2025/09/17 05:56:11 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:13 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:56:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2025/09/17 05:56:14 DEBUG : forgetting directory cache 2025/09/17 05:56:14 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:19 DEBUG : forgetting directory cache 2025/09/17 05:56:19 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:19 DEBUG : dir: Removed virtual directory entry vAddFile: "file1" 2025/09/17 05:56:24 DEBUG : forgetting directory cache 2025/09/17 05:56:24 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:24 DEBUG : dir: Removed virtual directory entry vAddFile: "file1" 2025/09/17 05:56:28 DEBUG : forgetting directory cache 2025/09/17 05:56:28 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:32 DEBUG : forgetting directory cache 2025/09/17 05:56:32 DEBUG : dir: forgetting directory cache 2025/09/17 05:56:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:56:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:56:36 DEBUG : forgetting directory cache 2025/09/17 05:56:36 DEBUG : dir: forgetting directory cache 2025/09/17 05:57:13 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=422430854;tm=1758088596;ut=vd;rs=0;fet=download_cloud;He;ch=d2c6d70cf2a0b05da54701527163708f;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:57:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2025/09/17 05:57:13 DEBUG : pacer: Reducing sleep to 480ms 2025/09/17 05:57:13 INFO : dir/file1: vfs cache: downloader: error count now 1: vfs reader: failed to write to cache file: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n \n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 05:57:13 ERROR : dir/file1: vfs cache: failed to download: vfs reader: failed to write to cache file: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n \n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 05:57:13 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 05:57:13 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 05:57:13 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 05:57:14 DEBUG : pacer: Reducing sleep to 360ms 2025/09/17 05:57:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:57:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:58:14 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 05:58:14 DEBUG : pacer: Rate limited, increasing sleep to 720ms 2025/09/17 05:58:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:58:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 05:59:16 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 05:59:16 DEBUG : pacer: Rate limited, increasing sleep to 1.44s 2025/09/17 05:59:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 05:59:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:00:16 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:00:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 06:00:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:00:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:01:18 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:01:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:01:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:02:18 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:02:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:02:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:03:18 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:03:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:03:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:04:19 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:04:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:04:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:05:17 DEBUG : forgetting directory cache 2025/09/17 06:05:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:05:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:05:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:05:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:05:20 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:05:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:05:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:05:40 DEBUG : forgetting directory cache 2025/09/17 06:05:44 DEBUG : forgetting directory cache 2025/09/17 06:06:20 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:06:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:06:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:06:36 DEBUG : forgetting directory cache 2025/09/17 06:06:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:06:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:07:20 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:07:20 INFO : dir/file1: vfs cache: downloader: error count now 2: vfs reader: failed to write to cache file: Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout 2025/09/17 06:07:20 ERROR : dir/file1: vfs cache: failed to download: vfs reader: failed to write to cache file: Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=981241078;tm=1758089234;ut=vd;rs=0;fet=download_cloud;He;ch=5e089dc37a575312871bf8a4090dcfee;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout 2025/09/17 06:07:20 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 06:07:20 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 06:07:20 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 06:07:20 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 06:07:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:07:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:08:22 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:08:22 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 06:08:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:08:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:09:23 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:09:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:09:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:10:23 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:10:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:10:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:11:24 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:11:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:11:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:12:24 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:12:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:12:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:13:24 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:13:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:13:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:14:24 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:14:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:14:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:15:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:15:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:15:17 DEBUG : forgetting directory cache 2025/09/17 06:15:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:15:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:15:24 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:15:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:15:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:16:25 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:16:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:16:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:16:36 DEBUG : forgetting directory cache 2025/09/17 06:16:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:16:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:17:26 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:17:26 INFO : dir/file1: vfs cache: downloader: error count now 3: vfs reader: failed to write to cache file: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout 2025/09/17 06:17:26 ERROR : dir/file1: vfs cache: failed to download: vfs reader: failed to write to cache file: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1378857849;tm=1758089840;ut=vd;rs=0;fet=download_cloud;He;ch=6a195f3d2249005996c85fd4325eeb1d;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout 2025/09/17 06:17:26 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 06:17:26 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 06:17:26 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 06:17:26 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 06:17:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:17:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:18:28 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:18:28 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 06:18:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:18:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:19:28 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:19:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:19:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:20:28 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:20:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:20:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:21:30 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:21:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:21:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:22:30 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:22:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:22:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:23:30 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:23:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:23:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:24:30 DEBUG : pacer: low level retry 7/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) 2025/09/17 06:24:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:24:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:25:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:25:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:25:17 DEBUG : forgetting directory cache 2025/09/17 06:25:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:25:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:25:31 DEBUG : pacer: low level retry 8/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:25:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:25:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:26:31 DEBUG : pacer: low level retry 9/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:26:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:26:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:26:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:26:36 DEBUG : forgetting directory cache 2025/09/17 06:26:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:27:31 DEBUG : pacer: low level retry 10/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:27:31 INFO : dir/file1: vfs cache: downloader: error count now 4: vfs reader: failed to write to cache file: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout 2025/09/17 06:27:31 ERROR : dir/file1: vfs cache: failed to download: vfs reader: failed to write to cache file: Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=210130559;tm=1758090446;ut=vd;rs=0;fet=download_cloud;He;ch=65a80c91685510c28dea8200c232dc4b;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout 2025/09/17 06:27:31 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 06:27:31 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 06:27:31 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 06:27:31 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 06:27:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:27:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:28:33 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:28:33 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 06:28:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:28:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:29:33 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:29:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:29:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:30:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:30:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:31:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:31:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:32:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:32:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:33:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:33:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:34:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:34:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:35:10 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: timeout awaiting response headers) 2025/09/17 06:35:17 DEBUG : forgetting directory cache 2025/09/17 06:35:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:35:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:35:17 DEBUG : dir: forgetting directory cache 2025/09/17 06:35:17 DEBUG : dir/virtualDir: forgetting directory cache 2025/09/17 06:35:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:35:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:36:11 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:36:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:36:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:36:36 DEBUG : forgetting directory cache 2025/09/17 06:36:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:36:36 DEBUG : dir: forgetting directory cache 2025/09/17 06:37:12 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:37:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:37:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:38:15 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1893899514;tm=1758091051;ut=vd;rs=0;fet=download_cloud;He;ch=12e9237bfd34751d2ac160afd4d02225;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:38:15 DEBUG : pacer: Reducing sleep to 1.5s 2025/09/17 06:38:15 INFO : dir/file1: vfs cache: downloader: error count now 5: vfs reader: failed to write to cache file: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n \n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 06:38:15 ERROR : dir/file1: vfs cache: failed to download: vfs reader: failed to write to cache file: HTTP error 400 (400 Bad Request) returned body: "\n\n\n \n Ulož.to Disk - Platnost odkazu na stránku vypršela\n \n \n \n \n\n\n\n
\n \n
\n\n
\n
\n\t\n\t

Platnost odkazu na stránku vypršela

\n

\n

Link to the page has expired

\n\t\n
\n
\n\n \n\n\n\n\n\n" 2025/09/17 06:38:15 DEBUG : dir/file1: ChunkedReader.RangeSeek from -1 to 5 length -1 2025/09/17 06:38:15 DEBUG : dir/file1: ChunkedReader.Read at -1 length 32768 chunkOffset 5 chunkSize 134217728 2025/09/17 06:38:15 DEBUG : dir/file1: ChunkedReader.openRange at 5 length 134217728 2025/09/17 06:38:17 DEBUG : pacer: Reducing sleep to 1.125s 2025/09/17 06:38:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:38:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:39:20 DEBUG : pacer: low level retry 1/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": net/http: TLS handshake timeout) 2025/09/17 06:39:20 DEBUG : pacer: Rate limited, increasing sleep to 2s 2025/09/17 06:39:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:39:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:40:22 DEBUG : pacer: low level retry 2/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:40:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:40:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:41:22 DEBUG : pacer: low level retry 3/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:41:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:41:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:42:22 DEBUG : pacer: low level retry 4/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:42:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:42:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:43:22 DEBUG : pacer: low level retry 5/10 (error Get "https://content-provider30.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.140:443: i/o timeout) 2025/09/17 06:43:34 DEBUG : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache RemoveNotInUse (maxAge=3600000000000, emptyOnly=false): item dir/file1 not removed, freed 0 bytes 2025/09/17 06:43:34 INFO : uloz.to root 'rclone-test-sulayag5yoxu': vfs cache: cleaned: objects 1 (was 1) in use 1, to upload 0, uploading 0, total size 5 (was 5) 2025/09/17 06:44:24 DEBUG : pacer: low level retry 6/10 (error Get "https://content-provider32.greencdn.link/Ps;Hs;up=1;uid=24664897;cid=3214735720;uip=65.108.87.141;aff=apis.uloz.to;did=uloz-to;fide=Cftjyxbe;fs=HHhSHTZ3261C;hid=2tyDAzNf;rid=1833476353;tm=1758091697;ut=vd;rs=0;fet=download_cloud;He;ch=ecbfd75d73ec743f9439971772952f98;Pe/file/HHhSHTZ3261C/file1?bD&u=24664897&c=3214735720&De": dial tcp 93.99.2.151:443: i/o timeout) panic: test timed out after 1h0m0s running tests: TestFileSetModTime (58m15s) TestFileSetModTime/cache=full,open=true,write=true (57m53s) goroutine 4313 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 58 minutes]: testing.(*T).Run(0xc0009de000, {0x280e780?, 0xc000679ad8?}, 0x2945738) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc0009de000) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc0009de000, 0xc000679c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc0007ff908, {0x40e7060, 0x55, 0x55}, {0xee05c41bb?, 0x411a0a0?, 0x411a0a0?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc00057e6e0) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc00057e6e0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/vfs.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:37 main.main() _testmain.go:217 +0xa9 goroutine 52 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 53 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 2539 [chan receive, 58 minutes]: testing.(*T).Run(0xc000a2ac40, {0xc000a0b340?, 0xc0008e2f20?}, 0xc0002ece60) /usr/local/go/src/testing/testing.go:1859 +0x431 github.com/rclone/rclone/vfs.TestFileSetModTime(0xc000a2ac40) /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:158 +0x210 testing.tRunner(0xc000a2ac40, 0x2945738) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 4181 [select]: net/http.(*Transport).getConn(0xc0003916c0, 0xc0009a44b0, {{}, 0x0, {0xc0001858c0, 0x5}, {0xc0005e8990, 0x24}, 0x0}) /usr/local/go/src/net/http/transport.go:1520 +0x4ae net/http.(*Transport).roundTrip(0xc0003916c0, 0xc0008b63c0) /usr/local/go/src/net/http/transport.go:685 +0xb57 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0000b5e00, 0xc0008b63c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x345 net/http.send(0xc0008b63c0, {0x2c766e0, 0xc0000b5e00}, {0x479601?, 0xc0006e13c0?, 0x0?}) /usr/local/go/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc000319170, 0xc0008b63c0, {0x7ec9e85c9a40?, 0x413d7e0?, 0x0?}) /usr/local/go/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc000319170, 0xc0008b63c0) /usr/local/go/src/net/http/client.go:728 +0x989 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc00028f900, {0x2c9e038, 0x413d7e0}, 0xc000b2aa00) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:353 +0xd67 github.com/rclone/rclone/backend/ulozto.(*Object).Open.func2() /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:804 +0x52 github.com/rclone/rclone/fs.pacerInvoker(0x7, 0xa, 0x2c75440?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0000b5e60, 0xc000aa10c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:199 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0000b5e60, 0xc000aa10c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:218 +0x93 github.com/rclone/rclone/backend/ulozto.(*Object).Open(0xc0009ae3c0, {0x2c9e038, 0x413d7e0}, {0xc000ce0580, 0x2, 0x2}) /home/rclone/go/src/github.com/rclone/rclone/backend/ulozto/ulozto.go:803 +0x428 github.com/rclone/rclone/fs/chunkedreader.(*sequential).openRange(0xc00048d7a0) /home/rclone/go/src/github.com/rclone/rclone/fs/chunkedreader/sequential.go:209 +0x4e5 github.com/rclone/rclone/fs/chunkedreader.(*sequential).Read(0xc00048d7a0, {0xc00079a000?, 0xc0009bce30?, 0x479699?}) /home/rclone/go/src/github.com/rclone/rclone/fs/chunkedreader/sequential.go:73 +0x2ff io.copyBuffer({0x2c75780, 0xc000dac738}, {0x7ec9e85c8e88, 0xc00048d7a0}, {0x0, 0x0, 0x0}) /usr/local/go/src/io/io.go:429 +0x190 io.Copy(...) /usr/local/go/src/io/io.go:388 github.com/rclone/rclone/fs/accounting.(*Account).WriteTo(0xc0009c4b00, {0x2c768e0, 0xc00048d6c0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:413 +0x13c github.com/rclone/rclone/vfs/vfscache/downloaders.(*downloader).download(0xc0009bcfd0?) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/downloaders/downloaders.go:628 +0x28 github.com/rclone/rclone/vfs/vfscache/downloaders.(*Downloaders)._newDownloader.func1() /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/downloaders/downloaders.go:195 +0x72 created by github.com/rclone/rclone/vfs/vfscache/downloaders.(*Downloaders)._newDownloader in goroutine 4036 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/downloaders/downloaders.go:193 +0x1fb goroutine 3191 [select, 58 minutes]: github.com/rclone/rclone/vfs.(*VFS).signalHandler(0xc000bcd400, {0x2c9e0e0, 0xc000a96c80}) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:290 +0xc5 created by github.com/rclone/rclone/vfs.New in goroutine 3190 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:266 +0x7fe goroutine 4180 [select]: github.com/rclone/rclone/fs/accounting.(*Account).averageLoop(0xc0009c4b00) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:221 +0xed created by github.com/rclone/rclone/fs/accounting.newAccountSizeName in goroutine 4036 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting.go:119 +0x436 goroutine 4296 [IO wait]: internal/poll.runtime_pollWait(0x7eca32a1d600, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0000de200?, 0xc0006ec000?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0000de200, {0xc0006ec000, 0xd80, 0xd80}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0000de200, {0xc0006ec000?, 0x479fb3?, 0xc0008e3958?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008c0a0, {0xc0006ec000?, 0x4b?, 0x479699?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000034630, {0xc0006ec000?, 0x7eca32a59698?, 0x7eca32bc0a78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc0000da3a8, {0xc0006ec000?, 0x47ebde?, 0xc0008b6280?}) /usr/local/go/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000578d38, {0x2c77e80, 0xc0000da3a8}) /usr/local/go/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000578a88, {0x7ec9e9771a08, 0xc000034630}, 0x4426d4?) /usr/local/go/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000578a88, 0x0) /usr/local/go/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000578a88, {0xc00070f000, 0x1000, 0x1b?}) /usr/local/go/src/crypto/tls/conn.go:1385 +0x145 bufio.(*Reader).Read(0xc0004132c0, {0xc00017e3c0, 0x9, 0xc000a49a40?}) /usr/local/go/src/bufio/bufio.go:245 +0x197 io.ReadAtLeast({0x2c75020, 0xc0004132c0}, {0xc00017e3c0, 0x9, 0x9}, 0x9) /usr/local/go/src/io/io.go:335 +0x91 io.ReadFull(...) /usr/local/go/src/io/io.go:354 net/http.http2readFrameHeader({0xc00017e3c0, 0x9, 0xc000362ab0?}, {0x2c75020?, 0xc0004132c0?}) /usr/local/go/src/net/http/h2_bundle.go:1805 +0x65 net/http.(*http2Framer).ReadFrame(0xc00017e380) /usr/local/go/src/net/http/h2_bundle.go:2072 +0x7d net/http.(*http2clientConnReadLoop).run(0xc0008e3fa8) /usr/local/go/src/net/http/h2_bundle.go:9933 +0xda net/http.(*http2ClientConn).readLoop(0xc000dea000) /usr/local/go/src/net/http/h2_bundle.go:9812 +0x79 created by net/http.(*http2Transport).newClientConn in goroutine 4295 /usr/local/go/src/net/http/h2_bundle.go:8334 +0xde5 goroutine 3190 [chan receive, 57 minutes]: github.com/rclone/rclone/vfs/vfscache/downloaders.(*Downloaders).Download(0xc000792210, {0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/downloaders/downloaders.go:270 +0x1bd github.com/rclone/rclone/vfs/vfscache.(*Item)._ensure(0xc0009c4400, 0x0, 0xc000db0ea0?) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/item.go:1175 +0x325 github.com/rclone/rclone/vfs/vfscache.(*Item).Close(0xc0009c4400, 0xc000a90960) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/item.go:676 +0x185 github.com/rclone/rclone/vfs.(*RWFileHandle).close(0xc000447300) /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write.go:168 +0x1fb github.com/rclone/rclone/vfs.(*RWFileHandle).Close(0x2c88770?) /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write.go:186 +0x79 github.com/rclone/rclone/vfs.testFileSetModTime(0xc000a2ae00, 0x3, 0x1, 0x1) /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:134 +0x346 github.com/rclone/rclone/vfs.TestFileSetModTime.func1(0xc000a2ae00?) /home/rclone/go/src/github.com/rclone/rclone/vfs/file_test.go:159 +0x25 testing.tRunner(0xc000a2ae00, 0xc0002ece60) /usr/local/go/src/testing/testing.go:1792 +0xf4 created by testing.(*T).Run in goroutine 2539 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 4179 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc0002e32c0, {0x2c9e0e0, 0xc0009a4910}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:339 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 4036 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:376 +0x11a goroutine 4323 [select]: net.(*netFD).connect.func2() /usr/local/go/src/net/fd_unix.go:118 +0x7a created by net.(*netFD).connect in goroutine 4287 /usr/local/go/src/net/fd_unix.go:117 +0x349 goroutine 4287 [IO wait]: internal/poll.runtime_pollWait(0x7eca32a1d830, 0x77) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0000de300?, 0x0?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitWrite(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 internal/poll.(*FD).WaitWrite(...) /usr/local/go/src/internal/poll/fd_unix.go:692 net.(*netFD).connect(0xc0000de300, {0x2c9e150, 0xc00048caf0}, {0x41b074?, 0xc000d98060?}, {0x2c74e40?, 0xc0006d2260?}) /usr/local/go/src/net/fd_unix.go:141 +0x6cb net.(*netFD).dial(0xc0000de300, {0x2c9e150, 0xc00048caf0}, {0x2ca5df0?, 0x0?}, {0x2ca5df0, 0xc0003631a0}, 0xc0006fd210?) /usr/local/go/src/net/sock_posix.go:124 +0x3bc net.socket({0x2c9e150, 0xc00048caf0}, {0x27e3040, 0x3}, 0x2, 0x1, 0x2?, 0x0, {0x2ca5df0, 0x0}, ...) /usr/local/go/src/net/sock_posix.go:70 +0x29b net.internetSocket({0x2c9e150, 0xc00048caf0}, {0x27e3040, 0x3}, {0x2ca5df0, 0x0}, {0x2ca5df0, 0xc0003631a0}, 0x1, 0x0, ...) /usr/local/go/src/net/ipsock_posix.go:167 +0xf8 net.(*sysDialer).doDialTCPProto(0xc0001a3440, {0x2c9e150, 0xc00048caf0}, 0x0, 0xc0003631a0, 0x0) /usr/local/go/src/net/tcpsock_posix.go:85 +0xe7 net.(*sysDialer).doDialTCP(...) /usr/local/go/src/net/tcpsock_posix.go:75 net.(*sysDialer).dialTCP(0x0?, {0x2c9e150?, 0xc00048caf0?}, 0xc0006fd528?, 0xc0006fd420?) /usr/local/go/src/net/tcpsock_posix.go:71 +0x69 net.(*sysDialer).dialSingle(0xc0001a3440, {0x2c9e150, 0xc00048caf0}, {0x2c890d8, 0xc0003631a0}) /usr/local/go/src/net/dial.go:721 +0x3ce net.(*sysDialer).dialSerial(0xc0001a3440, {0x2c9e150, 0xc00048caf0}, {0xc0008f8410?, 0x1, 0xc000a03e30?}) /usr/local/go/src/net/dial.go:686 +0x24e net.(*sysDialer).dialParallel(0x0?, {0x2c9e150?, 0xc00048caf0?}, {0xc0008f8410?, 0x23b5280?, 0x27e4121?}, {0x0?, 0x27e3040?, 0xc00009d7f0?}) /usr/local/go/src/net/dial.go:587 +0x3a7 net.(*Dialer).DialContext(0xc000a0e460, {0x2c9e0e0, 0xc0009a4500}, {0x27e3040, 0x3}, {0xc0005e8990, 0x24}) /usr/local/go/src/net/dial.go:578 +0x6ac github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0xc000a0e460, {0x2c9e0e0, 0xc0009a4500}, {0x27e3040?, 0x7ec9e97761b8?}, {0xc0005e8990, 0x24}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125 github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func2({0x2c9e0e0, 0xc0009a4500}, {0x27e3040, 0x3}, {0xc0005e8990, 0x24}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:269 +0x5c net/http.(*Transport).dial(0xc00009da08?, {0x2c9e0e0?, 0xc0009a4500?}, {0x27e3040?, 0xc0001fc900?}, {0xc0005e8990?, 0x10b?}) /usr/local/go/src/net/http/transport.go:1278 +0xd2 net/http.(*Transport).dialConn(0xc0003916c0, {0x2c9e0e0, 0xc0009a4500}, {{}, 0x0, {0xc0001858c0, 0x5}, {0xc0005e8990, 0x24}, 0x0}) /usr/local/go/src/net/http/transport.go:1780 +0x7e5 net/http.(*Transport).dialConnFor(0xc0003916c0, 0xc000792160) /usr/local/go/src/net/http/transport.go:1615 +0xb8 net/http.(*Transport).startDialConnForLocked.func1() /usr/local/go/src/net/http/transport.go:1597 +0x35 created by net/http.(*Transport).startDialConnForLocked in goroutine 4181 /usr/local/go/src/net/http/transport.go:1596 +0x112 goroutine 3212 [select]: github.com/rclone/rclone/vfs/vfscache.(*Cache).cleaner(0xc00083a780, {0x2c9e0e0, 0xc000a96cd0}) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/cache.go:854 +0x16c created by github.com/rclone/rclone/vfs/vfscache.New in goroutine 3190 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfscache/cache.go:145 +0x735 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestUlozto: -verbose" - Finished ERROR in 1h0m2.500821235s (try 2/5): exit status 2: Failed []