"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Starting (try 1/5)
2026/04/11 04:05:38 DEBUG : Creating backend with remote "TestPcloud:rclone-test-lipopac6zije"
2026/04/11 04:05:38 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/04/11 04:05:38 DEBUG : Creating backend with remote "/tmp/rclone3973219400"
=== RUN   TestDirHandleMethods
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:38 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:05:39 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir" (type: 0)
2026/04/11 04:05:39 DEBUG : changeNotify: relativePath="dir", type=0
2026/04/11 04:05:39 DEBUG : >changeNotify: 
2026/04/11 04:05:39 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:39 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:39 DEBUG : >changeNotify: 
2026/04/11 04:05:40 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:40 DEBUG : dir: Looking for writers
2026/04/11 04:05:40 DEBUG : Looking for writers
2026/04/11 04:05:40 DEBUG : dir: reading active writers
2026/04/11 04:05:40 DEBUG : >WaitForWriters: 
2026/04/11 04:05:40 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:40 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:40 DEBUG : >changeNotify: 
--- PASS: TestDirHandleMethods (1.81s)
=== RUN   TestDirHandleReaddir
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:40 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:05:40 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir" (type: 0)
2026/04/11 04:05:40 DEBUG : changeNotify: relativePath="dir", type=0
2026/04/11 04:05:40 DEBUG : >changeNotify: 
2026/04/11 04:05:41 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:41 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:41 DEBUG : >changeNotify: 
2026/04/11 04:05:41 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file2" (type: 1)
2026/04/11 04:05:41 DEBUG : changeNotify: relativePath="dir/file2", type=1
2026/04/11 04:05:41 DEBUG : >changeNotify: 
2026/04/11 04:05:41 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/subdir" (type: 0)
2026/04/11 04:05:41 DEBUG : changeNotify: relativePath="dir/subdir", type=0
2026/04/11 04:05:41 DEBUG : >changeNotify: 
2026/04/11 04:05:41 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/subdir/file3" (type: 1)
2026/04/11 04:05:41 DEBUG : changeNotify: relativePath="dir/subdir/file3", type=1
2026/04/11 04:05:41 DEBUG : >changeNotify: 
2026/04/11 04:05:42 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:42 DEBUG : dir/subdir: Looking for writers
2026/04/11 04:05:42 DEBUG : dir: Looking for writers
2026/04/11 04:05:42 DEBUG : file1: reading active writers
2026/04/11 04:05:42 DEBUG : file2: reading active writers
2026/04/11 04:05:42 DEBUG : subdir: reading active writers
2026/04/11 04:05:42 DEBUG : Looking for writers
2026/04/11 04:05:42 DEBUG : dir: reading active writers
2026/04/11 04:05:42 DEBUG : >WaitForWriters: 
2026/04/11 04:05:42 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/subdir/file3" (type: 1)
2026/04/11 04:05:42 DEBUG : changeNotify: relativePath="dir/subdir/file3", type=1
2026/04/11 04:05:42 DEBUG : >changeNotify: 
--- PASS: TestDirHandleReaddir (2.64s)
=== RUN   TestDirHandleReaddirnames
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:43 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:05:43 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir" (type: 0)
2026/04/11 04:05:43 DEBUG : changeNotify: relativePath="dir", type=0
2026/04/11 04:05:43 DEBUG : >changeNotify: 
2026/04/11 04:05:43 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:43 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:43 DEBUG : >changeNotify: 
2026/04/11 04:05:44 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:44 DEBUG : dir: Looking for writers
2026/04/11 04:05:44 DEBUG : file1: reading active writers
2026/04/11 04:05:44 DEBUG : Looking for writers
2026/04/11 04:05:44 DEBUG : dir: reading active writers
2026/04/11 04:05:44 DEBUG : >WaitForWriters: 
2026/04/11 04:05:44 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:44 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:44 DEBUG : dir: invalidating directory cache
2026/04/11 04:05:44 DEBUG : >changeNotify: 
--- PASS: TestDirHandleReaddirnames (1.42s)
=== RUN   TestDirMethods
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:44 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:05:45 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir" (type: 0)
2026/04/11 04:05:45 DEBUG : changeNotify: relativePath="dir", type=0
2026/04/11 04:05:45 DEBUG : >changeNotify: 
2026/04/11 04:05:45 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:45 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:45 DEBUG : >changeNotify: 
2026/04/11 04:05:45 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:45 DEBUG : dir: Looking for writers
2026/04/11 04:05:45 DEBUG : Looking for writers
2026/04/11 04:05:45 DEBUG : dir: reading active writers
2026/04/11 04:05:45 DEBUG : >WaitForWriters: 
2026/04/11 04:05:45 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:45 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:45 DEBUG : >changeNotify: 
--- PASS: TestDirMethods (1.29s)
=== RUN   TestDirForgetAll
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:46 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:05:46 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir" (type: 0)
2026/04/11 04:05:46 DEBUG : changeNotify: relativePath="dir", type=0
2026/04/11 04:05:46 DEBUG : >changeNotify: 
2026/04/11 04:05:46 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:46 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:46 DEBUG : >changeNotify: 
2026/04/11 04:05:46 DEBUG : dir: forgetting directory cache
2026/04/11 04:05:46 DEBUG : forgetting directory cache
2026/04/11 04:05:46 DEBUG : dir: forgetting directory cache
2026/04/11 04:05:46 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:46 DEBUG : Looking for writers
2026/04/11 04:05:46 DEBUG : >WaitForWriters: 
2026/04/11 04:05:47 DEBUG : pcloud root 'rclone-test-lipopac6zije': ChangeNotify: detected change in "dir/file1" (type: 1)
2026/04/11 04:05:47 DEBUG : changeNotify: relativePath="dir/file1", type=1
2026/04/11 04:05:47 DEBUG : >changeNotify: 
--- PASS: TestDirForgetAll (1.43s)
=== RUN   TestDirForgetPath
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:47 NOTICE: pcloud root 'rclone-test-lipopac6zije': --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-lipopac6zije'": pcloud error: Directory does not exist. (2005)
2026/04/11 04:05:53 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:05:53 DEBUG : Looking for writers
2026/04/11 04:05:53 DEBUG : >WaitForWriters: 
--- FAIL: TestDirForgetPath (6.17s)
=== RUN   TestDirWalk
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:05:53 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:07:53 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:07:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:07:53 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:07:55 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:07:56 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:10:01 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:10:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:10:01 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:10:02 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:10:03 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:12:09 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:12:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:12:09 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:12:10 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:12:11 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:14:17 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:14:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:14:17 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:14:18 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:14:19 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:15:40 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:40 DEBUG : forgetting directory cache
2026/04/11 04:15:40 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:42 DEBUG : forgetting directory cache
2026/04/11 04:15:42 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:42 DEBUG : dir/subdir: forgetting directory cache
2026/04/11 04:15:44 DEBUG : forgetting directory cache
2026/04/11 04:15:44 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:45 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:45 DEBUG : forgetting directory cache
2026/04/11 04:15:45 DEBUG : dir: forgetting directory cache
2026/04/11 04:15:47 DEBUG : forgetting directory cache
2026/04/11 04:15:53 DEBUG : forgetting directory cache
2026/04/11 04:16:24 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:16:24 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:16:24 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:16:26 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:16:27 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:18:32 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:18:32 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:18:32 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:18:33 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:18:34 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:20:40 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:20:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:20:40 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:20:41 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:20:42 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:22:48 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:22:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:22:48 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:22:49 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:22:50 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:24:55 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:24:55 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:24:56 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:24:57 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:24:58 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:27:03 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:27:03 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:27:03 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:27:04 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:27:06 DEBUG : pacer: Reducing sleep to 10ms
    run.go:290: Failed to put "dir/file1" to "pcloud root 'rclone-test-lipopac6zije'": Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF
