"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Starting (try 1/5)
2020/11/03 05:04:40 DEBUG : Creating backend with remote "TestPcloud:rclone-test-cudiruw0zerupat5foripec5"
2020/11/03 05:04:43 DEBUG : Creating backend with remote "/tmp/rclone656813155"
=== RUN   TestDirHandleMethods
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:04:43 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:04:43 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:04:49 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:04:49 DEBUG : dir: Looking for writers
2020/11/03 05:04:49 DEBUG : : Looking for writers
2020/11/03 05:04:49 DEBUG : dir: reading active writers
2020/11/03 05:04:49 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleMethods (8.08s)
=== RUN   TestDirHandleReaddir
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:04:51 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:04:51 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:04:58 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:04:58 DEBUG : dir/subdir: Looking for writers
2020/11/03 05:04:58 DEBUG : dir: Looking for writers
2020/11/03 05:04:58 DEBUG : file1: reading active writers
2020/11/03 05:04:58 DEBUG : file2: reading active writers
2020/11/03 05:04:58 DEBUG : subdir: reading active writers
2020/11/03 05:04:58 DEBUG : : Looking for writers
2020/11/03 05:04:58 DEBUG : dir: reading active writers
2020/11/03 05:04:58 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddir (10.85s)
=== RUN   TestDirHandleReaddirnames
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:02 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:02 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:06 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:06 DEBUG : dir: Looking for writers
2020/11/03 05:05:06 DEBUG : file1: reading active writers
2020/11/03 05:05:06 DEBUG : : Looking for writers
2020/11/03 05:05:06 DEBUG : dir: reading active writers
2020/11/03 05:05:06 DEBUG : >WaitForWriters: 
--- PASS: TestDirHandleReaddirnames (5.95s)
=== RUN   TestDirMethods
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:08 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:08 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:11 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:11 DEBUG : dir: Looking for writers
2020/11/03 05:05:11 DEBUG : : Looking for writers
2020/11/03 05:05:11 DEBUG : dir: reading active writers
2020/11/03 05:05:11 DEBUG : >WaitForWriters: 
--- PASS: TestDirMethods (5.59s)
=== RUN   TestDirForgetAll
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:13 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:13 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:17 DEBUG : dir: forgetting directory cache
2020/11/03 05:05:17 DEBUG : : forgetting directory cache
2020/11/03 05:05:17 DEBUG : dir: forgetting directory cache
2020/11/03 05:05:17 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:17 DEBUG : : Looking for writers
2020/11/03 05:05:17 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (6.11s)
=== RUN   TestDirForgetPath
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:20 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:20 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:23 DEBUG : : ForgetPath: relativePath="dir/notfound", type=3
2020/11/03 05:05:23 DEBUG : dir: invalidating directory cache
2020/11/03 05:05:23 DEBUG : : >ForgetPath: 
2020/11/03 05:05:23 DEBUG : : ForgetPath: relativePath="dir", type=2
2020/11/03 05:05:23 DEBUG : : invalidating directory cache
2020/11/03 05:05:23 DEBUG : dir: forgetting directory cache
2020/11/03 05:05:23 DEBUG : : >ForgetPath: 
2020/11/03 05:05:23 DEBUG : : ForgetPath: relativePath="not/in/cache", type=2
2020/11/03 05:05:23 DEBUG : : >ForgetPath: 
2020/11/03 05:05:23 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:23 DEBUG : dir: Looking for writers
2020/11/03 05:05:23 DEBUG : : Looking for writers
2020/11/03 05:05:23 DEBUG : dir: reading active writers
2020/11/03 05:05:23 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetPath (6.11s)
=== RUN   TestDirWalk
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:26 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:26 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:35 DEBUG : : forgetting directory cache
2020/11/03 05:05:35 DEBUG : dir: forgetting directory cache
2020/11/03 05:05:36 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:36 DEBUG : dir: Looking for writers
2020/11/03 05:05:36 DEBUG : fil/a/b: Looking for writers
2020/11/03 05:05:36 DEBUG : fil/a: Looking for writers
2020/11/03 05:05:36 DEBUG : b: reading active writers
2020/11/03 05:05:36 DEBUG : fil: Looking for writers
2020/11/03 05:05:36 DEBUG : a: reading active writers
2020/11/03 05:05:36 DEBUG : : Looking for writers
2020/11/03 05:05:36 DEBUG : dir: reading active writers
2020/11/03 05:05:36 DEBUG : fil: reading active writers
2020/11/03 05:05:36 DEBUG : >WaitForWriters: 
--- PASS: TestDirWalk (15.40s)
=== RUN   TestDirSetModTime
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:41 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:41 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:44 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:44 DEBUG : dir: Looking for writers
2020/11/03 05:05:44 DEBUG : : Looking for writers
2020/11/03 05:05:44 DEBUG : dir: reading active writers
2020/11/03 05:05:44 DEBUG : >WaitForWriters: 
--- PASS: TestDirSetModTime (5.56s)
=== RUN   TestDirStat
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:47 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:47 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:05:50 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:05:50 DEBUG : dir: Looking for writers
2020/11/03 05:05:50 DEBUG : file1: reading active writers
2020/11/03 05:05:50 DEBUG : : Looking for writers
2020/11/03 05:05:50 DEBUG : dir: reading active writers
2020/11/03 05:05:50 DEBUG : >WaitForWriters: 
2020/11/03 05:05:53 ERROR : dir: error listing: couldn't list files: pcloud error: Directory does not exist. (2005)
    fstest.go:279: Error listing: couldn't list files: pcloud error: Directory does not exist. (2005)
