"./operations.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestCaseInsensitiveMoveFile|TestCaseInsensitiveMoveFileDryRun|TestCopyDirMetadata|TestCopyURL|TestDirMove|TestDirsEqual|TestGetFsInfo|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Starting (try 2/5)
2026/01/26 02:43:44 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-luyuwit5sala"
2026/01/26 02:43:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/01/26 02:43:44 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE
2026/01/26 02:43:44 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2026/01/26 02:43:44 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2026/01/26 02:43:44 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2026/01/26 02:43:44 DEBUG : Setting pass="FjJ8OBCA8a9oWNgQLRHuCMYN10ngEkOeXDng-0_IItsU4GI" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2026/01/26 02:43:44 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{qHdw4}" suffix to name
2026/01/26 02:43:44 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2026/01/26 02:43:44 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2026/01/26 02:43:44 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2026/01/26 02:43:44 DEBUG : Setting pass="FjJ8OBCA8a9oWNgQLRHuCMYN10ngEkOeXDng-0_IItsU4GI" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2026/01/26 02:43:44 DEBUG : found headers: 
2026/01/26 02:43:45 DEBUG : Creating backend with remote "/tmp/rclone427094353"
=== RUN   TestRmdirsNoLeaveRoot
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:46 DEBUG : A1/B1/C1/one: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:47 INFO  : A2: Making directory
2026/01/26 02:43:47 INFO  : A1/B2: Making directory
2026/01/26 02:43:47 INFO  : A1/B2/C2: Making directory
2026/01/26 02:43:47 INFO  : A1/B1/C3: Making directory
2026/01/26 02:43:47 INFO  : A3: Making directory
2026/01/26 02:43:47 INFO  : A3/B3: Making directory
2026/01/26 02:43:48 INFO  : A3/B3/C4: Making directory
2026/01/26 02:43:48 DEBUG : A1/two: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:48 DEBUG : removing 1 level 3 directories
2026/01/26 02:43:48 INFO  : A3/B3/C4: Removing directory
2026/01/26 02:43:49 DEBUG : removing 2 level 3 directories
2026/01/26 02:43:49 INFO  : A1/B2/C2: Removing directory
2026/01/26 02:43:49 INFO  : A1/B1/C3: Removing directory
2026/01/26 02:43:50 DEBUG : removing 2 level 2 directories
2026/01/26 02:43:50 INFO  : A3/B3: Removing directory
2026/01/26 02:43:50 INFO  : A1/B2: Removing directory
2026/01/26 02:43:50 DEBUG : removing 2 level 1 directories
2026/01/26 02:43:50 INFO  : A3: Removing directory
2026/01/26 02:43:50 INFO  : A2: Removing directory
2026/01/26 02:43:50 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\/8d613d2ff409cfcb0251120c454dbbae"]:

SQLSTATE[HY000]: General error: 5 database is locked: Doctrine\DBAL\Exception\LockWaitTimeoutException: 500 Internal Server Error)
2026/01/26 02:43:50 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/26 02:43:50 DEBUG : pacer: Reducing sleep to 15ms
2026/01/26 02:43:50 DEBUG : pacer: Reducing sleep to 11.25ms
2026/01/26 02:43:50 ERROR : A3: Failed to rmdir: rmdir failed: Sabre\DAV\Exception\Forbidden: 403 Forbidden
    operations_test.go:705: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:705
        	Error:      	Received unexpected error:
        	            	failed to remove directories: rmdir failed: Sabre\DAV\Exception\Forbidden: 403 Forbidden
        	Test:       	TestRmdirsNoLeaveRoot