2026/04/11 04:27:09 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:27:09 DEBUG : Looking for writers
2026/04/11 04:27:09 DEBUG : >WaitForWriters: 
--- FAIL: TestDirWalk (1275.93s)
=== RUN   TestDirSetModTime
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:27:09 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:29:09 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:29:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:29:09 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:29:10 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:29:12 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:31:17 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:31:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:31:17 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:31:18 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:31:19 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:33:25 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:33:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:33:25 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:33:26 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:33:27 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:35:33 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:35:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:35:33 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:35:34 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:35:35 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:37:09 DEBUG : forgetting directory cache
2026/04/11 04:37:40 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:37:40 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:37:40 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:37:42 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:37:43 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:39:48 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:39:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:39:48 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:39:49 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:39:50 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:41:56 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:41:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:41:56 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:41:57 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:41:58 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:44:04 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:44:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:44:04 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:44:05 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:44:06 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:46:11 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:46:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:46:11 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:46:13 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:46:14 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 9/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:48:19 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:48:19 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:48:19 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:48:20 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:48:22 DEBUG : pacer: Reducing sleep to 10ms
    run.go:290: Failed to put "dir/file1" to "pcloud root 'rclone-test-lipopac6zije'": Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF
2026/04/11 04:48:25 DEBUG : WaitForWriters: timeout=30s
2026/04/11 04:48:25 DEBUG : Looking for writers
2026/04/11 04:48:25 DEBUG : >WaitForWriters: 
--- FAIL: TestDirSetModTime (1275.95s)
=== RUN   TestDirStat
    run.go:185: Remote "pcloud root 'rclone-test-lipopac6zije'", Local "Local file system at /tmp/rclone3973219400", Modify Window "1s"