--- FAIL: TestDirStat (6.32s)
=== RUN   TestDirReadDirAll
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:05:53 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:05:53 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 05:10:53 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:10:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2020/11/03 05:10:54 DEBUG : pacer: Reducing sleep to 15ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 1/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:15:56 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:15:56 DEBUG : pacer: Rate limited, increasing sleep to 30ms
2020/11/03 05:15:57 DEBUG : pacer: Reducing sleep to 22.5ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 2/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:20:59 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:42896->74.120.8.7:443: i/o timeout)
2020/11/03 05:20:59 DEBUG : pacer: Rate limited, increasing sleep to 45ms
2020/11/03 05:20:59 DEBUG : pacer: Reducing sleep to 33.75ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 3/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:42896->74.120.8.7:443: i/o timeout)
2020/11/03 05:26:02 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:55422->74.120.8.7:443: i/o timeout)
2020/11/03 05:26:02 DEBUG : pacer: Rate limited, increasing sleep to 67.5ms
2020/11/03 05:26:02 DEBUG : pacer: Reducing sleep to 50.625ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 4/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:55422->74.120.8.7:443: i/o timeout)
2020/11/03 05:31:04 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:31:04 DEBUG : pacer: Rate limited, increasing sleep to 101.25ms
2020/11/03 05:31:05 DEBUG : pacer: Reducing sleep to 75.9375ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 5/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:36:07 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:36:07 DEBUG : pacer: Rate limited, increasing sleep to 151.875ms
2020/11/03 05:36:08 DEBUG : pacer: Reducing sleep to 113.90625ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 6/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:41:10 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:41:10 DEBUG : pacer: Rate limited, increasing sleep to 227.8125ms
2020/11/03 05:41:11 DEBUG : pacer: Reducing sleep to 170.859375ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 7/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:46:13 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:54332->74.120.8.7:443: i/o timeout)
2020/11/03 05:46:13 DEBUG : pacer: Rate limited, increasing sleep to 341.71875ms
2020/11/03 05:46:13 DEBUG : pacer: Reducing sleep to 256.289062ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 8/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:54332->74.120.8.7:443: i/o timeout)
2020/11/03 05:51:16 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:51:16 DEBUG : pacer: Rate limited, increasing sleep to 512.578124ms
2020/11/03 05:51:16 DEBUG : pacer: Reducing sleep to 384.433593ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 9/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 05:56:19 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:51786->74.120.8.7:443: i/o timeout)
2020/11/03 05:56:19 DEBUG : pacer: Rate limited, increasing sleep to 768.867186ms
2020/11/03 05:56:19 DEBUG : pacer: Reducing sleep to 576.650389ms
    run.go:282: Failed to put "dir/file1" to "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'": Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": read tcp 167.172.51.213:51786->74.120.8.7:443: i/o timeout
