"./vfs.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose" - Starting (try 1/5) 2026/01/29 05:22:48 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-duxaras9dapa" 2026/01/29 05:22:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/01/29 05:22:48 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2026/01/29 05:22:48 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2026/01/29 05:22:48 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2026/01/29 05:22:48 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2026/01/29 05:22:48 DEBUG : Setting pass="Cr_ivY3osQ3qloJlHm9DQuNX-Lvcenxs0DxH50gJrcyybF4" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2026/01/29 05:22:48 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{CZiVy}" suffix to name 2026/01/29 05:22:48 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2026/01/29 05:22:48 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2026/01/29 05:22:48 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2026/01/29 05:22:48 DEBUG : Setting pass="Cr_ivY3osQ3qloJlHm9DQuNX-Lvcenxs0DxH50gJrcyybF4" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2026/01/29 05:22:48 DEBUG : found headers: 2026/01/29 05:22:50 DEBUG : Creating backend with remote "/tmp/rclone374859474" === RUN TestDirHandleMethods run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:50 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:50 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:50 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:50 DEBUG : dir: Looking for writers 2026/01/29 05:22:50 DEBUG : Looking for writers 2026/01/29 05:22:50 DEBUG : dir: reading active writers 2026/01/29 05:22:50 DEBUG : >WaitForWriters: --- PASS: TestDirHandleMethods (1.38s) === RUN TestDirHandleReaddir run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:51 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:51 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:52 DEBUG : dir/file2: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:52 DEBUG : dir/subdir/file3: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:52 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:52 DEBUG : dir/subdir: Looking for writers 2026/01/29 05:22:52 DEBUG : dir: Looking for writers 2026/01/29 05:22:52 DEBUG : file1: reading active writers 2026/01/29 05:22:52 DEBUG : file2: reading active writers 2026/01/29 05:22:52 DEBUG : subdir: reading active writers 2026/01/29 05:22:52 DEBUG : Looking for writers 2026/01/29 05:22:52 DEBUG : dir: reading active writers 2026/01/29 05:22:52 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddir (2.62s) === RUN TestDirHandleReaddirnames run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:54 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:54 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:54 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:54 DEBUG : dir: Looking for writers 2026/01/29 05:22:54 DEBUG : file1: reading active writers 2026/01/29 05:22:54 DEBUG : Looking for writers 2026/01/29 05:22:54 DEBUG : dir: reading active writers 2026/01/29 05:22:54 DEBUG : >WaitForWriters: --- PASS: TestDirHandleReaddirnames (1.20s) === RUN TestDirMethods run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:55 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:55 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:56 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:56 DEBUG : dir: Looking for writers 2026/01/29 05:22:56 DEBUG : Looking for writers 2026/01/29 05:22:56 DEBUG : dir: reading active writers 2026/01/29 05:22:56 DEBUG : >WaitForWriters: --- PASS: TestDirMethods (1.10s) === RUN TestDirForgetAll run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:56 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:56 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:57 DEBUG : dir: forgetting directory cache 2026/01/29 05:22:57 DEBUG : forgetting directory cache 2026/01/29 05:22:57 DEBUG : dir: forgetting directory cache 2026/01/29 05:22:57 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:57 DEBUG : Looking for writers 2026/01/29 05:22:57 DEBUG : >WaitForWriters: --- PASS: TestDirForgetAll (1.13s) === RUN TestDirForgetPath run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:57 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:57 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:22:58 DEBUG : ForgetPath: relativePath="dir/notfound", type=1 2026/01/29 05:22:58 DEBUG : dir: invalidating directory cache 2026/01/29 05:22:58 DEBUG : >ForgetPath: 2026/01/29 05:22:58 DEBUG : ForgetPath: relativePath="dir", type=0 2026/01/29 05:22:58 DEBUG : invalidating directory cache 2026/01/29 05:22:58 DEBUG : dir: forgetting directory cache 2026/01/29 05:22:58 DEBUG : >ForgetPath: 2026/01/29 05:22:58 DEBUG : ForgetPath: relativePath="not/in/cache", type=0 2026/01/29 05:22:58 DEBUG : >ForgetPath: 2026/01/29 05:22:58 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:22:58 DEBUG : dir: Looking for writers 2026/01/29 05:22:58 DEBUG : Looking for writers 2026/01/29 05:22:58 DEBUG : dir: reading active writers 2026/01/29 05:22:58 DEBUG : >WaitForWriters: --- PASS: TestDirForgetPath (1.13s) === RUN TestDirWalk run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:22:58 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:22:58 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:23:00 DEBUG : fil/a/b/c: Update will use the normal upload strategy (no chunks) 2026/01/29 05:23:00 DEBUG : forgetting directory cache 2026/01/29 05:23:00 DEBUG : dir: forgetting directory cache 2026/01/29 05:23:00 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:23:00 DEBUG : dir: Looking for writers 2026/01/29 05:23:00 DEBUG : fil/a/b: Looking for writers 2026/01/29 05:23:00 DEBUG : fil/a: Looking for writers 2026/01/29 05:23:00 DEBUG : b: reading active writers 2026/01/29 05:23:00 DEBUG : fil: Looking for writers 2026/01/29 05:23:00 DEBUG : a: reading active writers 2026/01/29 05:23:00 DEBUG : Looking for writers 2026/01/29 05:23:00 DEBUG : dir: reading active writers 2026/01/29 05:23:00 DEBUG : fil: reading active writers 2026/01/29 05:23:00 DEBUG : >WaitForWriters: 2026/01/29 05:23:01 DEBUG : pacer: low level retry 1/10 (error An exception occurred while executing 'UPDATE "oc_file_locks" SET "lock" = 0 WHERE "key" = ? AND "lock" = -1' with params ["files\/edc38873f4f0671fb432adf69b71c78a"]: SQLSTATE[HY000]: General error: 5 database is locked: Doctrine\DBAL\Exception\LockWaitTimeoutException: 500 Internal Server Error) 2026/01/29 05:23:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:23:01 DEBUG : pacer: Reducing sleep to 15ms run.go:130: removing dir "fil/a/b" failed - try 1/3: rmdir failed: Sabre\DAV\Exception\Forbidden: 403 Forbidden 2026/01/29 05:23:02 DEBUG : pacer: Reducing sleep to 11.25ms run.go:130: removing dir "fil/a/b" failed - try 2/3: directory not found 2026/01/29 05:23:04 DEBUG : pacer: Reducing sleep to 10ms run.go:130: removing dir "fil/a/b" failed - try 3/3: directory not found run.go:133: removing dir "fil/a/b" failed: directory not found 2026/01/29 05:23:05 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:23:05 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:23:05 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:23:05 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:23:05 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:23:05 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:05 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:23:06 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:06 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:23:06 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:06 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:23:08 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:10 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:23:12 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:23:14 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:14 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:23:15 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:17 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:19 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:21 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:23 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:25 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:27 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:29 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:31 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:23:33 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:23:35 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:23:37 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:39 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:41 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:43 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:45 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:47 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:49 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:51 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:23:53 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:23:55 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:23:57 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:23:58 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:23:59 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:24:00 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:24:01 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:24:01 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:24:02 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:24:03 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:24:03 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/01/29 05:24:03 DEBUG : pacer: Reducing sleep to 84.470271ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:24:05 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:24:05 DEBUG : pacer: Reducing sleep to 47.514527ms 2026/01/29 05:24:05 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirWalk Messages: directories --- FAIL: TestDirWalk (70.90s) === RUN TestDirSetModTime run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:24:09 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:24:09 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:24:09 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:24:09 DEBUG : pacer: Reducing sleep to 20.04519ms 2026/01/29 05:24:10 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:24:10 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:24:10 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:24:10 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:24:10 DEBUG : dir: Looking for writers 2026/01/29 05:24:10 DEBUG : fil: Looking for writers 2026/01/29 05:24:10 DEBUG : Looking for writers 2026/01/29 05:24:10 DEBUG : dir: reading active writers 2026/01/29 05:24:10 DEBUG : fil: reading active writers 2026/01/29 05:24:10 DEBUG : >WaitForWriters: 2026/01/29 05:24:10 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:24:10 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:24:11 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:11 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:24:11 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:11 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:24:11 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:11 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:24:11 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:11 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:24:11 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:11 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:24:12 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:12 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:24:13 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:15 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:24:17 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:24:19 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:19 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:24:21 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:23 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:25 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:27 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:29 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:31 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:33 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:35 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:37 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:24:39 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:24:41 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:41 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:24:42 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:44 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:46 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:48 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:50 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:52 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:54 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:56 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:24:58 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:25:00 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:25:02 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:25:04 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:25:05 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:25:06 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:25:06 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:25:07 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:25:07 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:25:08 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:25:08 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/01/29 05:25:09 DEBUG : pacer: Reducing sleep to 84.470271ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:25:11 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:25:11 DEBUG : pacer: Reducing sleep to 47.514527ms 2026/01/29 05:25:11 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirSetModTime Messages: directories --- FAIL: TestDirSetModTime (65.57s) === RUN TestDirStat run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:25:15 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:25:15 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:25:15 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:25:15 DEBUG : pacer: Reducing sleep to 20.04519ms 2026/01/29 05:25:15 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:25:15 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:25:15 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:25:16 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:25:16 DEBUG : dir: Looking for writers 2026/01/29 05:25:16 DEBUG : file1: reading active writers 2026/01/29 05:25:16 DEBUG : fil: Looking for writers 2026/01/29 05:25:16 DEBUG : Looking for writers 2026/01/29 05:25:16 DEBUG : dir: reading active writers 2026/01/29 05:25:16 DEBUG : fil: reading active writers 2026/01/29 05:25:16 DEBUG : >WaitForWriters: 2026/01/29 05:25:16 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:16 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:25:16 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:16 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:25:16 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:16 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:25:16 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:16 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:25:17 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:17 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:25:17 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:17 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:25:17 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:17 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:25:18 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:18 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:25:19 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:21 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:25:23 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:25:25 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:25 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:25:27 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:28 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:30 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:32 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:35 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:37 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:39 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:41 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:43 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:25:45 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:25:46 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:46 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:25:48 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:50 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:52 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:54 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:56 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:25:58 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:00 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:02 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:04 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:26:06 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:26:08 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:26:10 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:26:11 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:26:12 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:26:12 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:26:13 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:26:13 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:26:15 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:26:15 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/01/29 05:26:15 DEBUG : pacer: Reducing sleep to 84.470271ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:26:17 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:26:17 DEBUG : pacer: Reducing sleep to 47.514527ms 2026/01/29 05:26:17 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirStat Messages: directories --- FAIL: TestDirStat (66.42s) === RUN TestDirReadDirAll run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:26:21 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:26:22 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:26:22 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:26:22 DEBUG : pacer: Reducing sleep to 20.04519ms 2026/01/29 05:26:22 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:26:22 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:26:22 DEBUG : dir/file2: Update will use the normal upload strategy (no chunks) 2026/01/29 05:26:22 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:26:23 DEBUG : dir/subdir/file3: Update will use the normal upload strategy (no chunks) dir_test.go:250: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:250 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:271 Error: Not equal: expected: []string{"dir,0,true"} actual : []string{"dir,0,true", "fil,0,true"} Diff: --- Expected +++ Actual @@ -1,3 +1,4 @@ -([]string) (len=1) { - (string) (len=10) "dir,0,true" +([]string) (len=2) { + (string) (len=10) "dir,0,true", + (string) (len=10) "fil,0,true" } Test: TestDirReadDirAll === RUN TestDirReadDirAll/Virtual 2026/01/29 05:26:23 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile" 2026/01/29 05:26:23 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir" 2026/01/29 05:26:23 DEBUG : dir: Added virtual directory entry vDel: "file2" 2026/01/29 05:26:23 DEBUG : dir: Added virtual directory entry vDel: "subdir" 2026/01/29 05:26:23 DEBUG : dir/virtualFile: Update will use the normal upload strategy (no chunks) 2026/01/29 05:26:24 DEBUG : dir/virtualDir/testFile: Update will use the normal upload strategy (no chunks) 2026/01/29 05:26:25 DEBUG : dir: invalidating directory cache 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir" 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vDel: "file2" 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vDel: "subdir" 2026/01/29 05:26:25 DEBUG : dir/file1: Reset virtual modtime 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vAddFile: "virtualFile" 2026/01/29 05:26:25 DEBUG : dir/virtualFile: Reset virtual modtime 2026/01/29 05:26:25 DEBUG : dir: Added virtual directory entry vAddFile: "virtualFile2" 2026/01/29 05:26:25 DEBUG : dir: Added virtual directory entry vAddDir: "virtualDir2" 2026/01/29 05:26:25 DEBUG : dir: Added virtual directory entry vDel: "file1" 2026/01/29 05:26:25 DEBUG : dir: invalidating directory cache 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vAddDir: "virtualDir2" 2026/01/29 05:26:25 DEBUG : dir: Removed virtual directory entry vDel: "file1" 2026/01/29 05:26:25 DEBUG : dir/virtualFile: Reset virtual modtime 2026/01/29 05:26:25 DEBUG : forgetting directory cache 2026/01/29 05:26:25 DEBUG : dir: forgetting directory cache 2026/01/29 05:26:25 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 05:26:25 DEBUG : fil: forgetting directory cache 2026/01/29 05:26:25 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:26:25 DEBUG : dir/virtualDir: Looking for writers 2026/01/29 05:26:25 DEBUG : dir: Looking for writers 2026/01/29 05:26:25 DEBUG : file1: reading active writers 2026/01/29 05:26:25 DEBUG : virtualFile2: reading active writers 2026/01/29 05:26:25 DEBUG : virtualFile: reading active writers 2026/01/29 05:26:25 DEBUG : virtualDir: reading active writers 2026/01/29 05:26:25 DEBUG : fil: Looking for writers 2026/01/29 05:26:25 DEBUG : Looking for writers 2026/01/29 05:26:25 DEBUG : dir: reading active writers 2026/01/29 05:26:25 DEBUG : fil: reading active writers 2026/01/29 05:26:25 DEBUG : >WaitForWriters: 2026/01/29 05:26:26 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:26:26 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:26 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:26:27 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:27 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:26:28 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:30 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) === NAME TestDirReadDirAll run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:26:32 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:26:34 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:34 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:26:36 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:38 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:40 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:42 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:44 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:46 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:51 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:53 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:54 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:26:56 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:26:58 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:26:58 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:27:00 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:18 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:21 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:23 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:24 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:26 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:28 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:27:30 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/subdir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/subdir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/virtualDir2: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/subdir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a/b: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a/b: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a/b: forgetting directory cache 2026/01/29 05:36:36 DEBUG : fil/a: forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : forgetting directory cache 2026/01/29 05:36:36 DEBUG : dir: forgetting directory cache 2026/01/29 05:36:36 DEBUG : pacer: low level retry 10/10 (error Delete "http://127.0.0.1:38081/remote.php/webdav/rclone-test-duxaras9dapa/fil/a/": net/http: timeout awaiting response headers) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: Delete "http://127.0.0.1:38081/remote.php/webdav/rclone-test-duxaras9dapa/fil/a/": net/http: timeout awaiting response headers run.go:133: removing dir "fil/a" failed: rmdir failed: Delete "http://127.0.0.1:38081/remote.php/webdav/rclone-test-duxaras9dapa/fil/a/": net/http: timeout awaiting response headers 2026/01/29 05:36:37 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:36:40 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:36:41 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:36:42 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:36:43 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:36:43 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:36:44 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:36:44 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/01/29 05:36:44 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:36:45 DEBUG : pacer: Reducing sleep to 112.627028ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:36:46 DEBUG : pacer: Reducing sleep to 84.470271ms 2026/01/29 05:36:46 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:36:46 DEBUG : pacer: Reducing sleep to 47.514527ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:36:48 DEBUG : pacer: Reducing sleep to 35.635895ms 2026/01/29 05:36:48 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:36:48 DEBUG : pacer: Reducing sleep to 20.04519ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirReadDirAll Messages: directories --- FAIL: TestDirReadDirAll (630.95s) --- PASS: TestDirReadDirAll/Virtual (1.38s) === RUN TestDirOpen run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:36:52 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:36:52 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:36:52 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:36:52 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:36:53 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:36:53 ERROR : dir/: Can only open directories read only 2026/01/29 05:36:53 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:36:53 DEBUG : dir: Looking for writers 2026/01/29 05:36:53 DEBUG : fil: Looking for writers 2026/01/29 05:36:53 DEBUG : Looking for writers 2026/01/29 05:36:53 DEBUG : dir: reading active writers 2026/01/29 05:36:53 DEBUG : fil: reading active writers 2026/01/29 05:36:53 DEBUG : >WaitForWriters: 2026/01/29 05:36:53 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:36:54 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:54 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:36:55 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:55 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:36:56 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:36:58 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:37:00 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:37:02 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:37:04 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:06 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:08 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:10 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:12 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:14 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:16 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:18 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:20 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:37:22 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:37:24 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:24 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:37:25 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:27 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:29 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:31 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:33 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:35 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:37 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:39 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:37:41 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:37:43 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:37:45 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:37:47 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:37:48 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:37:49 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:37:49 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:37:50 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:37:50 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:37:51 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:37:51 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/01/29 05:37:52 DEBUG : pacer: Reducing sleep to 84.470271ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:37:54 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:37:54 DEBUG : pacer: Reducing sleep to 47.514527ms 2026/01/29 05:37:54 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirOpen Messages: directories --- FAIL: TestDirOpen (65.76s) === RUN TestDirCreate run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:37:58 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:37:58 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:37:58 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:37:59 DEBUG : pacer: Reducing sleep to 20.04519ms 2026/01/29 05:37:59 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:37:59 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:37:59 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:37:59 DEBUG : dir/potato: Open: flags=O_WRONLY|O_CREATE 2026/01/29 05:37:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/29 05:37:59 DEBUG : dir/potato: >Open: fd=dir/potato (w), err= 2026/01/29 05:37:59 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/29 05:37:59 DEBUG : webdav root 'rclone-test-duxaras9dapa': File to upload is small (5 bytes), uploading instead of streaming 2026/01/29 05:37:59 DEBUG : dir/potato: Update will use the normal upload strategy (no chunks) 2026/01/29 05:38:00 DEBUG : dir/potato: size = 5 OK 2026/01/29 05:38:00 DEBUG : dir/potato: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/01/29 05:38:00 DEBUG : dir/potato: Size and md5 of src and dst objects identical 2026/01/29 05:38:00 DEBUG : dir: Added virtual directory entry vAddFile: "potato" 2026/01/29 05:38:00 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:38:00 DEBUG : fil: Looking for writers 2026/01/29 05:38:00 DEBUG : dir: Looking for writers 2026/01/29 05:38:00 DEBUG : file1: reading active writers 2026/01/29 05:38:00 DEBUG : potato: reading active writers 2026/01/29 05:38:00 DEBUG : Looking for writers 2026/01/29 05:38:00 DEBUG : dir: reading active writers 2026/01/29 05:38:00 DEBUG : fil: reading active writers 2026/01/29 05:38:00 DEBUG : >WaitForWriters: 2026/01/29 05:38:00 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:00 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:38:00 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:00 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:38:00 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:00 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:38:00 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:00 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:38:00 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:38:01 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:01 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:38:01 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:01 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:38:02 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:02 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:38:03 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:05 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:38:07 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:38:09 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:09 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:38:10 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:12 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:14 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:16 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:19 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:21 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:22 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:25 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:27 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:38:29 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:38:31 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:31 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:38:32 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:34 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:36 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:38 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:40 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:42 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:44 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:46 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:38:48 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:38:50 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:38:52 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:38:53 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:38:55 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:38:55 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:38:56 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:38:56 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:38:57 DEBUG : pacer: Reducing sleep to 200.225829ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:38:58 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:38:58 DEBUG : pacer: Reducing sleep to 112.627028ms 2026/01/29 05:38:58 DEBUG : pacer: Reducing sleep to 84.470271ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:39:00 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:39:01 DEBUG : pacer: Reducing sleep to 47.514527ms 2026/01/29 05:39:01 DEBUG : pacer: Reducing sleep to 35.635895ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirCreate Messages: directories --- FAIL: TestDirCreate (66.71s) === RUN TestDirMkdir run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:39:05 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:39:05 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:39:05 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:39:05 DEBUG : pacer: Reducing sleep to 20.04519ms 2026/01/29 05:39:05 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:39:05 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:39:05 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:39:06 DEBUG : dir: Added virtual directory entry vAddDir: "sub" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:405 Error: Not equal: expected: []string{"dir", "dir/sub"} actual : []string{"dir", "dir/sub", "fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,4 +1,6 @@ -([]string) (len=2) { +([]string) (len=4) { (string) (len=3) "dir", - (string) (len=7) "dir/sub" + (string) (len=7) "dir/sub", + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirMkdir Messages: directories 2026/01/29 05:39:14 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:39:14 DEBUG : fil: Looking for writers 2026/01/29 05:39:14 DEBUG : dir/sub: Looking for writers 2026/01/29 05:39:14 DEBUG : dir: Looking for writers 2026/01/29 05:39:14 DEBUG : file1: reading active writers 2026/01/29 05:39:14 DEBUG : sub: reading active writers 2026/01/29 05:39:14 DEBUG : Looking for writers 2026/01/29 05:39:14 DEBUG : dir: reading active writers 2026/01/29 05:39:14 DEBUG : fil: reading active writers 2026/01/29 05:39:14 DEBUG : >WaitForWriters: 2026/01/29 05:39:15 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2026/01/29 05:39:15 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:15 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2026/01/29 05:39:16 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:16 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:39:17 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:19 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:39:21 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:39:23 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:23 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:39:25 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:27 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:29 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:31 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:33 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:35 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:37 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:39 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:41 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 2/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:39:43 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 05:39:45 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:45 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 05:39:46 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:48 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:50 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:52 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:54 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:56 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:39:58 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:40:00 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 05:40:02 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 3/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked run.go:133: removing dir "fil/a" failed: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 05:40:04 DEBUG : pacer: Reducing sleep to 1.5s run.go:130: removing dir "fil" failed - try 1/3: directory not empty 2026/01/29 05:40:07 DEBUG : pacer: Reducing sleep to 1.125s run.go:130: removing dir "fil" failed - try 2/3: directory not empty 2026/01/29 05:40:08 DEBUG : pacer: Reducing sleep to 843.75ms run.go:130: removing dir "fil" failed - try 3/3: directory not empty run.go:133: removing dir "fil" failed: directory not empty 2026/01/29 05:40:09 DEBUG : pacer: Reducing sleep to 632.8125ms 2026/01/29 05:40:10 DEBUG : pacer: Reducing sleep to 474.609375ms 2026/01/29 05:40:11 DEBUG : pacer: Reducing sleep to 355.957031ms 2026/01/29 05:40:11 DEBUG : pacer: Reducing sleep to 266.967773ms 2026/01/29 05:40:11 DEBUG : pacer: Reducing sleep to 200.225829ms 2026/01/29 05:40:12 DEBUG : pacer: Reducing sleep to 150.169371ms 2026/01/29 05:40:12 DEBUG : pacer: Reducing sleep to 112.627028ms fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 2026/01/29 05:40:13 DEBUG : pacer: Reducing sleep to 84.470271ms 2026/01/29 05:40:13 DEBUG : pacer: Reducing sleep to 63.352703ms 2026/01/29 05:40:13 DEBUG : pacer: Reducing sleep to 47.514527ms fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 2026/01/29 05:40:15 DEBUG : pacer: Reducing sleep to 35.635895ms 2026/01/29 05:40:15 DEBUG : pacer: Reducing sleep to 26.726921ms 2026/01/29 05:40:15 DEBUG : pacer: Reducing sleep to 20.04519ms fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:178 /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 /usr/local/go/src/testing/testing.go:1211 /usr/local/go/src/testing/testing.go:1445 /usr/local/go/src/testing/testing.go:1786 Error: Not equal: expected: []string{} actual : []string{"fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,2 +1,4 @@ -([]string) { +([]string) (len=2) { + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirMkdir Messages: directories --- FAIL: TestDirMkdir (74.85s) === RUN TestDirMkdirSub run.go:185: Remote "webdav root 'rclone-test-duxaras9dapa'", Local "Local file system at /tmp/rclone374859474", Modify Window "1s" 2026/01/29 05:40:20 INFO : webdav root 'rclone-test-duxaras9dapa': poll-interval is not supported by this remote 2026/01/29 05:40:20 DEBUG : pacer: Reducing sleep to 15.033892ms 2026/01/29 05:40:20 DEBUG : dir/file1: Update will use the normal upload strategy (no chunks) 2026/01/29 05:40:20 DEBUG : pacer: Reducing sleep to 11.275419ms 2026/01/29 05:40:20 DEBUG : pacer: Reducing sleep to 10ms 2026/01/29 05:40:21 DEBUG : dir: Added virtual directory entry vAddDir: "sub" 2026/01/29 05:40:21 DEBUG : dir/sub: Added virtual directory entry vAddDir: "subsub" fstest.go:298: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:298: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:298: Sleeping for 4s for list eventual consistency: 3/3 2026/01/29 05:46:50 DEBUG : dir: forgetting directory cache 2026/01/29 05:46:50 DEBUG : forgetting directory cache 2026/01/29 05:46:51 DEBUG : fil: forgetting directory cache 2026/01/29 05:46:51 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 05:46:51 DEBUG : dir: forgetting directory cache 2026/01/29 05:46:51 DEBUG : dir/virtualDir: forgetting directory cache fstest.go:327: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:327 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:338 /home/rclone/go/src/github.com/rclone/rclone/vfs/dir_test.go:430 Error: Not equal: expected: []string{"dir", "dir/sub", "dir/sub/subsub"} actual : []string{"dir", "dir/sub", "dir/sub/subsub", "fil", "fil/a"} Diff: --- Expected +++ Actual @@ -1,5 +1,7 @@ -([]string) (len=3) { +([]string) (len=5) { (string) (len=3) "dir", (string) (len=7) "dir/sub", - (string) (len=14) "dir/sub/subsub" + (string) (len=14) "dir/sub/subsub", + (string) (len=3) "fil", + (string) (len=5) "fil/a" } Test: TestDirMkdirSub Messages: directories 2026/01/29 05:46:51 DEBUG : WaitForWriters: timeout=30s 2026/01/29 05:46:51 DEBUG : dir/sub/subsub: Looking for writers 2026/01/29 05:46:51 DEBUG : dir/sub: Looking for writers 2026/01/29 05:46:51 DEBUG : subsub: reading active writers 2026/01/29 05:46:51 DEBUG : dir: Looking for writers 2026/01/29 05:46:51 DEBUG : file1: reading active writers 2026/01/29 05:46:51 DEBUG : sub: reading active writers 2026/01/29 05:46:51 DEBUG : fil: Looking for writers 2026/01/29 05:46:51 DEBUG : Looking for writers 2026/01/29 05:46:51 DEBUG : fil: reading active writers 2026/01/29 05:46:51 DEBUG : dir: reading active writers 2026/01/29 05:46:51 DEBUG : >WaitForWriters: 2026/01/29 05:46:54 DEBUG : fil: forgetting directory cache 2026/01/29 05:46:54 DEBUG : dir: forgetting directory cache 2026/01/29 05:46:54 DEBUG : forgetting directory cache 2026/01/29 05:46:55 DEBUG : dir: forgetting directory cache 2026/01/29 05:46:56 DEBUG : fil: forgetting directory cache 2026/01/29 05:48:10 DEBUG : dir: forgetting directory cache 2026/01/29 05:48:10 DEBUG : fil: forgetting directory cache 2026/01/29 05:48:10 DEBUG : forgetting directory cache 2026/01/29 05:48:25 DEBUG : dir: Removed virtual directory entry vAddFile: "potato" 2026/01/29 05:48:27 DEBUG : dir: forgetting directory cache 2026/01/29 05:48:28 DEBUG : fil: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 06:14:08 DEBUG : fil: forgetting directory cache 2026/01/29 06:14:08 DEBUG : forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir/sub/subsub: forgetting directory cache 2026/01/29 06:14:08 DEBUG : fil: forgetting directory cache 2026/01/29 06:13:00 DEBUG : forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir/sub/subsub: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir: forgetting directory cache 2026/01/29 06:14:08 DEBUG : dir: forgetting directory cache 2026/01/29 06:09:56 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/01/29 06:14:09 DEBUG : fil: forgetting directory cache 2026/01/29 06:14:09 DEBUG : forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir/virtualDir: forgetting directory cache 2026/01/29 06:14:09 DEBUG : fil: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir/sub: Removed virtual directory entry vAddDir: "subsub" 2026/01/29 06:14:09 DEBUG : dir/sub: forgetting directory cache 2026/01/29 06:14:09 DEBUG : dir: Removed virtual directory entry vAddDir: "sub" 2026/01/29 06:14:09 DEBUG : fil: forgetting directory cache 2026/01/29 06:22:27 DEBUG : pacer: low level retry 1/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-duxaras9dapa/fil/a/": net/http: timeout awaiting response headers) 2026/01/29 06:22:27 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2026/01/29 06:22:30 DEBUG : pacer: Reducing sleep to 15ms 2026/01/29 06:22:31 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:31 DEBUG : pacer: Rate limited, increasing sleep to 30ms 2026/01/29 06:22:32 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:32 DEBUG : pacer: Rate limited, increasing sleep to 60ms 2026/01/29 06:22:32 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:32 DEBUG : pacer: Rate limited, increasing sleep to 120ms 2026/01/29 06:22:33 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:33 DEBUG : pacer: Rate limited, increasing sleep to 240ms 2026/01/29 06:22:33 DEBUG : pacer: low level retry 5/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:33 DEBUG : pacer: Rate limited, increasing sleep to 480ms 2026/01/29 06:22:33 DEBUG : pacer: low level retry 6/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:33 DEBUG : pacer: Rate limited, increasing sleep to 960ms 2026/01/29 06:22:34 DEBUG : pacer: low level retry 7/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:34 DEBUG : pacer: Rate limited, increasing sleep to 1.92s 2026/01/29 06:22:35 DEBUG : pacer: low level retry 8/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:35 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 06:22:36 DEBUG : pacer: low level retry 9/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:38 DEBUG : pacer: low level retry 10/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) run.go:130: removing dir "fil/a" failed - try 1/3: rmdir failed: "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked 2026/01/29 06:22:40 DEBUG : pacer: Reducing sleep to 1.5s 2026/01/29 06:22:42 DEBUG : pacer: low level retry 1/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:42 DEBUG : pacer: Rate limited, increasing sleep to 2s 2026/01/29 06:22:44 DEBUG : pacer: low level retry 2/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:46 DEBUG : pacer: low level retry 3/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2026/01/29 06:22:48 DEBUG : pacer: low level retry 4/10 (error "rclone-test-duxaras9dapa/fil/a" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) panic: test timed out after 1h0m0s running tests: TestDirMkdirSub (42m30s) goroutine 1427 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2484 +0x394 created by time.goFunc /usr/local/go/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 42 minutes]: testing.(*T).Run(0xc000244e00, {0x2925b11?, 0xc000029ad8?}, 0x2a74720) /usr/local/go/src/testing/testing.go:1859 +0x431 testing.runTests.func1(0xc000244e00) /usr/local/go/src/testing/testing.go:2279 +0x37 testing.tRunner(0xc000244e00, 0xc000029c18) /usr/local/go/src/testing/testing.go:1792 +0xf4 testing.runTests(0xc000581800, {0x4446940, 0x59, 0x59}, {0xee10ce5aa?, 0x4463880?, 0x4463880?}) /usr/local/go/src/testing/testing.go:2277 +0x4b4 testing.(*M).Run(0xc000606d20) /usr/local/go/src/testing/testing.go:2142 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc000606d20) /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 +0xa9 goroutine 40 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:152 +0x1f goroutine 41 [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 1357 [select]: net/http.(*persistConn).writeLoop(0xc0008ee000) /usr/local/go/src/net/http/transport.go:2590 +0xe7 created by net/http.(*Transport).dialConn in goroutine 1416 /usr/local/go/src/net/http/transport.go:1945 +0x17a5 goroutine 1267 [chan receive]: github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc00021d140, 0x0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:162 +0x26 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc00021d140, 0xc00002b7c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:215 +0xb2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc00021d140, 0xc00002b7c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:235 +0x93 github.com/rclone/rclone/backend/webdav.(*Fs).purgeCheck(0xc000202580, {0x2e11398, 0x4487140}, {0xc00080c610, 0x5}, 0xa0?) /home/rclone/go/src/github.com/rclone/rclone/backend/webdav/webdav.go:1110 +0x2b9 github.com/rclone/rclone/backend/webdav.(*Fs).Rmdir(0x4?, {0x2e11398?, 0x4487140?}, {0xc00080c610?, 0xc0008316c0?}) /home/rclone/go/src/github.com/rclone/rclone/backend/webdav/webdav.go:1125 +0x28 github.com/rclone/rclone/fstest.newRunIndividual.func1.2() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:174 +0x30 github.com/rclone/rclone/fstest.retry(0xc00075ae00, {0xc000740120, 0x14}, 0xc00006bdd8) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:126 +0xde github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 +0x271 github.com/rclone/rclone/fstest.(*Run).Finalise(0xc00043d730) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:393 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1211 +0x106 testing.(*common).runCleanup(0xc00075ae00, 0xe?) /usr/local/go/src/testing/testing.go:1445 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:1786 +0x25 testing.tRunner(0xc00075ae00, 0x2a74720) /usr/local/go/src/testing/testing.go:1798 +0x11e created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1851 +0x413 goroutine 1356 [IO wait]: internal/poll.runtime_pollWait(0x75711531fdd8, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000396280?, 0xc000021000?, 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(0xc000396280, {0xc000021000, 0x1000, 0x1000}) /usr/local/go/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000396280, {0xc000021000?, 0x2dfb618?, 0xc0008a4b90?}) /usr/local/go/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00008a350, {0xc000021000?, 0x4091b4?, 0x0?}) /usr/local/go/src/net/net.go:194 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0007d2018, {0xc000021000?, 0x408faf?, 0xc0003c7230?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 net/http.(*persistConn).Read(0xc0008ee000, {0xc000021000?, 0x810c05?, 0x2de7120?}) /usr/local/go/src/net/http/transport.go:2122 +0x47 bufio.(*Reader).fill(0xc0007483c0) /usr/local/go/src/bufio/bufio.go:113 +0x103 bufio.(*Reader).Peek(0xc0007483c0, 0x1) /usr/local/go/src/bufio/bufio.go:152 +0x53 net/http.(*persistConn).readLoop(0xc0008ee000) /usr/local/go/src/net/http/transport.go:2275 +0x172 created by net/http.(*Transport).dialConn in goroutine 1416 /usr/local/go/src/net/http/transport.go:1944 +0x174f goroutine 1442 [sleep]: time.Sleep(0x77359400) /usr/local/go/src/runtime/time.go:338 +0x165 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc0008ad760?) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:170 +0x1c created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 1267 /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:169 +0xf6 "./vfs.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose" - Finished ERROR in 1h0m1.682010646s (try 1/5): exit status 2: Failed [TestDirWalk TestDirSetModTime TestDirStat TestDirReadDirAll TestDirOpen TestDirCreate TestDirMkdir]