"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Starting (try 1/5) 2026/03/10 06:15:16 DEBUG : Creating backend with remote "TestPcloud:rclone-test-wacisap9yoru" 2026/03/10 06:15:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/03/10 06:15:17 DEBUG : Creating backend with remote "/tmp/rclone3069401472" === RUN TestDirHandleMethods run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:17 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:18 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:18 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:18 DEBUG : >changeNotify: 2026/03/10 06:15:18 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:18 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:18 DEBUG : >changeNotify: 2026/03/10 06:15:18 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:18 DEBUG : dir: Looking for writers 2026/03/10 06:15:18 DEBUG : Looking for writers 2026/03/10 06:15:18 DEBUG : dir: reading active writers 2026/03/10 06:15:18 DEBUG : >WaitForWriters: 2026/03/10 06:15:19 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:19 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:19 DEBUG : >changeNotify: --- PASS: TestDirHandleMethods (1.82s) === RUN TestDirHandleReaddir run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:19 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:19 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:19 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:19 DEBUG : >changeNotify: 2026/03/10 06:15:19 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:19 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:19 DEBUG : >changeNotify: 2026/03/10 06:15:19 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file2" (type: 1) 2026/03/10 06:15:19 DEBUG : changeNotify: relativePath="dir/file2", type=1 2026/03/10 06:15:19 DEBUG : >changeNotify: 2026/03/10 06:15:20 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/subdir" (type: 0) 2026/03/10 06:15:20 DEBUG : changeNotify: relativePath="dir/subdir", type=0 2026/03/10 06:15:20 DEBUG : >changeNotify: 2026/03/10 06:15:20 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/subdir/file3" (type: 1) 2026/03/10 06:15:20 DEBUG : changeNotify: relativePath="dir/subdir/file3", type=1 2026/03/10 06:15:20 DEBUG : >changeNotify: 2026/03/10 06:15:21 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:21 DEBUG : dir/subdir: Looking for writers 2026/03/10 06:15:21 DEBUG : dir: Looking for writers 2026/03/10 06:15:21 DEBUG : file2: reading active writers 2026/03/10 06:15:21 DEBUG : subdir: reading active writers 2026/03/10 06:15:21 DEBUG : file1: reading active writers 2026/03/10 06:15:21 DEBUG : Looking for writers 2026/03/10 06:15:21 DEBUG : dir: reading active writers 2026/03/10 06:15:21 DEBUG : >WaitForWriters: 2026/03/10 06:15:21 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/subdir/file3" (type: 1) 2026/03/10 06:15:21 DEBUG : changeNotify: relativePath="dir/subdir/file3", type=1 2026/03/10 06:15:21 DEBUG : >changeNotify: --- PASS: TestDirHandleReaddir (2.60s) === RUN TestDirHandleReaddirnames run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:22 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:22 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:22 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:22 DEBUG : >changeNotify: 2026/03/10 06:15:22 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:22 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:22 DEBUG : >changeNotify: 2026/03/10 06:15:22 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:22 DEBUG : dir: Looking for writers 2026/03/10 06:15:22 DEBUG : file1: reading active writers 2026/03/10 06:15:22 DEBUG : Looking for writers 2026/03/10 06:15:22 DEBUG : dir: reading active writers 2026/03/10 06:15:22 DEBUG : >WaitForWriters: 2026/03/10 06:15:23 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:23 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:23 DEBUG : dir: invalidating directory cache 2026/03/10 06:15:23 DEBUG : >changeNotify: --- PASS: TestDirHandleReaddirnames (1.43s) === RUN TestDirMethods run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:23 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:23 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:23 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:23 DEBUG : >changeNotify: 2026/03/10 06:15:23 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:23 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:23 DEBUG : >changeNotify: 2026/03/10 06:15:24 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:24 DEBUG : dir: Looking for writers 2026/03/10 06:15:24 DEBUG : Looking for writers 2026/03/10 06:15:24 DEBUG : dir: reading active writers 2026/03/10 06:15:24 DEBUG : >WaitForWriters: 2026/03/10 06:15:24 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:24 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:24 DEBUG : >changeNotify: --- PASS: TestDirMethods (1.33s) === RUN TestDirForgetAll run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:24 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:25 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:25 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:25 DEBUG : >changeNotify: 2026/03/10 06:15:25 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:25 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:25 DEBUG : >changeNotify: 2026/03/10 06:15:25 DEBUG : dir: forgetting directory cache 2026/03/10 06:15:25 DEBUG : forgetting directory cache 2026/03/10 06:15:25 DEBUG : dir: forgetting directory cache 2026/03/10 06:15:25 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:25 DEBUG : Looking for writers 2026/03/10 06:15:25 DEBUG : >WaitForWriters: 2026/03/10 06:15:25 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:25 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:25 DEBUG : >changeNotify: --- PASS: TestDirForgetAll (1.45s) === RUN TestDirForgetPath run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:26 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:15:26 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir" (type: 0) 2026/03/10 06:15:26 DEBUG : changeNotify: relativePath="dir", type=0 2026/03/10 06:15:26 DEBUG : >changeNotify: 2026/03/10 06:15:26 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:26 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:26 DEBUG : >changeNotify: 2026/03/10 06:15:27 DEBUG : ForgetPath: relativePath="dir/notfound", type=1 2026/03/10 06:15:27 DEBUG : dir: invalidating directory cache 2026/03/10 06:15:27 DEBUG : >ForgetPath: 2026/03/10 06:15:27 DEBUG : ForgetPath: relativePath="dir", type=0 2026/03/10 06:15:27 DEBUG : invalidating directory cache 2026/03/10 06:15:27 DEBUG : dir: forgetting directory cache 2026/03/10 06:15:27 DEBUG : >ForgetPath: 2026/03/10 06:15:27 DEBUG : ForgetPath: relativePath="not/in/cache", type=0 2026/03/10 06:15:27 DEBUG : >ForgetPath: 2026/03/10 06:15:27 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:27 DEBUG : dir: Looking for writers 2026/03/10 06:15:27 DEBUG : Looking for writers 2026/03/10 06:15:27 DEBUG : dir: reading active writers 2026/03/10 06:15:27 DEBUG : >WaitForWriters: 2026/03/10 06:15:27 DEBUG : pcloud root 'rclone-test-wacisap9yoru': ChangeNotify: detected change in "dir/file1" (type: 1) 2026/03/10 06:15:27 DEBUG : changeNotify: relativePath="dir/file1", type=1 2026/03/10 06:15:27 DEBUG : >changeNotify: --- PASS: TestDirForgetPath (1.46s) === RUN TestDirWalk run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:27 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream run.go:290: Failed to put "dir/file1" to "pcloud root 'rclone-test-wacisap9yoru'": pcloud error: Directory does not exist. (2005) 2026/03/10 06:15:33 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:15:33 DEBUG : Looking for writers 2026/03/10 06:15:33 DEBUG : >WaitForWriters: --- FAIL: TestDirWalk (6.17s) === RUN TestDirSetModTime run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:15:33 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:17:34 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:17:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:17:34 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:17:35 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:17:36 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:19:41 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:19:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:19:41 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:19:43 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:19:44 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:21:49 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:21:49 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:21:49 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:21:50 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:21:51 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:23:57 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:23:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:23:57 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:23:58 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:23:59 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:25:18 DEBUG : forgetting directory cache 2026/03/10 06:25:18 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:18 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:21 DEBUG : forgetting directory cache 2026/03/10 06:25:21 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:21 DEBUG : dir/subdir: forgetting directory cache 2026/03/10 06:25:22 DEBUG : forgetting directory cache 2026/03/10 06:25:22 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:24 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:24 DEBUG : forgetting directory cache 2026/03/10 06:25:24 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:27 DEBUG : forgetting directory cache 2026/03/10 06:25:27 DEBUG : dir: forgetting directory cache 2026/03/10 06:25:27 DEBUG : forgetting directory cache 2026/03/10 06:25:33 DEBUG : forgetting directory cache 2026/03/10 06:26:05 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:26:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:26:05 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:26:06 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:26:07 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:28:12 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:28:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:28:13 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:28:14 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:28:15 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:30:20 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:30:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:30:20 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:30:21 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:30:23 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:32:28 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:32:28 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:32:28 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:32:29 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:32:30 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:34:36 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:34:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:34:36 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:34:37 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:34:38 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:36:44 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:36:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:36:44 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:36:45 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:36:46 DEBUG : pacer: Reducing sleep to 10ms run.go:290: Failed to put "dir/file1" to "pcloud root 'rclone-test-wacisap9yoru'": Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF 2026/03/10 06:36:49 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:36:49 DEBUG : Looking for writers 2026/03/10 06:36:49 DEBUG : >WaitForWriters: --- FAIL: TestDirSetModTime (1276.04s) === RUN TestDirStat run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:36:49 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 06:38:50 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:38:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:38:50 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:38:51 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:38:52 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:40:57 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:40:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:40:57 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:40:59 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:41:00 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:43:05 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:43:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:43:05 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:43:06 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:43:07 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:45:13 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:45:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:45:13 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:45:14 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:45:15 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:46:49 DEBUG : forgetting directory cache 2026/03/10 06:47:21 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:47:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:47:21 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:47:22 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:47:23 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:49:29 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:49:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:49:29 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:49:30 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:49:31 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:51:36 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:51:36 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:51:36 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:51:38 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:51:39 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:53:44 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:53:44 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:53:44 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:53:45 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:53:46 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:55:52 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:55:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:55:52 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:55:53 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:55:54 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:58:00 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 06:58:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 06:58:00 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 06:58:01 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 06:58:02 DEBUG : pacer: Reducing sleep to 10ms run.go:290: Failed to put "dir/file1" to "pcloud root 'rclone-test-wacisap9yoru'": Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF 2026/03/10 06:58:05 DEBUG : WaitForWriters: timeout=30s 2026/03/10 06:58:05 DEBUG : Looking for writers 2026/03/10 06:58:05 DEBUG : >WaitForWriters: --- FAIL: TestDirStat (1276.13s) === RUN TestDirReadDirAll run.go:185: Remote "pcloud root 'rclone-test-wacisap9yoru'", Local "Local file system at /tmp/rclone3069401472", Modify Window "1s" 2026/03/10 06:58:06 NOTICE: pcloud root 'rclone-test-wacisap9yoru': --vfs-cache-mode writes or full is recommended for this remote as it can't stream 2026/03/10 07:00:06 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:00:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:00:06 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:00:07 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:00:08 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:02:13 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:02:13 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:02:14 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:02:15 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:02:16 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:04:21 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:04:21 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:04:21 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:04:22 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:04:24 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:06:29 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:06:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:06:29 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:06:30 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:06:31 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:08:06 DEBUG : forgetting directory cache 2026/03/10 07:08:37 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:08:37 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:08:37 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:08:38 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:08:39 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:10:45 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:10:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:10:45 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:10:46 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:10:47 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:12:52 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:12:52 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:12:52 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:12:54 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:12:55 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:15:00 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) 2026/03/10 07:15:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/03/10 07:15:00 DEBUG : pacer: Reducing sleep to 15ms 2026/03/10 07:15:01 DEBUG : pacer: Reducing sleep to 11.25ms 2026/03/10 07:15:02 DEBUG : pacer: Reducing sleep to 10ms run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-wacisap9yoru': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=22802809843&mtime=981173106&nopartial=1": EOF) panic: test timed out after 1h0m0s running tests: TestDirReadDirAll (17m12s) goroutine 16446 [running]: testing.(*M).startAlarm.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2682 +0x345 created by time.goFunc /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 19 minutes]: testing.(*T).Run(0xc00050a8c0, {0x29df9ab?, 0xc000787ae8?}, 0x2b54158) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2005 +0x485 testing.runTests.func1(0xc00050a8c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2477 +0x37 testing.tRunner(0xc00050a8c0, 0xc000787c28) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea testing.runTests(0xc000243dd0, {0x456c060, 0x59, 0x59}, {0xee141adf5?, 0x458a4e0?, 0x458a4e0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2475 +0x4b4 testing.(*M).Run(0xc00077e960) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:2337 +0x63a github.com/rclone/rclone/fstest.TestMain(0xc00077e960) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6 github.com/rclone/rclone/vfs.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:36 main.main() _testmain.go:225 +0xa6 goroutine 23 [syscall, 60 minutes]: os/signal.signal_recv() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/os/signal/signal.go:152 +0x1f goroutine 24 [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 25 [select, 60 minutes]: github.com/rclone/rclone/lib/oauthutil.(*Renew).renewOnExpiry(0xc0006104c0) /home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:43 +0x86 created by github.com/rclone/rclone/lib/oauthutil.NewRenew in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:32 +0xe5 goroutine 16491 [IO wait]: internal/poll.runtime_pollWait(0x76c4209caa00, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000627080?, 0xc0008d0600?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000627080, {0xc0008d0600, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x279 net.(*netFD).Read(0xc000627080, {0xc0008d0600?, 0x197b?, 0xc00072d0c0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0xc000088c20, {0xc0008d0600?, 0xc0008d0600?, 0x5?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004d5548, {0xc0008d0600?, 0x76c41f97ec28?, 0x76c467ea3f30?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000112ae0, {0xc0008d0600?, 0x197b?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:816 +0x3b bytes.(*Buffer).ReadFrom(0xc000647428, {0x2ec4480, 0xc000112ae0}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bytes/buffer.go:217 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000647188, {0x76c41ddec2c0, 0xc0004d5548}, 0x1980?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:838 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000647188, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:627 +0x3db crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:589 crypto/tls.(*Conn).Read(0xc000647188, {0xc00099a000, 0x1000, 0x76c467ea3f30?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:1392 +0x145 net/http.(*persistConn).Read(0xc0006b8c60, {0xc00099a000?, 0x415b26?, 0x70?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0009ee000) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0009ee000, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0006b8c60) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 16484 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:1944 +0x174f goroutine 15806 [select, 19 minutes]: github.com/rclone/rclone/vfs.(*VFS).signalHandler(0xc00045c3c0, {0x2eec270, 0xc000615e50}) /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:290 +0xc5 created by github.com/rclone/rclone/vfs.New in goroutine 15879 /home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:266 +0x808 goroutine 15879 [select]: net/http.(*persistConn).roundTrip(0xc0006b8c60, 0xc000900a50) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2833 +0x74d net/http.(*Transport).roundTrip(0xc00068a1a0, 0xc000619b80) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:696 +0xa8f net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc00043c7e0, 0xc000619b80) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a golang.org/x/oauth2.(*Transport).RoundTrip(0xc000267260, 0xc000619900) /home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.35.0/transport.go:55 +0x15a net/http.send(0xc000619900, {0x2ec51c0, 0xc000267260}, {0xc00006ae01?, 0x708910?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc00077d980, 0xc000619900, {0x41ecf4?, 0xb?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc00077d980, 0xc000619900) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:729 +0x9c9 net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc0001c0f50, {0x2eec158, 0x45ae040}, 0xc000158580) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd87 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc0001c0f50, {0x2eec158, 0x45ae040}, 0xc00006b778?, {0x0?, 0x0?}, {0x247b640, 0xc0009516e0}, 0x416d7d?, 0x2b55bd0, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 github.com/rclone/rclone/backend/pcloud.(*Object).Update.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1477 +0x93 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0x9?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00043c8a0, 0xc000a36240, 0x1) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:256 github.com/rclone/rclone/backend/pcloud.(*Object).Update(0xc000143c20, {0x2eec158, 0x45ae040}, {0x2ec15e0, 0xc0005f0300}, {0x2ef9250, 0xc000587b20}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1476 +0xb3e github.com/rclone/rclone/backend/pcloud.(*Fs).Put(0xc0003fa840, {0x2eec158, 0x45ae040}, {0x2ec15e0, 0xc0005f0300}, {0x2ef9250, 0xc000587b20}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:707 +0x14c github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc000800e00, {0x2eec158, 0x45ae040}, {0x2f06040, 0xc0003fa840}, {0x29c7cbd, 0x9}, {0x29d6396, 0xe}, {0x1dcd64ff, ...}, ...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:280 +0x3f2 github.com/rclone/rclone/fstest.(*Run).WriteObject(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297 github.com/rclone/rclone/vfs.TestDirReadDirAll(0xc000828fc0) /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:256 +0x97 testing.tRunner(0xc000828fc0, 0x2b54158) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1934 +0xea created by testing.(*T).Run in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/testing/testing.go:1997 +0x465 goroutine 153 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000488960, {0x2eec270, 0xc0009007d0}) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:352 +0x145 created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 54 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x11d goroutine 16492 [select]: net/http.(*persistConn).writeLoop(0xc0006b8c60) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2597 +0xe7 created by net/http.(*Transport).dialConn in goroutine 16484 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:1945 +0x17a5 goroutine 15880 [select]: net/http.(*persistConn).roundTrip(0xc0009b2120, 0xc000b36050) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2833 +0x74d net/http.(*Transport).roundTrip(0xc00068a1a0, 0xc0003f83c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:696 +0xa8f net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc00043c7e0, 0xc0003f83c0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a golang.org/x/oauth2.(*Transport).RoundTrip(0xc000267260, 0xc0003f8280) /home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.35.0/transport.go:55 +0x15a net/http.send(0xc0003f8280, {0x2ec51c0, 0xc000267260}, {0xc00082d201?, 0x708910?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:259 +0x5e2 net/http.(*Client).send(0xc00077d980, 0xc0003f8280, {0x41ecf4?, 0xc00082d218?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:180 +0x91 net/http.(*Client).do(0xc00077d980, 0xc0003f8280) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:729 +0x9c9 net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/client.go:587 github.com/rclone/rclone/lib/rest.(*Client).Call(0xc0001c0f50, {0x2eec2e0, 0xc000746000}, 0xc00082db48) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd87 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc0001c0f50, {0x2eec2e0, 0xc000746000}, 0xc000797ae8?, {0x0?, 0x0?}, {0x230e220, 0xc00047c080}, 0x48a2d2?, 0x2b55bd0, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x40b github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522 github.com/rclone/rclone/backend/pcloud.(*Fs).changeNotifyLoop(0xc0003fa840, {0x2eec1c8, 0x45ae040}, 0xc000a970a0, 0xc0007dfe30) /home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1135 +0x465 created by github.com/rclone/rclone/backend/pcloud.(*Fs).ChangeNotify in goroutine 15879 /home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1040 +0xa8 goroutine 16417 [IO wait]: internal/poll.runtime_pollWait(0x76c4209ca800, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc00056a780?, 0xc0001dac80?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc00056a780, {0xc0001dac80, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x279 net.(*netFD).Read(0xc00056a780, {0xc0001dac80?, 0x197b?, 0xc000a94320?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0xc0004c0018, {0xc0001dac80?, 0xc0001dac80?, 0x5?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0001120d8, {0xc0001dac80?, 0x76c41e79c7c8?, 0x76c467ea3108?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*atLeastReader).Read(0xc000482090, {0xc0001dac80?, 0x197b?, 0x431125?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:816 +0x3b bytes.(*Buffer).ReadFrom(0xc00012e628, {0x2ec4480, 0xc000482090}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bytes/buffer.go:217 +0x98 crypto/tls.(*Conn).readFromUntil(0xc00012e388, {0x76c41ddec2c0, 0xc0001120d8}, 0x1980?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:838 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc00012e388, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:627 +0x3db crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:589 crypto/tls.(*Conn).Read(0xc00012e388, {0xc00080e000, 0x1000, 0x76c467ea35c0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/crypto/tls/conn.go:1392 +0x145 net/http.(*persistConn).Read(0xc0009b2120, {0xc00080e000?, 0x415b26?, 0x70?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc000be4720) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc000be4720, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0009b2120) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 16411 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:1944 +0x174f goroutine 16434 [select]: net/http.(*persistConn).writeLoop(0xc0009b2120) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:2597 +0xe7 created by net/http.(*Transport).dialConn in goroutine 16411 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.25.0.linux-amd64/src/net/http/transport.go:1945 +0x17a5 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Finished ERROR in 1h0m0.813721866s (try 1/5): exit status 2: Failed [TestDirWalk TestDirSetModTime TestDirStat]