2020/11/03 05:56:19 DEBUG : WaitForWriters: timeout=10s
2020/11/03 05:56:19 DEBUG : : Looking for writers
2020/11/03 05:56:19 DEBUG : >WaitForWriters: 
2020/11/03 05:56:20 DEBUG : pacer: Reducing sleep to 432.487791ms
2020/11/03 05:56:20 DEBUG : pacer: Reducing sleep to 324.365843ms
--- FAIL: TestDirReadDirAll (3027.43s)
=== RUN   TestDirOpen
    run.go:176: Remote "pcloud root 'rclone-test-cudiruw0zerupat5foripec5'", Local "Local file system at /tmp/rclone656813155", Modify Window "1s"
2020/11/03 05:56:20 INFO  : pcloud root 'rclone-test-cudiruw0zerupat5foripec5': poll-interval is not supported by this remote
2020/11/03 05:56:20 NOTICE: pcloud root 'rclone-test-cudiruw0zerupat5foripec5': --vfs-cache-mode writes or full is recommended for this remote as it can't stream
2020/11/03 06:01:21 DEBUG : pacer: low level retry 1/1 (error Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
2020/11/03 06:01:21 DEBUG : pacer: Rate limited, increasing sleep to 648.731686ms
2020/11/03 06:01:21 DEBUG : pacer: Reducing sleep to 486.548764ms
    run.go:278: Retry Put of "dir/file1" to pcloud root 'rclone-test-cudiruw0zerupat5foripec5': 1/10 (Put "https://api.pcloud.com/uploadfile?filename=file1&folderid=7562452268&mtime=981173106&nopartial=1": net/http: timeout awaiting response headers)
panic: test timed out after 1h0m0s

goroutine 1721 [running]:
testing.(*M).startAlarm.func1()
	/usr/local/go/src/testing/testing.go:1618 +0xe5
created by time.goFunc
	/usr/local/go/src/time/sleep.go:167 +0x45

goroutine 1 [chan receive, 9 minutes]:
testing.(*T).Run(0xc0000e8f00, 0x19cb2d8, 0xb, 0x1a59b00, 0x49c000)
	/usr/local/go/src/testing/testing.go:1169 +0x2da
testing.runTests.func1(0xc000676000)
	/usr/local/go/src/testing/testing.go:1439 +0x78
testing.tRunner(0xc000676000, 0xc0002fbd48)
	/usr/local/go/src/testing/testing.go:1123 +0xef
testing.runTests(0xc000626e40, 0x265d7c0, 0x49, 0x49, 0xbfe05a9eddbc24e6, 0x346f6330fb4, 0x266c360, 0x14)
	/usr/local/go/src/testing/testing.go:1437 +0x2fe
testing.(*M).Run(0xc000178000, 0x0)
	/usr/local/go/src/testing/testing.go:1345 +0x1eb
github.com/rclone/rclone/fstest.TestMain(0xc000178000)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:68 +0x9f
github.com/rclone/rclone/vfs.TestMain(...)
	/home/rclone/go/src/github.com/rclone/rclone/vfs/vfs_test.go:37
main.main()
	_testmain.go:191 +0x165

goroutine 8 [chan receive, 60 minutes]:
github.com/rclone/rclone/lib/oauthutil.(*Renew).renewOnExpiry(0xc00035d860)
	/home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:39 +0x68
created by github.com/rclone/rclone/lib/oauthutil.NewRenew
	/home/rclone/go/src/github.com/rclone/rclone/lib/oauthutil/renew.go:28 +0x85

goroutine 22 [select]:
go.opencensus.io/stats/view.(*worker).start(0xc000179300)
	/home/rclone/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:276 +0x105
created by go.opencensus.io/stats/view.init.0
	/home/rclone/go/pkg/mod/go.opencensus.io@v0.22.5/stats/view/worker.go:34 +0x68

goroutine 1681 [select, 4 minutes]:
net/http.(*persistConn).roundTrip(0xc000158480, 0xc0000fe100, 0x0, 0x0, 0x0)
	/usr/local/go/src/net/http/transport.go:2565 +0x779
net/http.(*Transport).roundTrip(0xc0001ad540, 0xc0000bc900, 0x19c9e11, 0xa, 0xc000164a58)
	/usr/local/go/src/net/http/transport.go:582 +0xa65
net/http.(*Transport).RoundTrip(...)
	/usr/local/go/src/net/http/roundtrip.go:17
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000237f80, 0xc0000bc900, 0x0, 0x0, 0x160)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:337 +0x393
golang.org/x/oauth2.(*Transport).RoundTrip(0xc000206260, 0xc0000bc500, 0x0, 0x0, 0x0)
	/home/rclone/go/pkg/mod/golang.org/x/oauth2@v0.0.0-20200902213428-5d25da1a8d43/transport.go:55 +0x159