2026/04/11 04:48:25 NOTICE: pcloud root 'rclone-test-lipopac6zije': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2026/04/11 04:50:25 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:50:25 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:50:25 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:50:26 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:50:28 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 1/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:52:33 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:52:33 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:52:33 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:52:34 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:52:35 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 2/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:54:41 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:54:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:54:41 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:54:42 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:54:43 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 3/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:56:48 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:56:48 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:56:49 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:56:50 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:56:51 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 4/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:58:25 DEBUG : forgetting directory cache
2026/04/11 04:58:56 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 04:58:56 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 04:58:56 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 04:58:58 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 04:58:59 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 5/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:01:04 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:01:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 05:01:04 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 05:01:05 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 05:01:06 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 6/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:03:12 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:03:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 05:03:12 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 05:03:13 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 05:03:14 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 7/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:05:20 DEBUG : pacer: low level retry 1/1 (error Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
2026/04/11 05:05:20 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/04/11 05:05:20 DEBUG : pacer: Reducing sleep to 15ms
2026/04/11 05:05:21 DEBUG : pacer: Reducing sleep to 11.25ms
2026/04/11 05:05:22 DEBUG : pacer: Reducing sleep to 10ms
    run.go:286: Retry Put of "dir/file1" to pcloud root 'rclone-test-lipopac6zije': 8/10 (Put "https://eapi.pcloud.com/uploadfile?filename=file1&folderid=23403367083&mtime=981173106&nopartial=1": EOF)
panic: test timed out after 1h0m0s
	running tests:
		TestDirStat (17m13s)

goroutine 39016 [running]:
testing.(*M).startAlarm.func1()
	/usr/local/go/src/testing/testing.go:2802 +0x34b
created by time.goFunc
	/usr/local/go/src/time/sleep.go:215 +0x2d

goroutine 1 [chan receive, 17 minutes]:
testing.(*T).Run(0x324b72826908, {0x2a36abb?, 0x324b72683ae8?}, 0x2bbf7d0)
	/usr/local/go/src/testing/testing.go:2109 +0x4e5
testing.runTests.func1(0x324b72826908)
	/usr/local/go/src/testing/testing.go:2585 +0x37
testing.tRunner(0x324b72826908, 0x324b72683c10)
	/usr/local/go/src/testing/testing.go:2036 +0xea
testing.runTests({0x2a634d0, 0x18}, {0x2a73283, 0x1c}, 0x324b72b07db8, {0x4681100, 0x59, 0x59}, {0xc26e9388b5075c76, 0x34663ba6d7d, ...})
	/usr/local/go/src/testing/testing.go:2583 +0x505
testing.(*M).Run(0x324b72f02fa0)
	/usr/local/go/src/testing/testing.go:2443 +0x6ac
github.com/rclone/rclone/fstest.TestMain(0x324b72f02fa0)
	/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:226 +0xa6

goroutine 31 [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 30 [syscall, 60 minutes]:
os/signal.signal_recv()
	/usr/local/go/src/runtime/sigqueue.go:152 +0x98
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 32 [select, 60 minutes]:
github.com/rclone/rclone/lib/oauthutil.(*Renew).renewOnExpiry(0x324b72af6b00)
	/home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:43 +0x85
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 27366 [select]:
net/http.(*Transport).getConn(0x324b72cee340, 0x324b72832e10, {{}, 0x0, {0x324b72caa4c0, 0x5}, {0x324b72671cc8, 0x13}, 0x0})
	/usr/local/go/src/net/http/transport.go:1552 +0x4b0
net/http.(*Transport).roundTrip(0x324b72cee340, 0x324b72f90b40)
	/usr/local/go/src/net/http/transport.go:693 +0xa7f
net/http.(*Transport).RoundTrip(...)
	/usr/local/go/src/net/http/roundtrip.go:33
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x324b72ab9da0, 0x324b72f90b40)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a
golang.org/x/oauth2.(*Transport).RoundTrip(0x324b72df4720, 0x324b72f90a00)
	/home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.36.0/transport.go:55 +0x15a
net/http.send(0x324b72f90a00, {0x2bde7e0, 0x324b72df4720}, {0x324b7316b1d8?, 0x4936a6?, 0x0?})
	/usr/local/go/src/net/http/client.go:264 +0x64b
net/http.(*Client).send(0x324b72a79bc0, 0x324b72f90a00, {0x324b7316b201?, 0x324b7316b260?, 0x0?})
	/usr/local/go/src/net/http/client.go:185 +0x258
net/http.(*Client).do(0x324b72a79bc0, 0x324b72f90a00)
	/usr/local/go/src/net/http/client.go:733 +0x9d7
net/http.(*Client).Do(...)
	/usr/local/go/src/net/http/client.go:592
github.com/rclone/rclone/lib/rest.(*Client).Call(0x324b72bc17c0, {0x2c06508, 0x324b72a9c150}, 0x324b7316bb48)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd85
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x324b72bc17c0, {0x2c06508, 0x324b72a9c150}, 0x7cdc52e0a830?, {0x0?, 0x0?}, {0x2369620, 0x324b73289540}, 0x266da40?, 0x2bc11f8, ...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x447
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(0x324b72a76840, {0x2c063b8, 0x46c3560}, 0x324b72c4b1c0, 0x324b72f25a40)
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1135 +0x485
created by github.com/rclone/rclone/backend/pcloud.(*Fs).ChangeNotify in goroutine 27365
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1040 +0xa8

goroutine 27365 [select]:
net/http.(*persistConn).roundTrip(0x324b72cf9400, 0x324b73160320)
	/usr/local/go/src/net/http/transport.go:2911 +0x83d
net/http.(*Transport).roundTrip(0x324b72cee340, 0x324b72cf9180)
	/usr/local/go/src/net/http/transport.go:704 +0xaba
net/http.(*Transport).RoundTrip(...)
	/usr/local/go/src/net/http/roundtrip.go:33
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x324b72ab9da0, 0x324b72cf9180)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:496 +0x34a
golang.org/x/oauth2.(*Transport).RoundTrip(0x324b72df4720, 0x324b72cf9040)
	/home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.36.0/transport.go:55 +0x15a
