"./vfs.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestDirCreate|TestDirForgetAll|TestDirForgetPath|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods)$'" - Starting (try 2/5)
2021/12/01 07:29:45 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-tufixay0corigup3zinoduc9"
2021/12/01 07:29:45 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE
2021/12/01 07:29:45 DEBUG : Setting url="http://172.17.0.2:8080/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2021/12/01 07:29:45 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2021/12/01 07:29:45 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2021/12/01 07:29:45 DEBUG : Setting pass="Q6hDBuLaIcv1yPi5sUvx6iEtwh_jOzaqAays1Ozmo2u_aIY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2021/12/01 07:29:45 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KZhjV}" suffix to name
2021/12/01 07:29:45 DEBUG : Setting url="http://172.17.0.2:8080/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2021/12/01 07:29:45 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2021/12/01 07:29:45 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2021/12/01 07:29:45 DEBUG : Setting pass="Q6hDBuLaIcv1yPi5sUvx6iEtwh_jOzaqAays1Ozmo2u_aIY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2021/12/01 07:29:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/12/01 07:29:45 DEBUG : found headers: 
2021/12/01 07:29:45 DEBUG : Creating backend with remote "/tmp/rclone186824152"
=== RUN   TestDirForgetAll
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:45 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:46 DEBUG : dir: forgetting directory cache
2021/12/01 07:29:46 DEBUG : : forgetting directory cache
2021/12/01 07:29:46 DEBUG : dir: forgetting directory cache
2021/12/01 07:29:46 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:46 DEBUG : : Looking for writers
2021/12/01 07:29:46 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetAll (1.25s)
=== RUN   TestDirForgetPath
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:46 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:47 DEBUG : : ForgetPath: relativePath="dir/notfound", type=1
2021/12/01 07:29:47 DEBUG : dir: invalidating directory cache
2021/12/01 07:29:47 DEBUG : : >ForgetPath: 
2021/12/01 07:29:47 DEBUG : : ForgetPath: relativePath="dir", type=0
2021/12/01 07:29:47 DEBUG : : invalidating directory cache
2021/12/01 07:29:47 DEBUG : dir: forgetting directory cache
2021/12/01 07:29:47 DEBUG : : >ForgetPath: 
2021/12/01 07:29:47 DEBUG : : ForgetPath: relativePath="not/in/cache", type=0
2021/12/01 07:29:47 DEBUG : : >ForgetPath: 
2021/12/01 07:29:47 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:47 DEBUG : dir: Looking for writers
2021/12/01 07:29:47 DEBUG : : Looking for writers
2021/12/01 07:29:47 DEBUG : dir: reading active writers
2021/12/01 07:29:47 DEBUG : >WaitForWriters: 
--- PASS: TestDirForgetPath (1.07s)
=== RUN   TestDirWalk
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:47 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:49 DEBUG : : forgetting directory cache
2021/12/01 07:29:49 DEBUG : dir: forgetting directory cache
2021/12/01 07:29:49 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:49 DEBUG : dir: Looking for writers
2021/12/01 07:29:49 DEBUG : fil/a/b: Looking for writers
2021/12/01 07:29:49 DEBUG : fil/a: Looking for writers
2021/12/01 07:29:49 DEBUG : b: reading active writers
2021/12/01 07:29:49 DEBUG : fil: Looking for writers
2021/12/01 07:29:49 DEBUG : a: reading active writers
2021/12/01 07:29:49 DEBUG : : Looking for writers
2021/12/01 07:29:49 DEBUG : dir: reading active writers
2021/12/01 07:29:49 DEBUG : fil: reading active writers
2021/12/01 07:29:49 DEBUG : >WaitForWriters: 
--- PASS: TestDirWalk (3.13s)
=== RUN   TestDirSetModTime
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:51 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:51 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:51 DEBUG : dir: Looking for writers
2021/12/01 07:29:51 DEBUG : : Looking for writers
2021/12/01 07:29:51 DEBUG : dir: reading active writers
2021/12/01 07:29:51 DEBUG : >WaitForWriters: 
--- PASS: TestDirSetModTime (1.00s)
=== RUN   TestDirStat
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:52 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:52 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:52 DEBUG : dir: Looking for writers
2021/12/01 07:29:52 DEBUG : file1: reading active writers
2021/12/01 07:29:52 DEBUG : : Looking for writers
2021/12/01 07:29:52 DEBUG : dir: reading active writers
2021/12/01 07:29:52 DEBUG : >WaitForWriters: 
--- PASS: TestDirStat (1.12s)
=== RUN   TestDirReadDirAll
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:53 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
=== RUN   TestDirReadDirAll/Virtual
2021/12/01 07:29:54 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile"
2021/12/01 07:29:54 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir"
2021/12/01 07:29:54 DEBUG : dir: Added virtual directory entry vDel: "file2"
2021/12/01 07:29:54 DEBUG : dir: Added virtual directory entry vDel: "subdir"
2021/12/01 07:29:55 DEBUG : dir: invalidating directory cache
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir"
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vDel: "file2"
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vDel: "subdir"
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile"
2021/12/01 07:29:55 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2"
2021/12/01 07:29:55 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2"
2021/12/01 07:29:55 DEBUG : dir: Added virtual directory entry vDel: "file1"
2021/12/01 07:29:55 DEBUG : dir: invalidating directory cache
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2"
2021/12/01 07:29:55 DEBUG : dir: Removed virtual directory entry vDel: "file1"
2021/12/01 07:29:55 DEBUG : : forgetting directory cache
2021/12/01 07:29:55 DEBUG : dir: forgetting directory cache
2021/12/01 07:29:55 DEBUG : dir/virtualDir: forgetting directory cache
2021/12/01 07:29:55 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:55 DEBUG : dir/virtualDir: Looking for writers
2021/12/01 07:29:55 DEBUG : dir: Looking for writers
2021/12/01 07:29:55 DEBUG : file1: reading active writers
2021/12/01 07:29:55 DEBUG : virtualFile2: reading active writers
2021/12/01 07:29:55 DEBUG : virtualFile: reading active writers
2021/12/01 07:29:55 DEBUG : virtualDir: reading active writers
2021/12/01 07:29:55 DEBUG : : Looking for writers
2021/12/01 07:29:55 DEBUG : dir: reading active writers
2021/12/01 07:29:55 DEBUG : >WaitForWriters: 
--- PASS: TestDirReadDirAll (3.30s)
    --- PASS: TestDirReadDirAll/Virtual (1.08s)