net/http.send(0xc0000bc500, 0x1c4b400, 0xc000206260, 0x0, 0x0, 0x0, 0xc000010030, 0x203000, 0x1, 0x0)
	/usr/local/go/src/net/http/client.go:252 +0x453
net/http.(*Client).send(0xc00035d7d0, 0xc0000bc500, 0x0, 0x0, 0x0, 0xc000010030, 0x0, 0x1, 0x20)
	/usr/local/go/src/net/http/client.go:176 +0xff
net/http.(*Client).do(0xc00035d7d0, 0xc0000bc500, 0x0, 0x0, 0x0)
	/usr/local/go/src/net/http/client.go:718 +0x45f
net/http.(*Client).Do(...)
	/usr/local/go/src/net/http/client.go:586
github.com/rclone/rclone/lib/rest.(*Client).Call(0xc000090640, 0x1c74a40, 0xc00011c010, 0xc000158360, 0x0, 0x0, 0x0)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:280 +0x946
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0xc000090640, 0x1c74a40, 0xc00011c010, 0xc000158360, 0x0, 0x0, 0x1717700, 0xc0002ccae0, 0x1a58110, 0x1a59a58, ...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:464 +0x212
github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:407
github.com/rclone/rclone/backend/pcloud.(*Object).Update.func1(0xc00004c418, 0x7fa056e89fa0, 0x10000000000007f)
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1146 +0xc5
github.com/rclone/rclone/fs.pacerInvoker(0x1, 0x1, 0xc0000fe080, 0x6c00000000000009, 0xc0000a1840, 0x40ffb8)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fs.go:1487 +0x3c
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00007e7e0, 0xc0000fe080, 0x1, 0x5, 0xc0001644f0)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x90
github.com/rclone/rclone/lib/pacer.(*Pacer).CallNoRetry(...)
	/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:225
github.com/rclone/rclone/backend/pcloud.(*Object).Update(0xc0004a4080, 0x1c74a40, 0xc00011c010, 0x1c49460, 0xc0005d6120, 0x1c838c0, 0xc000090690, 0x0, 0x0, 0x0, ...)
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:1145 +0x8cc
github.com/rclone/rclone/backend/pcloud.(*Fs).Put(0xc0001ed0a0, 0x1c74a40, 0xc00011c010, 0x1c49460, 0xc0005d6120, 0x1c838c0, 0xc000090690, 0x0, 0x0, 0x0, ...)
	/home/rclone/go/src/github.com/rclone/rclone/backend/pcloud/pcloud.go:565 +0x1f6
