"./sync.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Starting (try 2/5) 2025/12/29 03:43:59 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-qepifob0rala" 2025/12/29 03:43:59 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/12/29 03:43:59 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2025/12/29 03:43:59 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:43:59 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:43:59 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:43:59 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:43:59 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{7hx6Q}" suffix to name 2025/12/29 03:43:59 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:43:59 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:43:59 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:43:59 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:43:59 DEBUG : found headers: 2025/12/29 03:44:00 DEBUG : Creating backend with remote "/tmp/rclone3416339459" === RUN TestCopyNoTraverse run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:00 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:00 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:00 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:01 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:01 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:01 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:01 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.48s) === RUN TestCopyCheckFirst run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:02 INFO : webdav root 'rclone-test-qepifob0rala': Running all checks before starting transfers 2025/12/29 03:44:02 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:02 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:02 INFO : webdav root 'rclone-test-qepifob0rala': Checks finished, now starting transfers 2025/12/29 03:44:02 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:02 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:02 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:02 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:02 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.19s) === RUN TestSyncNoTraverse run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:03 ERROR : Ignoring --no-traverse with sync 2025/12/29 03:44:03 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:03 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:03 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:03 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:03 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:03 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:03 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.10s) === RUN TestCopyEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:04 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/12/29 03:44:04 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/12/29 03:44:04 INFO : sub dir2: Set directory modification time (using DirSetModTime) 2025/12/29 03:44:04 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/12/29 03:44:04 INFO : sub dir2: Making directory 2025/12/29 03:44:04 INFO : sub dir: Making directory 2025/12/29 03:44:04 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:04 INFO : sub dir2/sub sub dir2: Making directory 2025/12/29 03:44:05 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:05 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:05 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:05 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:05 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:05 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:05 DEBUG : webdav root 'rclone-test-qepifob0rala': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.88s) === RUN TestCopyNoEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:06 INFO : sub dir2: Making directory 2025/12/29 03:44:06 DEBUG : sub dir2/sub sub dir2: Making directory with metadata 2025/12/29 03:44:06 INFO : sub dir2/sub sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/12/29 03:44:06 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:06 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:06 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:06 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:06 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:06 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:06 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoEmptyDirectories (1.11s) === RUN TestMoveEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:07 DEBUG : sub dir2: Making directory with metadata 2025/12/29 03:44:07 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/12/29 03:44:07 INFO : sub dir2: Making directory 2025/12/29 03:44:07 INFO : sub dir: Making directory 2025/12/29 03:44:07 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:07 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:07 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:07 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:08 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:08 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:08 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:08 INFO : sub dir/hello world: Deleted 2025/12/29 03:44:08 DEBUG : webdav root 'rclone-test-qepifob0rala': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2025/12/29 03:44:08 DEBUG : webdav root 'rclone-test-qepifob0rala': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.60s) === RUN TestMoveNoEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:09 INFO : sub dir2: Making directory 2025/12/29 03:44:09 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:09 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:09 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:09 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:09 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:09 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:09 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:09 INFO : sub dir/hello world: Deleted --- PASS: TestMoveNoEmptyDirectories (1.11s) === RUN TestSyncEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:10 DEBUG : sub dir2: Making directory with metadata 2025/12/29 03:44:10 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2025/12/29 03:44:10 INFO : sub dir: Set directory modification time (using DirSetModTime) 2025/12/29 03:44:10 INFO : sub dir2: Making directory 2025/12/29 03:44:10 INFO : sub dir: Making directory 2025/12/29 03:44:10 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:10 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:10 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:10 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:10 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:10 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:10 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:10 DEBUG : Waiting for deletions to finish 2025/12/29 03:44:11 DEBUG : webdav root 'rclone-test-qepifob0rala': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.50s) === RUN TestSyncNoEmptyDirectories run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:11 INFO : sub dir2: Making directory 2025/12/29 03:44:11 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:11 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:11 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:11 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:12 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:12 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:12 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoEmptyDirectories (1.10s) === RUN TestServerSideCopy run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:12 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:13 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-luwixen6hiqa" 2025/12/29 03:44:13 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2025/12/29 03:44:13 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:13 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:13 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:13 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:13 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{7hx6Q}" suffix to name 2025/12/29 03:44:13 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:13 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:13 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:13 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:13 DEBUG : found headers: sync_test.go:620: Server side copy (if possible) webdav root 'rclone-test-qepifob0rala' -> webdav root 'rclone-test-luwixen6hiqa' 2025/12/29 03:44:13 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:13 DEBUG : webdav root 'rclone-test-luwixen6hiqa': Waiting for checks to finish 2025/12/29 03:44:13 DEBUG : webdav root 'rclone-test-luwixen6hiqa': Waiting for transfers to finish 2025/12/29 03:44:14 DEBUG : sub dir/hello world: Setting modtime after copy to 2001-02-03 04:05:06 +0000 GMT 2025/12/29 03:44:14 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:14 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/29 03:44:14 INFO : sub dir/hello world: Copied (server-side copy) 2025/12/29 03:44:14 DEBUG : webdav root 'rclone-test-luwixen6hiqa': Purge remote --- PASS: TestServerSideCopy (2.40s) === RUN TestCopyOverSelf run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:15 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:15 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:15 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3416339459) 2025/12/29 03:44:15 DEBUG : sub dir/hello world: size = 11 (webdav root 'rclone-test-qepifob0rala') 2025/12/29 03:44:15 DEBUG : sub dir/hello world: Sizes differ 2025/12/29 03:44:15 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:15 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:16 DEBUG : sub dir/hello world: size = 17 OK 2025/12/29 03:44:16 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/12/29 03:44:16 INFO : sub dir/hello world: Copied (replaced existing) --- PASS: TestCopyOverSelf (1.71s) === RUN TestServerSideCopyOverSelf run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:17 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:17 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yiriwup1vora" 2025/12/29 03:44:17 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2025/12/29 03:44:17 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:17 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:17 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:17 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:17 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{7hx6Q}" suffix to name 2025/12/29 03:44:17 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:17 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:17 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:17 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:17 DEBUG : found headers: sync_test.go:656: Server side copy (if possible) webdav root 'rclone-test-qepifob0rala' -> webdav root 'rclone-test-yiriwup1vora' 2025/12/29 03:44:17 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:17 DEBUG : webdav root 'rclone-test-yiriwup1vora': Waiting for checks to finish 2025/12/29 03:44:17 DEBUG : webdav root 'rclone-test-yiriwup1vora': Waiting for transfers to finish 2025/12/29 03:44:18 DEBUG : sub dir/hello world: Setting modtime after copy to 2001-02-03 04:05:06 +0000 GMT 2025/12/29 03:44:18 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:18 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/29 03:44:18 INFO : sub dir/hello world: Copied (server-side copy) Diff of "logger" and "lsf" 2c2 < 2001-02-03 04:05:06;5eb63bbbe01eeed093cb22bb8f5acdc3;11;sub dir/hello world --- > 2001-02-03 04:05:06;;11;sub dir/hello world sync_test.go:3105: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:3105 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:661 Error: Received unexpected error: logger does not match lsf! logger: 2001-02-03 04:05:06;5eb63bbbe01eeed093cb22bb8f5acdc3;11;sub dir/hello world lsf: 2001-02-03 04:05:06;;11;sub dir/hello world Test: TestServerSideCopyOverSelf 2025/12/29 03:44:18 DEBUG : webdav root 'rclone-test-yiriwup1vora': Purge remote --- FAIL: TestServerSideCopyOverSelf (2.40s) === RUN TestMoveOverSelf run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:19 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:20 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:20 DEBUG : sub dir/hello world: size = 17 (Local file system at /tmp/rclone3416339459) 2025/12/29 03:44:20 DEBUG : sub dir/hello world: size = 11 (webdav root 'rclone-test-qepifob0rala') 2025/12/29 03:44:20 DEBUG : sub dir/hello world: Sizes differ 2025/12/29 03:44:20 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:20 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:20 DEBUG : sub dir/hello world: size = 17 OK 2025/12/29 03:44:20 DEBUG : sub dir/hello world: md5 = 00321a507bdb420dbbd4f53c733b1505 OK 2025/12/29 03:44:20 INFO : sub dir/hello world: Copied (replaced existing) 2025/12/29 03:44:20 INFO : sub dir/hello world: Deleted --- PASS: TestMoveOverSelf (1.79s) === RUN TestServerSideMoveOverSelf run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:21 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:21 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-mosaxum6cexa" 2025/12/29 03:44:21 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2025/12/29 03:44:21 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:21 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:21 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:21 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:21 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{7hx6Q}" suffix to name 2025/12/29 03:44:21 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2025/12/29 03:44:21 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2025/12/29 03:44:21 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2025/12/29 03:44:21 DEBUG : Setting pass="kCAXHJJ1Xee4VAjsutAg58jPiR74U4qeYaeO7ugKS8iuIHY" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2025/12/29 03:44:21 DEBUG : found headers: sync_test.go:701: Server side copy (if possible) webdav root 'rclone-test-qepifob0rala' -> webdav root 'rclone-test-mosaxum6cexa' 2025/12/29 03:44:21 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:21 DEBUG : webdav root 'rclone-test-mosaxum6cexa': Waiting for checks to finish 2025/12/29 03:44:21 DEBUG : webdav root 'rclone-test-mosaxum6cexa': Waiting for transfers to finish 2025/12/29 03:44:22 DEBUG : sub dir/hello world: Setting modtime after copy to 2001-02-03 04:05:06 +0000 GMT 2025/12/29 03:44:22 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:22 DEBUG : sub dir/hello world: Dst hash empty - aborting Src hash check 2025/12/29 03:44:22 INFO : sub dir/hello world: Copied (server-side copy) Diff of "logger" and "lsf" 2c2 < 2001-02-03 04:05:06;5eb63bbbe01eeed093cb22bb8f5acdc3;11;sub dir/hello world --- > 2001-02-03 04:05:06;;11;sub dir/hello world sync_test.go:3105: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:3105 /home/rclone/go/src/github.com/rclone/rclone/fs/sync/sync_test.go:706 Error: Received unexpected error: logger does not match lsf! logger: 2001-02-03 04:05:06;5eb63bbbe01eeed093cb22bb8f5acdc3;11;sub dir/hello world lsf: 2001-02-03 04:05:06;;11;sub dir/hello world Test: TestServerSideMoveOverSelf 2025/12/29 03:44:22 DEBUG : webdav root 'rclone-test-mosaxum6cexa': Purge remote --- FAIL: TestServerSideMoveOverSelf (2.31s) === RUN TestCopyAfterDelete run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:23 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:23 ERROR : error listing: directory not found 2025/12/29 03:44:23 INFO : Local file system at /tmp/rclone3416339459: Making directory 2025/12/29 03:44:24 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:24 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.34s) === RUN TestCopyRedownload run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:24 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:25 DEBUG : Added delayed dir = "sub dir", newDst= 2025/12/29 03:44:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:25 DEBUG : Local file system at /tmp/rclone3416339459: Waiting for checks to finish 2025/12/29 03:44:25 DEBUG : Local file system at /tmp/rclone3416339459: Waiting for transfers to finish 2025/12/29 03:44:25 DEBUG : sub dir/hello world.8a47773c.partial: size = 11 OK 2025/12/29 03:44:25 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:25 DEBUG : sub dir/hello world.8a47773c.partial: renamed to: sub dir/hello world 2025/12/29 03:44:25 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:25 INFO : sub dir: Set directory modification time (using DirSetModTime) --- PASS: TestCopyRedownload (1.30s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:185: Remote "webdav root 'rclone-test-qepifob0rala'", Local "Local file system at /tmp/rclone3416339459", Modify Window "1s" 2025/12/29 03:44:26 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2025/12/29 03:44:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2025/12/29 03:44:26 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for checks to finish 2025/12/29 03:44:26 DEBUG : webdav root 'rclone-test-qepifob0rala': Waiting for transfers to finish 2025/12/29 03:44:26 DEBUG : sub dir/hello world: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:26 DEBUG : sub dir/hello world: size = 11 OK 2025/12/29 03:44:26 DEBUG : sub dir/hello world: md5 = 5eb63bbbe01eeed093cb22bb8f5acdc3 OK 2025/12/29 03:44:26 INFO : sub dir/hello world: Copied (new) 2025/12/29 03:44:26 INFO : sub dir/hello world: Deleted 2025/12/29 03:44:26 DEBUG : nested/sub dir/file: Update will use the normal upload strategy (no chunks) 2025/12/29 03:44:26 DEBUG : nested/sub dir/file: size = 6 OK 2025/12/29 03:44:26 DEBUG : nested/sub dir/file: md5 = 83d3784ea62518eafc60e98d84f877ad OK 2025/12/29 03:44:26 INFO : nested/sub dir/file: Copied (new) 2025/12/29 03:44:26 INFO : nested/sub dir/file: Deleted 2025/12/29 03:44:26 INFO : sub dir: Removing directory 2025/12/29 03:44:26 INFO : nested/sub dir: Removing directory 2025/12/29 03:44:26 INFO : nested: Removing directory 2025/12/29 03:44:26 DEBUG : Local file system at /tmp/rclone3416339459: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.12s) FAIL 2025/12/29 03:44:28 DEBUG : webdav root 'rclone-test-qepifob0rala': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^(TestCopyAfterDelete|TestCopyCheckFirst|TestCopyEmptyDirectories|TestCopyNoEmptyDirectories|TestCopyNoTraverse|TestCopyOverSelf|TestCopyRedownload|TestMoveEmptyDirectories|TestMoveNoEmptyDirectories|TestMoveOverSelf|TestMoveWithDeleteEmptySrcDirs|TestServerSideCopy|TestServerSideCopyOverSelf|TestServerSideMoveOverSelf|TestSyncEmptyDirectories|TestSyncNoEmptyDirectories|TestSyncNoTraverse)$'" - Finished ERROR in 28.504110966s (try 2/5): exit status 1: Failed [TestServerSideCopyOverSelf TestServerSideMoveOverSelf]