=== RUN   TestDirOpen
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:56 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:57 ERROR : dir/: Can only open directories read only
2021/12/01 07:29:57 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:57 DEBUG : dir: Looking for writers
2021/12/01 07:29:57 DEBUG : : Looking for writers
2021/12/01 07:29:57 DEBUG : dir: reading active writers
2021/12/01 07:29:57 DEBUG : >WaitForWriters: 
--- PASS: TestDirOpen (1.02s)
=== RUN   TestDirCreate
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:57 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:58 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE
2021/12/01 07:29:58 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2021/12/01 07:29:58 DEBUG : dir/potato: >Open: fd=dir/potato (w), err=<nil>
2021/12/01 07:29:58 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2021/12/01 07:29:58 DEBUG : webdav root 'rclone-test-tufixay0corigup3zinoduc9': File to upload is small (5 bytes), uploading instead of streaming
2021/12/01 07:29:58 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK
2021/12/01 07:29:58 INFO  : dir/potato: Copied (new)
2021/12/01 07:29:58 DEBUG : dir: Added virtual directory entry vAddFile: "potato"
2021/12/01 07:29:58 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:29:58 DEBUG : dir: Looking for writers
2021/12/01 07:29:58 DEBUG : file1: reading active writers
2021/12/01 07:29:58 DEBUG : potato: reading active writers
2021/12/01 07:29:58 DEBUG : : Looking for writers
2021/12/01 07:29:58 DEBUG : dir: reading active writers
2021/12/01 07:29:58 DEBUG : >WaitForWriters: 
--- PASS: TestDirCreate (1.50s)
=== RUN   TestDirMkdir
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:29:59 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:29:59 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2021/12/01 07:30:00 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:00 DEBUG : dir/sub: Looking for writers
2021/12/01 07:30:00 DEBUG : dir: Looking for writers
2021/12/01 07:30:00 DEBUG : file1: reading active writers
2021/12/01 07:30:00 DEBUG : sub: reading active writers
2021/12/01 07:30:00 DEBUG : : Looking for writers
2021/12/01 07:30:00 DEBUG : dir: reading active writers
2021/12/01 07:30:00 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdir (1.75s)
=== RUN   TestDirMkdirSub
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:00 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:01 DEBUG : dir: Added virtual directory entry vAddDir: "sub"
2021/12/01 07:30:01 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub"
2021/12/01 07:30:02 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:02 DEBUG : dir/sub/subsub: Looking for writers
2021/12/01 07:30:02 DEBUG : dir/sub: Looking for writers
2021/12/01 07:30:02 DEBUG : subsub: reading active writers
2021/12/01 07:30:02 DEBUG : dir: Looking for writers
2021/12/01 07:30:02 DEBUG : file1: reading active writers
2021/12/01 07:30:02 DEBUG : sub: reading active writers
2021/12/01 07:30:02 DEBUG : : Looking for writers
2021/12/01 07:30:02 DEBUG : dir: reading active writers
2021/12/01 07:30:02 DEBUG : >WaitForWriters: 
--- PASS: TestDirMkdirSub (2.30s)
=== RUN   TestDirRemove
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:03 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:03 ERROR : dir/: Dir.Remove not empty
2021/12/01 07:30:03 DEBUG : dir/file1: Remove: 
2021/12/01 07:30:03 DEBUG : dir: Added virtual directory entry vDel: "file1"
2021/12/01 07:30:03 DEBUG : dir/file1: >Remove: err=<nil>
2021/12/01 07:30:03 DEBUG : : Added virtual directory entry vDel: "dir"
2021/12/01 07:30:04 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:04 DEBUG : : Looking for writers
2021/12/01 07:30:04 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemove (1.08s)
=== RUN   TestDirRemoveAll
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:04 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:04 DEBUG : dir/file1: Remove: 
2021/12/01 07:30:04 DEBUG : dir: Added virtual directory entry vDel: "file1"
2021/12/01 07:30:04 DEBUG : dir/file1: >Remove: err=<nil>
2021/12/01 07:30:05 DEBUG : : Added virtual directory entry vDel: "dir"
2021/12/01 07:30:05 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:05 DEBUG : : Looking for writers
2021/12/01 07:30:05 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveAll (1.10s)
=== RUN   TestDirRemoveName
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:05 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:05 DEBUG : dir/file1: Remove: 
2021/12/01 07:30:05 DEBUG : dir: Added virtual directory entry vDel: "file1"
2021/12/01 07:30:06 DEBUG : dir/file1: >Remove: err=<nil>
2021/12/01 07:30:06 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:06 DEBUG : dir: Looking for writers
2021/12/01 07:30:06 DEBUG : : Looking for writers
2021/12/01 07:30:06 DEBUG : dir: reading active writers
2021/12/01 07:30:06 DEBUG : >WaitForWriters: 
--- PASS: TestDirRemoveName (1.29s)
=== RUN   TestDirRename
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:06 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:07 ERROR : dir/not found: Dir.Rename error: file does not exist
2021/12/01 07:30:07 DEBUG : dir: Updating dir with dir2 0xc000507980
2021/12/01 07:30:07 DEBUG : dir: forgetting directory cache
2021/12/01 07:30:07 DEBUG : : Added virtual directory entry vDel: "dir"
2021/12/01 07:30:07 DEBUG : : Added virtual directory entry vAddDir: "dir2"
2021/12/01 07:30:08 INFO  : dir2/file1: Moved (server-side) to: file2
2021/12/01 07:30:08 DEBUG : file2: Updating file with file2 0xc000507c80
2021/12/01 07:30:08 DEBUG : dir2: Added virtual directory entry vDel: "file1"
2021/12/01 07:30:08 DEBUG : : Added virtual directory entry vAddFile: "file2"
2021/12/01 07:30:08 INFO  : dir2/file3: Deleted
2021/12/01 07:30:09 INFO  : file2: Moved (server-side) to: dir2/file3
2021/12/01 07:30:09 DEBUG : dir2/file3: Updating file with dir2/file3 0xc000507c80
2021/12/01 07:30:09 DEBUG : : Added virtual directory entry vDel: "file2"
2021/12/01 07:30:09 DEBUG : dir2: Added virtual directory entry vAddFile: "file3"
2021/12/01 07:30:09 DEBUG : : Added virtual directory entry vAddDir: "empty directory"
2021/12/01 07:30:09 DEBUG : empty directory: Updating dir with renamed empty directory 0xc00014a000
2021/12/01 07:30:09 DEBUG : empty directory: forgetting directory cache
2021/12/01 07:30:09 DEBUG : : Added virtual directory entry vDel: "empty directory"
2021/12/01 07:30:09 DEBUG : : Added virtual directory entry vAddDir: "renamed empty directory"
2021/12/01 07:30:09 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:09 DEBUG : dir2: Looking for writers
2021/12/01 07:30:09 DEBUG : file3: reading active writers
2021/12/01 07:30:09 DEBUG : renamed empty directory: Looking for writers
2021/12/01 07:30:09 DEBUG : : Looking for writers
2021/12/01 07:30:09 DEBUG : renamed empty directory: reading active writers
2021/12/01 07:30:09 DEBUG : dir2: reading active writers
2021/12/01 07:30:09 DEBUG : >WaitForWriters: 
--- PASS: TestDirRename (3.69s)
=== RUN   TestFileMethods
    run.go:181: Remote "webdav root 'rclone-test-tufixay0corigup3zinoduc9'", Local "Local file system at /tmp/rclone186824152", Modify Window "1s"
2021/12/01 07:30:10 INFO  : webdav root 'rclone-test-tufixay0corigup3zinoduc9': poll-interval is not supported by this remote
2021/12/01 07:30:10 DEBUG : WaitForWriters: timeout=30s
2021/12/01 07:30:10 DEBUG : dir: Looking for writers
2021/12/01 07:30:10 DEBUG : file1: reading active writers
2021/12/01 07:30:10 DEBUG : : Looking for writers
2021/12/01 07:30:10 DEBUG : dir: reading active writers
2021/12/01 07:30:10 DEBUG : >WaitForWriters: 
--- PASS: TestFileMethods (1.12s)
PASS
2021/12/01 07:30:11 DEBUG : webdav root 'rclone-test-tufixay0corigup3zinoduc9': Purge remote
"./vfs.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestDirCreate|TestDirForgetAll|TestDirForgetPath|TestDirMkdir|TestDirMkdirSub|TestDirOpen|TestDirReadDirAll|TestDirRemove|TestDirRemoveAll|TestDirRemoveName|TestDirRename|TestDirSetModTime|TestDirStat|TestDirWalk|TestFileMethods)$'" - Finished OK in 26.485638968s (try 2/5)