net/http.send(0x324b72cf9040, {0x2bde7e0, 0x324b72df4720}, {0x324b7316ee90?, 0x4936a6?, 0x0?})
	/usr/local/go/src/net/http/client.go:264 +0x64b
net/http.(*Client).send(0x324b72a79bc0, 0x324b72cf9040, {0x324b7316ee01?, 0x324b7316ef18?, 0x0?})
	/usr/local/go/src/net/http/client.go:185 +0x258
net/http.(*Client).do(0x324b72a79bc0, 0x324b72cf9040)
	/usr/local/go/src/net/http/client.go:733 +0x9d7
net/http.(*Client).Do(...)
	/usr/local/go/src/net/http/client.go:592
github.com/rclone/rclone/lib/rest.(*Client).Call(0x324b72bc17c0, {0x2c06348, 0x46c3560}, 0x324b73034840)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd85
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x324b72bc17c0, {0x2c06348, 0x46c3560}, 0x4?, {0x0?, 0x0?}, {0x24db080, 0x324b73008060}, 0x41e885?, 0x2bc11f8, ...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x447
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(0x324b72ab9e60, 0x324b73178380, 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(0x324b72d443f0, {0x2c06348, 0x46c3560}, {0x2bda8a0, 0x324b72c48a50}, {0x2c17730, 0x324b72a9ca80}, {0x0, 0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1476 +0xb90
github.com/rclone/rclone/backend/pcloud.(*Fs).Put(0x324b72a76840, {0x2c06348, 0x46c3560}, {0x2bda8a0, 0x324b72c48a50}, {0x2c17730, 0x324b72a9ca80}, {0x0, 0x0, 0x0})
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:707 +0x14c
github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0x324b72aa64d0, {0x2c06348, 0x46c3560}, {0x2c246d0, 0x324b72a76840}, {0x2a31167, 0x9}, {0x2a3fac9, 0xe}, {0x1dcd64ff, ...}, ...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:280 +0x3e8
github.com/rclone/rclone/fstest.(*Run).WriteObject(...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:297
github.com/rclone/rclone/vfs.dirCreate(0x324b72fd0248)
	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:25 +0xb2
github.com/rclone/rclone/vfs.TestDirStat(0x324b72fd0248)
	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:229 +0x1c
testing.tRunner(0x324b72fd0248, 0x2bbf7d0)
	/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 1
	/usr/local/go/src/testing/testing.go:2101 +0x4c5

goroutine 131 [select]:
github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0x324b730f2b40, {0x2c06498, 0x324b73032190})
	/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 58
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x11d

goroutine 27371 [select, 17 minutes]:
github.com/rclone/rclone/vfs.(*VFS).signalHandler(0x324b729a4640, {0x2c06498, 0x324b727160f0})
	/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:290 +0xc5
created by github.com/rclone/rclone/vfs.New in goroutine 27365
	/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs.go:266 +0x852

goroutine 38904 [select]:
net/http.(*persistConn).writeLoop(0x324b72cf9400)
	/usr/local/go/src/net/http/transport.go:2652 +0xe6
created by net/http.(*Transport).dialConn in goroutine 38750
	/usr/local/go/src/net/http/transport.go:1995 +0x16fe

goroutine 39026 [IO wait]:
internal/poll.runtime_pollWait(0x7cdc52e0aa00, 0x77)
	/usr/local/go/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0x2c06508?, 0x324b72a66310?, 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:685
net.(*netFD).connect(0x324b7319e200, {0x2c06508, 0x324b72a66310}, {0x427694?, 0x0?}, {0x2bda2c0?, 0x324b72d859c0?})
	/usr/local/go/src/net/fd_unix.go:119 +0x656
net.(*netFD).dial(0x324b7319e200, {0x2c06508, 0x324b72a66310}, {0x2c12c98?, 0x0?}, {0x2c12c98, 0x324b72c71350}, 0x48df45?)
	/usr/local/go/src/net/sock_posix.go:124 +0x3bc
net.socket({0x2c06508, 0x324b72a66310}, {0x2a1fce7, 0x3}, 0x2, 0x1, 0x324b72e2e000?, 0x0, {0x2c12c98, 0x0}, ...)
	/usr/local/go/src/net/sock_posix.go:70 +0x29b
net.internetSocket({0x2c06508, 0x324b72a66310}, {0x2a1fce7, 0x3}, {0x2c12c98, 0x0}, {0x2c12c98, 0x324b72c71350}, 0x1, 0x0, ...)
	/usr/local/go/src/net/ipsock_posix.go:167 +0xf8
net.(*sysDialer).doDialTCPProto(0x324b72bba900, {0x2c06508, 0x324b72a66310}, 0x0, 0x324b72c71350, 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(0x26317c0?, {0x2c06508?, 0x324b72a66310?}, 0x522fcf?, 0x324b72a66310?)
	/usr/local/go/src/net/tcpsock_posix.go:71 +0x69
net.(*sysDialer).dialSingle(0x324b72bba900, {0x2c06508, 0x324b72a66310}, {0x2befe88, 0x324b72c71350})
	/usr/local/go/src/net/dial.go:791 +0x3ae
net.(*sysDialer).dialSerial(0x324b72bba900, {0x2c06508, 0x324b72a9c4d0}, {0x324b72c21500?, 0xa, 0x0?})
	/usr/local/go/src/net/dial.go:756 +0x248
net.(*sysDialer).dialParallel(0x2befe88?, {0x2c06508?, 0x324b72a9c4d0?}, {0x324b72c21500?, 0x25ab2a0?, 0x2a20e15?}, {0x0?, 0x2a1fce7?, 0x7cdc52e41c28?})
	/usr/local/go/src/net/dial.go:657 +0x2f9
net.(*Dialer).DialContext(0x324b72df2000, {0x2c06498?, 0x324b72832e60?}, {0x2a1fce7, 0x3}, {0x324b72671cc8, 0x13})
	/usr/local/go/src/net/dial.go:557 +0x485
github.com/rclone/rclone/fs/fshttp.(*Dialer).DialContext(0x324b72df2000, {0x2c06498, 0x324b72832e60}, {0x2a1fce7?, 0x7cdc08a2bf00?}, {0x324b72671cc8, 0x13})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:62 +0x125
github.com/rclone/rclone/fs/fshttp.NewTransportCustom.func2({0x2c06498, 0x324b72832e60}, {0x2a1fce7, 0x3}, {0x324b72671cc8, 0x13})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:269 +0x5c
net/http.(*Transport).dial(0x5?, {0x2c06498?, 0x324b72832e60?}, {0x2a1fce7?, 0x479547?}, {0x324b72671cc8?, 0xd3?})
	/usr/local/go/src/net/http/transport.go:1307 +0xd2
net/http.(*Transport).dialConn(0x324b72cee340, {0x2c06498, 0x324b72832e60}, {{}, 0x0, {0x324b72caa4c0, 0x5}, {0x324b72671cc8, 0x13}, 0x0}, ...)
	/usr/local/go/src/net/http/transport.go:1815 +0x847
net/http.(*Transport).dialConnFor(0x324b72cee340, 0x324b72f800b0)
	/usr/local/go/src/net/http/transport.go:1648 +0xd2
net/http.(*Transport).startDialConnForLocked.func1()
	/usr/local/go/src/net/http/transport.go:1629 +0x35
created by net/http.(*Transport).startDialConnForLocked in goroutine 27366
	/usr/local/go/src/net/http/transport.go:1628 +0x112

goroutine 38903 [IO wait]:
internal/poll.runtime_pollWait(0x7cdc52e0a400, 0x72)
	/usr/local/go/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0x324b7319e800?, 0x324b7284e600?, 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(0x324b7319e800, {0x324b7284e600, 0x1980, 0x1980})
	/usr/local/go/src/internal/poll/fd_unix.go:165 +0x2ae
net.(*netFD).Read(0x324b7319e800, {0x324b7284e600?, 0x197b?, 0x324b728b0c80?})
	/usr/local/go/src/net/fd_posix.go:68 +0x25
net.(*conn).Read(0x324b72788558, {0x324b7284e600?, 0x324b7284e600?, 0x5?})
	/usr/local/go/src/net/net.go:196 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0x324b72d4ca08, {0x324b7284e600?, 0x7cdc089803e0?, 0x7cdc532292f8?})
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29
crypto/tls.(*atLeastReader).Read(0x324b72d4cd50, {0x324b7284e600?, 0x197b?, 0x324b73104a98?})
	/usr/local/go/src/crypto/tls/conn.go:815 +0x3b
bytes.(*Buffer).ReadFrom(0x324b72debb28, {0x2bdd7c0, 0x324b72d4cd50})
	/usr/local/go/src/bytes/buffer.go:229 +0x98
crypto/tls.(*Conn).readFromUntil(0x324b72deb888, {0x7cdc08a68c00, 0x324b72d4ca08}, 0x324b730635f9?)
	/usr/local/go/src/crypto/tls/conn.go:837 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0x324b72deb888, 0x0)
	/usr/local/go/src/crypto/tls/conn.go:626 +0x3db
crypto/tls.(*Conn).readRecord(...)
	/usr/local/go/src/crypto/tls/conn.go:588
crypto/tls.(*Conn).Read(0x324b72deb888, {0x324b72d6e000, 0x1000, 0x70?})
	/usr/local/go/src/crypto/tls/conn.go:1393 +0x145
net/http.(*persistConn).Read(0x324b72cf9400, {0x324b72d6e000?, 0x70?, 0x0?})
	/usr/local/go/src/net/http/transport.go:2174 +0x47
bufio.(*Reader).fill(0x324b72f60fc0)
	/usr/local/go/src/bufio/bufio.go:113 +0x103
bufio.(*Reader).Peek(0x324b72f60fc0, 0x1)
	/usr/local/go/src/bufio/bufio.go:152 +0x53
net/http.(*persistConn).readLoop(0x324b72cf9400)
	/usr/local/go/src/net/http/transport.go:2330 +0x172
created by net/http.(*Transport).dialConn in goroutine 38750
	/usr/local/go/src/net/http/transport.go:1994 +0x16ac
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Finished ERROR in 1h0m0.872584s (try 1/5): exit status 2: Failed [TestDirForgetPath TestDirWalk TestDirSetModTime]