2026/01/26 02:43:50 DEBUG : pacer: Reducing sleep to 10ms
--- FAIL: TestRmdirsNoLeaveRoot (5.96s)
=== RUN   TestRmdirsLeaveRoot
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:51 INFO  : A1: Making directory
2026/01/26 02:43:52 INFO  : A1/B1: Making directory
2026/01/26 02:43:52 INFO  : A1/B1/C1: Making directory
2026/01/26 02:43:52 DEBUG : removing 1 level 3 directories
2026/01/26 02:43:52 INFO  : A1/B1/C1: Removing directory
2026/01/26 02:43:53 DEBUG : removing 1 level 2 directories
2026/01/26 02:43:53 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsLeaveRoot (1.98s)
=== RUN   TestRmdirsWithFilter
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:53 INFO  : A1: Making directory
2026/01/26 02:43:54 INFO  : A1/B1: Making directory
2026/01/26 02:43:54 INFO  : A1/B1/C1: Making directory
2026/01/26 02:43:54 DEBUG : removing 1 level 3 directories
2026/01/26 02:43:54 INFO  : A1/B1/C1: Removing directory
2026/01/26 02:43:55 DEBUG : removing 1 level 2 directories
2026/01/26 02:43:55 INFO  : A1/B1: Removing directory
--- PASS: TestRmdirsWithFilter (2.15s)
=== RUN   TestCopyURL
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:56 DEBUG : file1: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:56 DEBUG : filename.txt: File name found in url
2026/01/26 02:43:56 DEBUG : filename.txt: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:56 DEBUG : headerfilename.txt: filename found in Content-Disposition header.
2026/01/26 02:43:56 DEBUG : headerfilename.txt: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:57 DEBUG : file2: Update will use the normal upload strategy (no chunks)
--- PASS: TestCopyURL (2.05s)
=== RUN   TestMoveFile
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:58 DEBUG : file1: Need to transfer - File not found at Destination
2026/01/26 02:43:58 DEBUG : sub/file2: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:58 DEBUG : sub/file2: size = 14 OK
2026/01/26 02:43:58 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/01/26 02:43:58 INFO  : file1: Copied (new) to: sub/file2
2026/01/26 02:43:58 INFO  : file1: Deleted
2026/01/26 02:43:58 DEBUG : sub/file2: size = 14 OK
2026/01/26 02:43:58 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/01/26 02:43:58 DEBUG : file1: Unchanged skipping
2026/01/26 02:43:58 INFO  : file1: Deleted
2026/01/26 02:43:58 DEBUG : webdav root 'rclone-test-luyuwit5sala': don't need to copy/move sub/file2, it is already at target location
--- PASS: TestMoveFile (1.44s)
=== RUN   TestMoveFileWithIgnoreExisting
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:43:59 DEBUG : file1: Need to transfer - File not found at Destination
2026/01/26 02:43:59 DEBUG : file1: Update will use the normal upload strategy (no chunks)
2026/01/26 02:43:59 DEBUG : file1: size = 14 OK
2026/01/26 02:43:59 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/01/26 02:43:59 INFO  : file1: Copied (new)
2026/01/26 02:43:59 INFO  : file1: Deleted
2026/01/26 02:44:00 DEBUG : file1: Destination exists, skipping
2026/01/26 02:44:00 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set
--- PASS: TestMoveFileWithIgnoreExisting (0.92s)
=== RUN   TestCaseInsensitiveMoveFile
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
--- PASS: TestCaseInsensitiveMoveFile (0.16s)
=== RUN   TestCaseInsensitiveMoveFileDryRun
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
--- PASS: TestCaseInsensitiveMoveFileDryRun (0.18s)
=== RUN   TestMoveFileBackupDir
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:00 DEBUG : dst/file1: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:01 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-luyuwit5sala/backup"
2026/01/26 02:44:01 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE
2026/01/26 02:44:01 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2026/01/26 02:44:01 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2026/01/26 02:44:01 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2026/01/26 02:44:01 DEBUG : Setting pass="FjJ8OBCA8a9oWNgQLRHuCMYN10ngEkOeXDng-0_IItsU4GI" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2026/01/26 02:44:01 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{qHdw4}" suffix to name
2026/01/26 02:44:01 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL
2026/01/26 02:44:01 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR
2026/01/26 02:44:01 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER
2026/01/26 02:44:01 DEBUG : Setting pass="FjJ8OBCA8a9oWNgQLRHuCMYN10ngEkOeXDng-0_IItsU4GI" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS
2026/01/26 02:44:01 DEBUG : found headers: 
2026/01/26 02:44:01 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-luyuwit5sala/backup" to be canonical "TestWebdavOwncloud{qHdw4}:rclone-test-luyuwit5sala/backup"
2026/01/26 02:44:01 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone427094353)
2026/01/26 02:44:01 DEBUG : dst/file1: size = 18 (webdav root 'rclone-test-luyuwit5sala')
2026/01/26 02:44:01 DEBUG : dst/file1: Sizes differ
2026/01/26 02:44:02 INFO  : dst/file1: Moved (server-side)
2026/01/26 02:44:02 DEBUG : dst/file1: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:02 DEBUG : dst/file1: size = 14 OK
2026/01/26 02:44:02 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/01/26 02:44:02 INFO  : dst/file1: Copied (new)
2026/01/26 02:44:02 INFO  : dst/file1: Deleted
--- PASS: TestMoveFileBackupDir (3.49s)
=== RUN   TestDirMove
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:04 DEBUG : A1/one: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:04 DEBUG : A1/two: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:05 DEBUG : A1/B1/three: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:05 DEBUG : A1/B1/C1/four: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:05 DEBUG : A1/B1/C2/five: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:05 INFO  : A1/B2: Making directory
2026/01/26 02:44:06 INFO  : A1/B1/C3: Making directory
2026/01/26 02:44:07 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/26 02:44:07 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/01/26 02:44:07 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/01/26 02:44:07 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/01/26 02:44:07 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/01/26 02:44:08 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:08 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/01/26 02:44:08 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/01/26 02:44:08 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:08 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/01/26 02:44:10 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:12 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    operations_test.go:1413: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:1413
        	Error:      	Received unexpected error:
        	            	RenameDir mkdir: "rclone-test-luyuwit5sala/A3" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked
        	Test:       	TestDirMove