github.com/rclone/rclone/fstest.(*Run).WriteObjectTo(0xc0005ddb60, 0x1c74a40, 0xc00011c010, 0x1c8a0a0, 0xc0001ed0a0, 0x19c8a93, 0x9, 0x19cff60, 0xe, 0x1dcd64ff, ...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:272 +0x364
github.com/rclone/rclone/fstest.(*Run).WriteObject(...)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:289
github.com/rclone/rclone/vfs.dirCreate(0xc0000e8f00, 0x528280, 0x1e38e80, 0x25f08a0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)
	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:21 +0x148
github.com/rclone/rclone/vfs.TestDirOpen(0xc0000e8f00)
	/home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:324 +0x54
testing.tRunner(0xc0000e8f00, 0x1a59b00)
	/usr/local/go/src/testing/testing.go:1123 +0xef
created by testing.(*T).Run
	/usr/local/go/src/testing/testing.go:1168 +0x2b3

goroutine 1710 [select, 4 minutes]:
net/http.(*persistConn).writeLoop(0xc000158480)
	/usr/local/go/src/net/http/transport.go:2340 +0x11c
created by net/http.(*Transport).dialConn
	/usr/local/go/src/net/http/transport.go:1709 +0xcdc

goroutine 1709 [IO wait, 4 minutes]:
internal/poll.runtime_pollWait(0x7fa056e6deb8, 0x72, 0x1c4fae0)
	/usr/local/go/src/runtime/netpoll.go:220 +0x55
internal/poll.(*pollDesc).wait(0xc0004a4318, 0x72, 0x1c4fa00, 0x25d5948, 0x0)
	/usr/local/go/src/internal/poll/fd_poll_runtime.go:87 +0x45
internal/poll.(*pollDesc).waitRead(...)
	/usr/local/go/src/internal/poll/fd_poll_runtime.go:92
internal/poll.(*FD).Read(0xc0004a4300, 0xc0002c6000, 0x1023, 0x1023, 0x0, 0x0, 0x0)
	/usr/local/go/src/internal/poll/fd_unix.go:159 +0x1a5
net.(*netFD).Read(0xc0004a4300, 0xc0002c6000, 0x1023, 0x1023, 0xc0005e8280, 0xc0000c9d40, 0x205)
	/usr/local/go/src/net/fd_posix.go:55 +0x4f
net.(*conn).Read(0xc0003f3c80, 0xc0002c6000, 0x1023, 0x1023, 0x0, 0x0, 0x0)
	/usr/local/go/src/net/net.go:182 +0x8e
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0003dafc0, 0xc000072580, 0xc0002c6000, 0x1023, 0x1023, 0x203000, 0xc0000c9d40, 0x205)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:75 +0x48
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0003dafc0, 0xc0002c6000, 0x1023, 0x1023, 0xc00012e020, 0xc0000c9d40, 0x7fa056e5c448)
	/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:87 +0x8a
crypto/tls.(*atLeastReader).Read(0xc00012ed40, 0xc0002c6000, 0x1023, 0x1023, 0xc00012e100, 0xc0000c9d40, 0xc000072668)
	/usr/local/go/src/crypto/tls/conn.go:779 +0x62
bytes.(*Buffer).ReadFrom(0xc000112d00, 0x1c49620, 0xc00012ed40, 0x40d365, 0x1768580, 0x18bb060)
	/usr/local/go/src/bytes/buffer.go:204 +0xb1
crypto/tls.(*Conn).readFromUntil(0xc000112a80, 0x7fa0563f32d8, 0xc0003dafc0, 0x5, 0xc0003dafc0, 0x7fa0563f32d8)
	/usr/local/go/src/crypto/tls/conn.go:801 +0xf3
crypto/tls.(*Conn).readRecordOrCCS(0xc000112a80, 0x0, 0x0, 0x0)
	/usr/local/go/src/crypto/tls/conn.go:608 +0x115
crypto/tls.(*Conn).readRecord(...)
	/usr/local/go/src/crypto/tls/conn.go:576
crypto/tls.(*Conn).Read(0xc000112a80, 0xc0000e0000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
	/usr/local/go/src/crypto/tls/conn.go:1252 +0x15f
net/http.(*persistConn).Read(0xc000158480, 0xc0000e0000, 0x1000, 0x1000, 0x407430, 0x60, 0x0)
	/usr/local/go/src/net/http/transport.go:1887 +0x77
bufio.(*Reader).fill(0xc0002cd140)
	/usr/local/go/src/bufio/bufio.go:101 +0x105
bufio.(*Reader).Peek(0xc0002cd140, 0x1, 0xc000101200, 0x800100000000, 0x0, 0x0, 0x0)
	/usr/local/go/src/bufio/bufio.go:139 +0x4f
net/http.(*persistConn).readLoop(0xc000158480)
	/usr/local/go/src/net/http/transport.go:2040 +0x1a8
created by net/http.(*Transport).dialConn
	/usr/local/go/src/net/http/transport.go:1708 +0xcb7
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestPcloud: -verbose" - Finished ERROR in 1h0m3.330633527s (try 1/5): exit status 2: Failed [TestDirStat TestDirReadDirAll]