2026/01/26 02:44:14 DEBUG : pacer: Reducing sleep to 1.5s
2026/01/26 02:44:16 DEBUG : pacer: Reducing sleep to 1.125s
2026/01/26 02:44:17 DEBUG : pacer: Reducing sleep to 843.75ms
2026/01/26 02:44:18 DEBUG : pacer: Reducing sleep to 632.8125ms
2026/01/26 02:44:19 DEBUG : pacer: Reducing sleep to 474.609375ms
2026/01/26 02:44:20 DEBUG : pacer: Reducing sleep to 355.957031ms
2026/01/26 02:44:20 DEBUG : pacer: Reducing sleep to 266.967773ms
2026/01/26 02:44:21 DEBUG : pacer: Reducing sleep to 200.225829ms
2026/01/26 02:44:21 DEBUG : pacer: Reducing sleep to 150.169371ms
2026/01/26 02:44:21 DEBUG : pacer: Reducing sleep to 112.627028ms
2026/01/26 02:44:21 DEBUG : pacer: Reducing sleep to 84.470271ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 63.352703ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 47.514527ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 35.635895ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 26.726921ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 20.04519ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 15.033892ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 11.275419ms
2026/01/26 02:44:22 DEBUG : pacer: Reducing sleep to 10ms
--- FAIL: TestDirMove (19.05s)
=== RUN   TestGetFsInfo
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
--- PASS: TestGetFsInfo (0.15s)
=== RUN   TestRcat
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=false
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:23 DEBUG : webdav root 'rclone-test-luyuwit5sala': File to upload is small (34 bytes), uploading instead of streaming
2026/01/26 02:44:23 DEBUG : no_checksum_small_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:23 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK
2026/01/26 02:44:23 DEBUG : no_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK
2026/01/26 02:44:23 DEBUG : no_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical
2026/01/26 02:44:23 DEBUG : no_checksum_big_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:24 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK
2026/01/26 02:44:24 DEBUG : no_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK
2026/01/26 02:44:24 DEBUG : no_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=false
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:24 DEBUG : webdav root 'rclone-test-luyuwit5sala': File to upload is small (34 bytes), uploading instead of streaming
2026/01/26 02:44:24 DEBUG : with_checksum_small_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:24 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK
2026/01/26 02:44:24 DEBUG : with_checksum_small_file_from_pipe: md5 = 4c762bc6ec18f21b23fcae426c7863b5 OK
2026/01/26 02:44:24 DEBUG : with_checksum_small_file_from_pipe: Size and md5 of src and dst objects identical
2026/01/26 02:44:24 DEBUG : with_checksum_big_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:25 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK
2026/01/26 02:44:25 DEBUG : with_checksum_big_file_from_pipe: md5 = fffc7956ba9a7b58a63c01b6ce1ddc45 OK
2026/01/26 02:44:25 DEBUG : with_checksum_big_file_from_pipe: Size and md5 of src and dst objects identical
=== RUN   TestRcat/withChecksum=false,ignoreChecksum=true
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:25 DEBUG : webdav root 'rclone-test-luyuwit5sala': File to upload is small (34 bytes), uploading instead of streaming
2026/01/26 02:44:25 DEBUG : ignore_checksum_small_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:26 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/01/26 02:44:26 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/01/26 02:44:26 DEBUG : ignore_checksum_big_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:26 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/01/26 02:44:26 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
=== RUN   TestRcat/withChecksum=true,ignoreChecksum=true
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:26 DEBUG : webdav root 'rclone-test-luyuwit5sala': File to upload is small (34 bytes), uploading instead of streaming
2026/01/26 02:44:26 DEBUG : ignore_checksum_small_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:27 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK
2026/01/26 02:44:27 DEBUG : ignore_checksum_small_file_from_pipe: Src hash empty - aborting Dst hash check
2026/01/26 02:44:27 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical
2026/01/26 02:44:27 DEBUG : ignore_checksum_big_file_from_pipe: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:27 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK
2026/01/26 02:44:27 DEBUG : ignore_checksum_big_file_from_pipe: Src hash empty - aborting Dst hash check
2026/01/26 02:44:27 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical
--- PASS: TestRcat (4.43s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (1.09s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (1.12s)
    --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (1.11s)
    --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (1.10s)
=== RUN   TestRcatMetadata
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1540: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatMetadata (0.16s)
=== RUN   TestRcatSize
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:28 DEBUG : potato1: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:28 DEBUG : webdav root 'rclone-test-luyuwit5sala': File to upload is small (60 bytes), uploading instead of streaming
2026/01/26 02:44:28 DEBUG : potato2: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:28 DEBUG : potato2: size = 60 OK
2026/01/26 02:44:28 DEBUG : potato2: md5 = d6548b156ea68a4e003e786df99eee76 OK
2026/01/26 02:44:28 DEBUG : potato2: Size and md5 of src and dst objects identical
--- PASS: TestRcatSize (0.97s)
=== RUN   TestRcatSizeMetadata
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1621: Skipping as destination doesn't support user metadata
--- SKIP: TestRcatSizeMetadata (0.16s)
=== RUN   TestTouchDir
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:29 DEBUG : potato2: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:29 DEBUG : empty space: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:29 DEBUG : sub dir/potato3: Update will use the normal upload strategy (no chunks)
2026/01/26 02:44:30 DEBUG : webdav root 'rclone-test-luyuwit5sala': Touching "empty space"
2026/01/26 02:44:30 DEBUG : webdav root 'rclone-test-luyuwit5sala': Touching "potato2"
2026/01/26 02:44:30 DEBUG : webdav root 'rclone-test-luyuwit5sala': Touching "sub dir/potato3"
2026/01/26 02:44:30 ERROR : empty space: failed to touch: can't set modified time
2026/01/26 02:44:30 ERROR : potato2: failed to touch: can't set modified time
2026/01/26 02:44:30 ERROR : sub dir/potato3: failed to touch: can't set modified time
2026/01/26 02:44:31 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\/d4c8390fc30bfd435af4fc51d4c8c868"]:

SQLSTATE[HY000]: General error: 5 database is locked: Doctrine\DBAL\Exception\LockWaitTimeoutException: 500 Internal Server Error)
2026/01/26 02:44:31 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/26 02:44:31 DEBUG : pacer: Reducing sleep to 15ms
    run.go:130: removing dir "sub dir" failed - try 1/3: rmdir failed: Sabre\DAV\Exception\Forbidden: 403 Forbidden
2026/01/26 02:44:32 DEBUG : pacer: Reducing sleep to 11.25ms
    run.go:130: removing dir "sub dir" failed - try 2/3: directory not found
2026/01/26 02:44:33 DEBUG : pacer: Reducing sleep to 10ms
    run.go:130: removing dir "sub dir" failed - try 3/3: directory not found
    run.go:133: removing dir "sub dir" failed: directory not found
--- PASS: TestTouchDir (5.54s)
=== RUN   TestMkdirMetadata
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1711: Skipping test as remote does not support MkdirMetadata
--- SKIP: TestMkdirMetadata (0.15s)
=== RUN   TestMkdirModTime
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1730: Skipping test as remote does not support DirSetModTime or MkdirMetadata
--- SKIP: TestMkdirModTime (0.17s)
=== RUN   TestCopyDirMetadata
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1751: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestCopyDirMetadata (0.15s)
=== RUN   TestSetDirModTime
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1787: Skipping test as remote does not support DirSetModTime or WriteDirSetModTime
--- SKIP: TestSetDirModTime (0.16s)
=== RUN   TestDirsEqual
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
    operations_test.go:1831: Skipping test as remote does not support WriteDirMetadata or MkdirMetadata
--- SKIP: TestDirsEqual (0.16s)
=== RUN   TestRemoveExisting
    run.go:185: Remote "webdav root 'rclone-test-luyuwit5sala'", Local "Local file system at /tmp/rclone427094353", Modify Window "1s"
2026/01/26 02:44:35 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:35 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/01/26 02:44:35 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:35 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/01/26 02:44:35 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:35 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/01/26 02:44:35 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:35 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/01/26 02:44:35 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:35 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/01/26 02:44:36 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:36 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/01/26 02:44:36 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:36 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/01/26 02:44:37 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:37 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/01/26 02:44:38 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:40 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 1/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:42 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:44 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:46 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:48 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:50 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:52 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:54 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:56 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:44:58 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:00 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 2/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:02 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:04 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:06 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:08 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:10 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:12 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:14 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:16 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:18 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:20 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 3/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:22 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:24 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:26 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:28 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:30 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:32 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:34 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:36 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:38 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:40 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 4/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:42 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:44 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:46 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:48 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:50 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:52 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:54 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:56 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:45:58 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:00 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 5/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:02 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:04 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:06 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:08 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:10 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:12 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:14 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:16 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:18 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:20 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 6/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:22 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:24 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:26 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:28 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:30 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:32 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:34 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:36 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:38 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:40 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 7/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:42 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:45 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:46 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:48 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:51 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:52 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:55 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:57 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:46:59 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:00 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 8/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:03 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:05 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:07 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:09 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:11 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:13 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:15 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:17 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:19 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:21 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:286: Retry Put of "sub dir/test remove existing" to webdav root 'rclone-test-luyuwit5sala': 9/10 (Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:23 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:25 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:27 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:29 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:31 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:33 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:35 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:37 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:39 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:41 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
    run.go:290: Failed to put "sub dir/test remove existing" to "webdav root 'rclone-test-luyuwit5sala'": Update mkParentDir failed: "rclone-test-luyuwit5sala/sub dir" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked
2026/01/26 02:47:43 DEBUG : pacer: Reducing sleep to 1.5s
2026/01/26 02:47:45 DEBUG : pacer: Reducing sleep to 1.125s
--- FAIL: TestRemoveExisting (189.71s)
FAIL
2026/01/26 02:47:45 DEBUG : webdav root 'rclone-test-luyuwit5sala': Purge remote
2026/01/26 02:47:46 DEBUG : pacer: low level retry 1/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:46 DEBUG : pacer: Rate limited, increasing sleep to 2s
2026/01/26 02:47:47 DEBUG : pacer: low level retry 2/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:49 DEBUG : pacer: low level retry 3/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:51 DEBUG : pacer: low level retry 4/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:53 DEBUG : pacer: low level retry 5/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:55 DEBUG : pacer: low level retry 6/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:57 DEBUG : pacer: low level retry 7/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:47:59 DEBUG : pacer: low level retry 8/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:48:01 DEBUG : pacer: low level retry 9/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:48:03 DEBUG : pacer: low level retry 10/10 (error "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked)
2026/01/26 02:48:03 NOTICE: purge failed: rmdir failed: "rclone-test-luyuwit5sala" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked
"./operations.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestCaseInsensitiveMoveFile|TestCaseInsensitiveMoveFileDryRun|TestCopyDirMetadata|TestCopyURL|TestDirMove|TestDirsEqual|TestGetFsInfo|TestMkdirMetadata|TestMkdirModTime|TestMoveFile|TestMoveFileBackupDir|TestMoveFileWithIgnoreExisting|TestRcatMetadata|TestRcatSize|TestRcatSizeMetadata|TestRemoveExisting|TestRmdirsLeaveRoot|TestRmdirsNoLeaveRoot|TestRmdirsWithFilter|TestSetDirModTime|TestTouchDir)$|^TestRcat$/^(withChecksum=false,ignoreChecksum=false|withChecksum=false,ignoreChecksum=true|withChecksum=true,ignoreChecksum=false|withChecksum=true,ignoreChecksum=true)$'" - Finished ERROR in 4m19.063355976s (try 2/5): exit status 1: Failed [TestRmdirsNoLeaveRoot TestDirMove TestRemoveExisting]