"./bisync.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^TestBisyncRemoteRemote$/^(ext_paths|resolve)$'" - Starting (try 4/5) 2024/04/12 01:39:04 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-ruqogaw5cixetic2jeqoqaf0" 2024/04/12 01:39:04 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/12 01:39:04 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:04 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:04 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:04 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:04 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:04 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:04 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:04 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:04 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:04 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:04 DEBUG : found headers: 2024/04/12 01:39:04 DEBUG : pacer: low level retry 1/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/12 01:39:04 DEBUG : pacer: low level retry 2/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/12 01:39:04 DEBUG : pacer: low level retry 3/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 80ms 2024/04/12 01:39:04 DEBUG : pacer: low level retry 4/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 160ms 2024/04/12 01:39:04 DEBUG : pacer: low level retry 5/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 320ms 2024/04/12 01:39:04 DEBUG : pacer: low level retry 6/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": read tcp 127.0.0.1:50498->127.0.0.1:38081: read: connection reset by peer) 2024/04/12 01:39:04 DEBUG : pacer: Rate limited, increasing sleep to 640ms 2024/04/12 01:39:05 DEBUG : pacer: low level retry 7/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": EOF) 2024/04/12 01:39:05 DEBUG : pacer: Rate limited, increasing sleep to 1.28s 2024/04/12 01:39:05 DEBUG : pacer: low level retry 8/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": read tcp 127.0.0.1:50518->127.0.0.1:38081: read: connection reset by peer) 2024/04/12 01:39:05 DEBUG : pacer: Rate limited, increasing sleep to 2s 2024/04/12 01:39:07 DEBUG : pacer: low level retry 9/10 (error Propfind "http://127.0.0.1:38081/remote.php/webdav/rclone-test-ruqogaw5cixetic2jeqoqaf0": read tcp 127.0.0.1:50524->127.0.0.1:38081: read: connection reset by peer) 2024/04/12 01:39:09 DEBUG : pacer: Reducing sleep to 1.5s 2024/04/12 01:39:09 DEBUG : Creating backend with remote "/tmp/rclone2271267910" 2024/04/12 01:39:09 DEBUG : Config file has changed externally - reloading === RUN TestBisyncRemoteRemote 2024/04/12 01:39:09 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0" 2024/04/12 01:39:09 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:09 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:09 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:09 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:09 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:09 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:09 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:09 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:09 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:09 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:09 DEBUG : found headers: 2024/04/12 01:39:09 remote: TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0 === RUN TestBisyncRemoteRemote/ext_paths 2024/04/12 01:39:09 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" 2024/04/12 01:39:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/12 01:39:09 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:09 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:09 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:09 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:09 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:09 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:09 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:09 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:09 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:09 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:09 DEBUG : found headers: 2024/04/12 01:39:10 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" to be canonical "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" 2024/04/12 01:39:10 DEBUG : path1: Making directory 2024/04/12 01:39:10 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1" 2024/04/12 01:39:10 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:10 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:10 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:10 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:10 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:10 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:10 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:10 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:10 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:10 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:10 DEBUG : found headers: 2024/04/12 01:39:10 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1" to be canonical "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1" 2024/04/12 01:39:10 DEBUG : fs cache: switching user supplied name "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" for canonical name "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" 2024/04/12 01:39:10 DEBUG : path2: Making directory 2024/04/12 01:39:10 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2" 2024/04/12 01:39:10 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:10 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:10 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:10 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:10 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:10 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:10 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:10 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:10 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:10 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:10 DEBUG : found headers: 2024/04/12 01:39:11 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2" to be canonical "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2" 2024/04/12 01:39:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/initial" 2024/04/12 01:39:11 DEBUG : Creating backend with remote "/tmp/bs-013909-qu/initdir/test_ext_paths-goqufub8" 2024/04/12 01:39:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:11 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:11 DEBUG : file1.txt.jayecup7.partial: renamed to: file1.txt 2024/04/12 01:39:11 INFO : file1.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : file_enconde_mañana_funcionará.txt.zijabop5.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/12 01:39:11 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:11 DEBUG : RCLONE_TEST.ludovim9.partial: renamed to: RCLONE_TEST 2024/04/12 01:39:11 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:11 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:11 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:11 DEBUG : filename_contains_ࢺ_.txt.zufatid8.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/12 01:39:11 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:11 DEBUG : filename_contains_ě_.txt.qeqiwiw9.partial: renamed to: filename_contains_ě_.txt 2024/04/12 01:39:11 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : Русский.txt.zezasud0.partial: renamed to: Русский.txt 2024/04/12 01:39:11 INFO : Русский.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/12 01:39:11 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z) 2024/04/12 01:39:11 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : Local file system at /tmp/bs-013909-qu/initdir/test_ext_paths-goqufub8: Waiting for checks to finish 2024/04/12 01:39:11 DEBUG : Local file system at /tmp/bs-013909-qu/initdir/test_ext_paths-goqufub8: Waiting for transfers to finish 2024/04/12 01:39:11 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:11 DEBUG : 測試_check file.jilajas9.partial: renamed to: 測試_check file 2024/04/12 01:39:11 INFO : 測試_check file: Copied (new) 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.baqihap5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/12 01:39:11 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.yudafep7.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:11 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.sihorod5.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:11 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/12 01:39:11 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/12 01:39:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/modfiles" 2024/04/12 01:39:11 DEBUG : Creating backend with remote "/tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3" 2024/04/12 01:39:11 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : Local file system at /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3: Waiting for checks to finish 2024/04/12 01:39:11 DEBUG : Local file system at /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3: Waiting for transfers to finish 2024/04/12 01:39:11 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/12 01:39:11 DEBUG : file1.txt.wuhofeq0.partial: renamed to: file1.txt 2024/04/12 01:39:11 INFO : file1.txt: Copied (new) 2024/04/12 01:39:11 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/12 01:39:11 DEBUG : 測試_filtersfile.txt.lafuhoq1.partial: renamed to: 測試_filtersfile.txt 2024/04/12 01:39:11 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/12 01:39:11 ERROR : : error listing: directory not found 2024/04/12 01:39:11 ERROR : : error listing: directory not found 2024/04/12 01:39:11 checking initFs Local file system at /tmp/bs-013909-qu/initdir/test_ext_paths-goqufub8 2024/04/12 01:39:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:11 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/12 01:39:11 DEBUG : pacer: low level retry 1/10 (error "rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2024/04/12 01:39:11 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/12 01:39:11 DEBUG : pacer: Reducing sleep to 15ms 2024/04/12 01:39:11 DEBUG : pacer: Reducing sleep to 11.25ms 2024/04/12 01:39:11 DEBUG : pacer: Reducing sleep to 10ms 2024/04/12 01:39:11 DEBUG : filename_contains_ě_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:11 DEBUG : RCLONE_TEST: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : file_enconde_mañana_funcionará.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : file1.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:12 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1': Waiting for checks to finish 2024/04/12 01:39:12 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1': Waiting for transfers to finish 2024/04/12 01:39:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:12 INFO : file1.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:12 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:12 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:12 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:12 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : filename_contains_ࢺ_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : Русский.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : 測試_check file: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:12 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:12 INFO : 測試_check file: Copied (new) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:12 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:12 INFO : Русский.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:12 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:12 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:12 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/12 01:39:12 checking Path1 webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1' 2024/04/12 01:39:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/12 01:39:13 DEBUG : file_enconde_mañana_funcionará.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : RCLONE_TEST: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : file1.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : filename_contains_ě_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/12 01:39:13 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2': Waiting for checks to finish 2024/04/12 01:39:13 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2': Waiting for transfers to finish 2024/04/12 01:39:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:13 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:13 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:13 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:13 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:13 INFO : file1.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : filename_contains_ࢺ_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : 測試_check file: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : Русский.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:13 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:13 INFO : 測試_check file: Copied (new) 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:13 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:13 INFO : Русский.txt: Copied (new) 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:13 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:14 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/12 01:39:14 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/12 01:39:14 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:14 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/12 01:39:14 checking path2 webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2' 2024/04/12 01:39:14 (01) : test extended-char-paths 2024/04/12 01:39:14 (02) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/12 01:39:14 INFO : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-qimidek0': Server side directory move succeeded 2024/04/12 01:39:14 INFO : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ': Server side directory move succeeded 2024/04/12 01:39:14 (03) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/12 01:39:15 INFO : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-pevemoj5': Server side directory move succeeded 2024/04/12 01:39:15 INFO : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ': Server side directory move succeeded 2024/04/12 01:39:15 (04) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/12 01:39:16 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-yuzivur9 2024/04/12 01:39:16 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-yuzivur9: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:17 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-lodokud7 2024/04/12 01:39:17 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-lodokud7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:17 (05) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/12 01:39:18 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-vakivac7 2024/04/12 01:39:18 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-vakivac7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:18 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-yoketaq8 2024/04/12 01:39:19 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-yoketaq8: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:19 (06) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:20 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-liqikaf0 2024/04/12 01:39:20 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-liqikaf0: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:20 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-muraboh7 2024/04/12 01:39:21 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-muraboh7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:21 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-vutihip9 2024/04/12 01:39:22 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-vutihip9: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:22 (07) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:22 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-zeququk7 2024/04/12 01:39:22 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-zeququk7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:23 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-kujafor9 2024/04/12 01:39:23 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-kujafor9: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:24 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-qupaqec2 2024/04/12 01:39:24 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt-rclone-move-qupaqec2: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/12 01:39:24 (08) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_check{spc}file 2024/04/12 01:39:25 (09) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_check{spc}file 2024/04/12 01:39:25 (10) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/12 01:39:26 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-nuguyuv4 2024/04/12 01:39:26 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-nuguyuv4: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:26 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-vaweyem7 2024/04/12 01:39:27 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-vaweyem7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:27 (11) : fix-names TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/12 01:39:28 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-cowanax1 2024/04/12 01:39:28 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-cowanax1: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:28 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-nibotuk7 2024/04/12 01:39:29 INFO : rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file-rclone-move-nibotuk7: Moved (server-side) to: rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:29 (12) : test resync subdirs with extended chars 2024/04/12 01:39:29 (13) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync 2024/04/12 01:39:30 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:30 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:30 INFO : /tmp/bs-013909-qu/workdir/TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: renaming to: /tmp/bs-013909-qu/workdir/TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst 2024/04/12 01:39:30 INFO : /tmp/bs-013909-qu/workdir/TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: renaming to: /tmp/bs-013909-qu/workdir/TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst 2024/04/12 01:39:30 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/12 01:39:30 INFO : Copying Path2 files to Path1 2024/04/12 01:39:30 INFO : - Path2 Resync is copying files to - Path1 2024/04/12 01:39:30 INFO : - Path1 Resync is copying files to - Path2 2024/04/12 01:39:30 INFO : Resync updating listings 2024/04/12 01:39:30 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/12 01:39:30 INFO : Bisync successful 2024/04/12 01:39:30 (14) : copy-listings resync 2024/04/12 01:39:30 (15) : test place new files with extended chars on each side 2024/04/12 01:39:30 (16) : touch-glob 2001-01-02 /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3/ file1.txt 2024/04/12 01:39:30 (17) : copy-as /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3/file1.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p1 2024/04/12 01:39:31 INFO : file1.txt: Copied (new) to: 測試_file1p1 2024/04/12 01:39:31 (18) : copy-as /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3/file1.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p2 2024/04/12 01:39:31 INFO : file1.txt: Copied (new) to: 測試_file1p2 2024/04/12 01:39:31 (19) : test normal sync of subdirs with extended chars 2024/04/12 01:39:31 (20) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ 2024/04/12 01:39:32 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:32 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:32 INFO : /tmp/bs-013909-qu/workdir/TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: renaming to: /tmp/bs-013909-qu/workdir/TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst 2024/04/12 01:39:32 INFO : /tmp/bs-013909-qu/workdir/TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: renaming to: /tmp/bs-013909-qu/workdir/TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst 2024/04/12 01:39:32 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/12 01:39:32 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:33 INFO : Path1 checking for diffs 2024/04/12 01:39:33 INFO : - Path1 File is new - 測試_file1p1 2024/04/12 01:39:33 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:39:33 INFO : Path2 checking for diffs 2024/04/12 01:39:33 INFO : - Path2 File is new - 測試_file1p2 2024/04/12 01:39:33 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:39:33 INFO : Applying changes 2024/04/12 01:39:33 INFO : - Path1 Queue copy to Path2 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_file1p1 2024/04/12 01:39:33 INFO : - Path2 Queue copy to Path1 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_file1p2 2024/04/12 01:39:33 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:39:33 INFO : 測試_file1p2: Copied (server-side copy) 2024/04/12 01:39:33 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:39:33 INFO : 測試_file1p1: Copied (server-side copy) 2024/04/12 01:39:33 INFO : Updating listings 2024/04/12 01:39:33 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/12 01:39:33 INFO : Bisync successful 2024/04/12 01:39:33 (21) : move-listings normal-sync 2024/04/12 01:39:33 (22) : test check-filename with extended chars. check should fail. 2024/04/12 01:39:33 (23) : bisync resync 2024/04/12 01:39:35 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:35 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:35 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:35 INFO : Copying Path2 files to Path1 2024/04/12 01:39:35 INFO : - Path2 Resync is copying files to - Path1 2024/04/12 01:39:35 INFO : - Path1 Resync is copying files to - Path2 2024/04/12 01:39:35 INFO : Resync updating listings 2024/04/12 01:39:35 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:35 INFO : Bisync successful 2024/04/12 01:39:35 (24) : delete-file TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/12 01:39:35 INFO : 測試_check file: Deleted 2024/04/12 01:39:35 (25) : bisync check-access check-filename=測試_check{spc}file 2024/04/12 01:39:36 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:36 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:36 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:36 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:37 INFO : Path1 checking for diffs 2024/04/12 01:39:37 INFO : - Path1 File was deleted - 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:37 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2024/04/12 01:39:37 INFO : Path2 checking for diffs 2024/04/12 01:39:37 INFO : Checking access health 2024/04/12 01:39:37 ERROR : Access test failed: Path1 count 1, Path2 count 2 - 測試_check file 2024/04/12 01:39:37 ERROR : -  Access test failed: Path2 file not found in Path1 - 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/12 01:39:37 ERROR : Bisync critical error: check file check failed 2024/04/12 01:39:37 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/12 01:39:37 Bisync error: bisync aborted 2024/04/12 01:39:37 (26) : copy-listings check-access-fail 2024/04/12 01:39:37 (27) : test check-filename with extended chars. check should pass. 2024/04/12 01:39:37 (28) : bisync resync 2024/04/12 01:39:38 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:38 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:38 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:38 INFO : Copying Path2 files to Path1 2024/04/12 01:39:38 INFO : - Path2 Resync is copying files to - Path1 2024/04/12 01:39:38 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (server-side copy) 2024/04/12 01:39:38 INFO : - Path1 Resync is copying files to - Path2 2024/04/12 01:39:38 INFO : Resync updating listings 2024/04/12 01:39:38 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:38 INFO : Bisync successful 2024/04/12 01:39:38 (29) : bisync check-access check-filename=測試_check{spc}file 2024/04/12 01:39:40 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:40 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:40 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:40 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:40 INFO : Path1 checking for diffs 2024/04/12 01:39:40 INFO : Path2 checking for diffs 2024/04/12 01:39:40 INFO : Checking access health 2024/04/12 01:39:40 INFO : Found 2 matching "測試_check file" files on both paths 2024/04/12 01:39:40 INFO : No changes found 2024/04/12 01:39:40 INFO : Updating listings 2024/04/12 01:39:40 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:40 INFO : Bisync successful 2024/04/12 01:39:40 (30) : move-listings check-access-pass 2024/04/12 01:39:40 (31) : test filters-file path with extended chars - masks /fileZ.txt 2024/04/12 01:39:40 (32) : copy-file /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3/測試_filtersfile.txt /tmp/bs-013909-qu/workdir/ 2024/04/12 01:39:40 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/12 01:39:40 (33) : bisync filters-file=/tmp/bs-013909-qu/workdir/測試_filtersfile.txt resync 2024/04/12 01:39:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:41 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:41 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:41 INFO : Using filters file /tmp/bs-013909-qu/workdir/測試_filtersfile.txt 2024/04/12 01:39:41 INFO : Storing filters file hash to /tmp/bs-013909-qu/workdir/測試_filtersfile.txt.md5 2024/04/12 01:39:41 INFO : Copying Path2 files to Path1 2024/04/12 01:39:41 INFO : - Path2 Resync is copying files to - Path1 2024/04/12 01:39:41 INFO : - Path1 Resync is copying files to - Path2 2024/04/12 01:39:41 INFO : Resync updating listings 2024/04/12 01:39:41 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:41 INFO : Bisync successful 2024/04/12 01:39:41 (34) : copy-as /tmp/bs-013909-qu/datadir/test_ext_paths-bufequl3/file1.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/ fileZ.txt 2024/04/12 01:39:42 INFO : file1.txt: Copied (new) to: fileZ.txt 2024/04/12 01:39:42 (35) : bisync filters-file=/tmp/bs-013909-qu/workdir/測試_filtersfile.txt 2024/04/12 01:39:43 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:43 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:43 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:43 INFO : Using filters file /tmp/bs-013909-qu/workdir/測試_filtersfile.txt 2024/04/12 01:39:43 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:43 INFO : Path1 checking for diffs 2024/04/12 01:39:43 INFO : Path2 checking for diffs 2024/04/12 01:39:43 INFO : No changes found 2024/04/12 01:39:43 INFO : Updating listings 2024/04/12 01:39:43 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/ext_paths/path2/" 2024/04/12 01:39:43 INFO : Bisync successful 2024/04/12 01:39:43 ---------------------------------------------------------- 2024/04/12 01:39:43 | MISCOMPARE -Golden vs +Results for test.log 2024/04/12 01:39:43 | @@ -23,0 +24,2 @@ 2024/04/12 01:39:43 | +INFO : {workdir/}TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: renaming to: {workdir/}TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst 2024/04/12 01:39:43 | +INFO : {workdir/}TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: renaming to: {workdir/}TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst 2024/04/12 01:39:43 | @@ -47,0 +50,2 @@ 2024/04/12 01:39:43 | +INFO : {workdir/}TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: renaming to: {workdir/}TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst 2024/04/12 01:39:43 | +INFO : {workdir/}TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud{KfslQ}_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: renaming to: {workdir/}TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path1_測試_Русский_____ě_áñ..TestWebdavOwncloud_rclone-test-yojohit7gitoriv2napojuj0_bs-013909-qu_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst 2024/04/12 01:39:43 ---------------------------------------------------------- bisync_test.go:539: TEST ext_paths FAILED - 1 MISCOMPARED FILES === RUN TestBisyncRemoteRemote/resolve 2024/04/12 01:39:43 DEBUG : fs cache: switching user supplied name "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" for canonical name "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" 2024/04/12 01:39:43 DEBUG : path1: Making directory 2024/04/12 01:39:43 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1" 2024/04/12 01:39:43 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:43 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:43 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:43 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:43 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:43 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:43 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:43 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:43 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:43 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:43 DEBUG : found headers: 2024/04/12 01:39:44 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1" to be canonical "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1" 2024/04/12 01:39:44 DEBUG : fs cache: switching user supplied name "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" for canonical name "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu" 2024/04/12 01:39:44 DEBUG : path2: Making directory 2024/04/12 01:39:44 DEBUG : Creating backend with remote "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2" 2024/04/12 01:39:44 DEBUG : Setting type="webdav" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_TYPE 2024/04/12 01:39:44 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:44 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:44 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:44 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:44 DEBUG : TestWebdavOwncloud: detected overridden config - adding "{KfslQ}" suffix to name 2024/04/12 01:39:44 DEBUG : Setting url="http://127.0.0.1:38081/remote.php/webdav/" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_URL 2024/04/12 01:39:44 DEBUG : Setting vendor="owncloud" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_VENDOR 2024/04/12 01:39:44 DEBUG : Setting user="rclone" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_USER 2024/04/12 01:39:44 DEBUG : Setting pass="NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw" for "TestWebdavOwncloud" from environment variable RCLONE_CONFIG_TESTWEBDAVOWNCLOUD_PASS 2024/04/12 01:39:44 DEBUG : found headers: 2024/04/12 01:39:44 DEBUG : fs cache: renaming cache item "TestWebdavOwncloud:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2" to be canonical "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2" 2024/04/12 01:39:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial" 2024/04/12 01:39:44 DEBUG : Creating backend with remote "/tmp/bs-013909-qu/initdir/test_resolve-peseqap0" 2024/04/12 01:39:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : Local file system at /tmp/bs-013909-qu/initdir/test_resolve-peseqap0: Waiting for checks to finish 2024/04/12 01:39:44 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:44 DEBUG : Local file system at /tmp/bs-013909-qu/initdir/test_resolve-peseqap0: Waiting for transfers to finish 2024/04/12 01:39:44 DEBUG : file2.txt.sabuhap7.partial: renamed to: file2.txt 2024/04/12 01:39:44 INFO : file2.txt: Copied (new) 2024/04/12 01:39:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:44 DEBUG : RCLONE_TEST.jogolog0.partial: renamed to: RCLONE_TEST 2024/04/12 01:39:44 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:44 DEBUG : file1.txt.jidogoh0.partial: renamed to: file1.txt 2024/04/12 01:39:44 INFO : file1.txt: Copied (new) 2024/04/12 01:39:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles" 2024/04/12 01:39:44 DEBUG : Creating backend with remote "/tmp/bs-013909-qu/datadir/test_resolve-rosusuw3" 2024/04/12 01:39:44 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : Local file system at /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3: Waiting for checks to finish 2024/04/12 01:39:44 DEBUG : Local file system at /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3: Waiting for transfers to finish 2024/04/12 01:39:44 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2024/04/12 01:39:44 DEBUG : file1L.txt.gadosiw1.partial: renamed to: file1L.txt 2024/04/12 01:39:44 INFO : file1L.txt: Copied (new) 2024/04/12 01:39:44 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2024/04/12 01:39:44 DEBUG : file2.txt.wasurig0.partial: renamed to: file2.txt 2024/04/12 01:39:44 INFO : file2.txt: Copied (new) 2024/04/12 01:39:44 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK 2024/04/12 01:39:44 DEBUG : file1R.txt.kofihoq0.partial: renamed to: file1R.txt 2024/04/12 01:39:44 INFO : file1R.txt: Copied (new) 2024/04/12 01:39:44 ERROR : : error listing: directory not found 2024/04/12 01:39:44 ERROR : : error listing: directory not found 2024/04/12 01:39:44 checking initFs Local file system at /tmp/bs-013909-qu/initdir/test_resolve-peseqap0 2024/04/12 01:39:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:44 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1': Waiting for checks to finish 2024/04/12 01:39:44 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1': Waiting for transfers to finish 2024/04/12 01:39:45 DEBUG : file1.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : file2.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : RCLONE_TEST: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:45 INFO : file1.txt: Copied (new) 2024/04/12 01:39:45 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:45 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:45 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:45 INFO : file2.txt: Copied (new) 2024/04/12 01:39:45 checking Path1 webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1' 2024/04/12 01:39:45 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/12 01:39:45 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:45 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/12 01:39:45 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': Waiting for checks to finish 2024/04/12 01:39:45 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': Waiting for transfers to finish 2024/04/12 01:39:45 DEBUG : pacer: low level retry 1/10 (error "rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2024/04/12 01:39:45 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/04/12 01:39:45 DEBUG : pacer: low level retry 1/10 (error "rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2" is locked: OCA\DAV\Connector\Sabre\Exception\FileLocked: 423 Locked) 2024/04/12 01:39:45 DEBUG : pacer: Rate limited, increasing sleep to 40ms 2024/04/12 01:39:45 DEBUG : pacer: Reducing sleep to 30ms 2024/04/12 01:39:45 DEBUG : RCLONE_TEST: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : pacer: Reducing sleep to 22.5ms 2024/04/12 01:39:45 DEBUG : file1.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : pacer: Reducing sleep to 16.875ms 2024/04/12 01:39:45 DEBUG : file2.txt: Update will use the normal upload strategy (no chunks) 2024/04/12 01:39:45 DEBUG : pacer: Reducing sleep to 12.65625ms 2024/04/12 01:39:45 DEBUG : pacer: Reducing sleep to 10ms 2024/04/12 01:39:45 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/12 01:39:45 INFO : RCLONE_TEST: Copied (new) 2024/04/12 01:39:45 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:45 INFO : file1.txt: Copied (new) 2024/04/12 01:39:46 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/12 01:39:46 INFO : file2.txt: Copied (new) 2024/04/12 01:39:46 checking path2 webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2' 2024/04/12 01:39:46 (01) : test resolve 2024/04/12 01:39:46 (02) : test initial bisync 2024/04/12 01:39:46 (03) : bisync resync 2024/04/12 01:39:47 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:47 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:47 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:47 INFO : Copying Path2 files to Path1 2024/04/12 01:39:47 INFO : - Path2 Resync is copying files to - Path1 2024/04/12 01:39:47 INFO : - Path1 Resync is copying files to - Path2 2024/04/12 01:39:47 INFO : Resync updating listings 2024/04/12 01:39:47 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:47 INFO : Bisync successful 2024/04/12 01:39:47 (04) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/04/12 01:39:47 (05) : touch-glob 2001-01-02 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:39:47 (06) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:39:47 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:47 (07) : touch-glob 2001-03-04 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:39:47 (08) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:39:48 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:48 (09) : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato 2024/04/12 01:39:48 (10) : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato 2024/04/12 01:39:49 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:49 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:49 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:49 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:49 INFO : Path1 checking for diffs 2024/04/12 01:39:49 INFO : - Path1 File changed: size (larger), time (newer) - file1.txt 2024/04/12 01:39:49 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2024/04/12 01:39:49 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/04/12 01:39:49 INFO : Path2 checking for diffs 2024/04/12 01:39:49 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2024/04/12 01:39:49 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2024/04/12 01:39:49 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/04/12 01:39:49 INFO : Applying changes 2024/04/12 01:39:49 INFO : Checking potential conflicts... 2024/04/12 01:39:49 ERROR : file1.txt: md5 differ 2024/04/12 01:39:49 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 differences found 2024/04/12 01:39:49 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 errors while checking 2024/04/12 01:39:49 INFO : Finished checking the potential conflicts. 1 differences found 2024/04/12 01:39:49 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:39:49 INFO : file1.txt: Path1 is newer. Path1: 2001-03-04 00:00:00 +0000 UTC, Path2: 2001-01-02 00:00:00 +0000 UTC, Difference: 1464h0m0s 2024/04/12 01:39:49 INFO : file1.txt: The winner is: Path1 2024/04/12 01:39:49 NOTICE: - Path2 Deleting Path2 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt 2024/04/12 01:39:49 INFO : file1.txt: Deleted 2024/04/12 01:39:49 INFO : - Path1 Queue copy to Path2 - file1.txt 2024/04/12 01:39:49 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:39:50 INFO : file1.txt: Copied (server-side copy) 2024/04/12 01:39:50 INFO : Updating listings 2024/04/12 01:39:50 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:50 INFO : Bisync successful 2024/04/12 01:39:50 (11) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/04/12 01:39:50 (12) : touch-glob 2001-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:39:50 (13) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:39:50 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:50 (14) : touch-glob 2001-08-26 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:39:50 (15) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:39:51 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1': Forced to upload files to set modification times on this backend. 2024/04/12 01:39:51 INFO : file1.txt: src and dst identical but can't set mod time without re-uploading 2024/04/12 01:39:51 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:51 (16) : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur 2024/04/12 01:39:51 (17) : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur 2024/04/12 01:39:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:52 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:52 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:52 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:52 INFO : Path1 checking for diffs 2024/04/12 01:39:52 INFO : - Path1 File changed: time (newer) - file1.txt 2024/04/12 01:39:52 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2024/04/12 01:39:52 INFO : (Modified:  1 newer,  0 older) 2024/04/12 01:39:52 INFO : Path2 checking for diffs 2024/04/12 01:39:52 INFO : - Path2 File changed: time (newer) - file1.txt 2024/04/12 01:39:52 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2024/04/12 01:39:52 INFO : (Modified:  1 newer,  0 older) 2024/04/12 01:39:52 INFO : Applying changes 2024/04/12 01:39:52 INFO : Checking potential conflicts... 2024/04/12 01:39:52 ERROR : file1.txt: md5 differ 2024/04/12 01:39:52 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 differences found 2024/04/12 01:39:52 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 errors while checking 2024/04/12 01:39:52 INFO : Finished checking the potential conflicts. 1 differences found 2024/04/12 01:39:52 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:39:52 INFO : file1.txt: The winner is: Path2 2024/04/12 01:39:52 NOTICE: - Path1 Renaming Path1 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt.dinosaur1 2024/04/12 01:39:53 INFO : file1.txt: Moved (server-side) to: file1.txt.dinosaur1 2024/04/12 01:39:53 NOTICE: - Path1 Queue copy to Path2 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt.dinosaur1 2024/04/12 01:39:53 NOTICE: - Path2 Not renaming Path2 copy, as it was determined the winner - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt 2024/04/12 01:39:53 NOTICE: - Path2 Queue copy to Path1 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt 2024/04/12 01:39:53 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:39:53 INFO : file1.txt: Copied (server-side copy) 2024/04/12 01:39:53 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:39:54 INFO : file1.txt.dinosaur1: Copied (server-side copy) 2024/04/12 01:39:54 INFO : Updating listings 2024/04/12 01:39:54 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:54 INFO : Bisync successful 2024/04/12 01:39:54 (18) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/04/12 01:39:54 (19) : touch-glob 2002-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:39:54 (20) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:39:54 INFO : file1.txt: src and dst identical but can't set mod time without re-uploading 2024/04/12 01:39:54 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:54 (21) : touch-glob 2002-08-26 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:39:54 (22) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:39:55 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/04/12 01:39:55 (23) : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple 2024/04/12 01:39:55 (24) : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple 2024/04/12 01:39:56 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:39:56 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:39:56 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:56 INFO : Building Path1 and Path2 listings 2024/04/12 01:39:56 INFO : Path1 checking for diffs 2024/04/12 01:39:56 INFO : - Path1 File is new - file1.txt 2024/04/12 01:39:56 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:39:56 INFO : Path2 checking for diffs 2024/04/12 01:39:56 INFO : - Path2 File is new - file1.txt 2024/04/12 01:39:56 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:39:56 INFO : Applying changes 2024/04/12 01:39:56 INFO : Checking potential conflicts... 2024/04/12 01:39:56 ERROR : file1.txt: md5 differ 2024/04/12 01:39:56 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 differences found 2024/04/12 01:39:56 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 errors while checking 2024/04/12 01:39:56 INFO : Finished checking the potential conflicts. 1 differences found 2024/04/12 01:39:56 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:39:56 INFO : file1.txt: Winner cannot be determined as sizes are equal. Path1: 33, Path2: 33, Difference: 0 2024/04/12 01:39:56 INFO : file1.txt: A winner could not be determined. 2024/04/12 01:39:56 NOTICE: - Path1 Renaming Path1 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt.apple1 2024/04/12 01:39:56 INFO : file1.txt: Moved (server-side) to: file1.txt.apple1 2024/04/12 01:39:56 NOTICE: - Path1 Queue copy to Path2 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt.apple1 2024/04/12 01:39:56 NOTICE: - Path2 Renaming Path2 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt.apple2 2024/04/12 01:39:57 INFO : file1.txt: Moved (server-side) to: file1.txt.apple2 2024/04/12 01:39:57 NOTICE: - Path2 Queue copy to Path1 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt.apple2 2024/04/12 01:39:57 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:39:57 INFO : file1.txt.apple2: Copied (server-side copy) 2024/04/12 01:39:57 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:39:58 INFO : file1.txt.apple1: Copied (server-side copy) 2024/04/12 01:39:58 INFO : Updating listings 2024/04/12 01:39:58 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:39:58 INFO : Bisync successful 2024/04/12 01:39:58 (25) : test different suffixes 2024/04/12 01:39:58 (26) : touch-glob 2003-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:39:58 (27) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:39:58 INFO : file1R.txt: Copied (new) to: file1.txt 2024/04/12 01:39:58 (28) : touch-glob 2003-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:39:58 (29) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:39:59 INFO : file1L.txt: Copied (new) to: file1.txt 2024/04/12 01:39:59 (30) : test bisync run with --conflict-resolve=older --conflict-loser=num --conflict-suffix=cloud,local 2024/04/12 01:39:59 (31) : bisync conflict-resolve=older conflict-loser=num conflict-suffix=cloud,local 2024/04/12 01:40:00 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:40:00 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:40:00 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:00 INFO : Building Path1 and Path2 listings 2024/04/12 01:40:00 INFO : Path1 checking for diffs 2024/04/12 01:40:00 INFO : - Path1 File is new - file1.txt 2024/04/12 01:40:00 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:40:00 INFO : Path2 checking for diffs 2024/04/12 01:40:00 INFO : - Path2 File is new - file1.txt 2024/04/12 01:40:00 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:40:00 INFO : Applying changes 2024/04/12 01:40:00 INFO : Checking potential conflicts... 2024/04/12 01:40:00 ERROR : file1.txt: md5 differ 2024/04/12 01:40:00 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 differences found 2024/04/12 01:40:00 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 errors while checking 2024/04/12 01:40:00 INFO : Finished checking the potential conflicts. 1 differences found 2024/04/12 01:40:00 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:40:00 INFO : file1.txt: Winner cannot be determined as times are equal. Path1: 2003-07-23 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 0s 2024/04/12 01:40:00 INFO : file1.txt: A winner could not be determined. 2024/04/12 01:40:00 NOTICE: - Path1 Renaming Path1 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt.cloud1 2024/04/12 01:40:00 INFO : file1.txt: Moved (server-side) to: file1.txt.cloud1 2024/04/12 01:40:00 NOTICE: - Path1 Queue copy to Path2 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt.cloud1 2024/04/12 01:40:00 NOTICE: - Path2 Renaming Path2 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt.local1 2024/04/12 01:40:01 INFO : file1.txt: Moved (server-side) to: file1.txt.local1 2024/04/12 01:40:01 NOTICE: - Path2 Queue copy to Path1 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt.local1 2024/04/12 01:40:01 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:40:01 INFO : file1.txt.local1: Copied (server-side copy) 2024/04/12 01:40:01 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:40:02 INFO : file1.txt.cloud1: Copied (server-side copy) 2024/04/12 01:40:02 INFO : Updating listings 2024/04/12 01:40:02 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:02 INFO : Bisync successful 2024/04/12 01:40:02 (32) : test legacy 2024/04/12 01:40:02 (33) : touch-glob 2004-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:40:02 (34) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:40:02 INFO : file1R.txt: Copied (new) to: file1.txt 2024/04/12 01:40:02 (35) : touch-glob 2004-07-23 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:40:02 (36) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:40:03 INFO : file1L.txt: Copied (new) to: file1.txt 2024/04/12 01:40:03 (37) : test bisync run with --conflict-resolve=none --conflict-loser=pathname --conflict-suffix=.path 2024/04/12 01:40:03 (38) : bisync conflict-resolve=none conflict-loser=pathname conflict-suffix=.path 2024/04/12 01:40:04 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:40:04 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:40:04 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:04 INFO : Building Path1 and Path2 listings 2024/04/12 01:40:04 INFO : Path1 checking for diffs 2024/04/12 01:40:04 INFO : - Path1 File is new - file1.txt 2024/04/12 01:40:04 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:40:04 INFO : Path2 checking for diffs 2024/04/12 01:40:04 INFO : - Path2 File is new - file1.txt 2024/04/12 01:40:04 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/12 01:40:04 INFO : Applying changes 2024/04/12 01:40:04 INFO : Checking potential conflicts... 2024/04/12 01:40:04 ERROR : file1.txt: md5 differ 2024/04/12 01:40:04 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 differences found 2024/04/12 01:40:04 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 1 errors while checking 2024/04/12 01:40:04 INFO : Finished checking the potential conflicts. 1 differences found 2024/04/12 01:40:04 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:40:04 NOTICE: - Path1 Renaming Path1 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt..path1 2024/04/12 01:40:04 INFO : file1.txt: Moved (server-side) to: file1.txt..path1 2024/04/12 01:40:04 NOTICE: - Path1 Queue copy to Path2 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt..path1 2024/04/12 01:40:04 NOTICE: - Path2 Renaming Path2 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt..path2 2024/04/12 01:40:05 INFO : file1.txt: Moved (server-side) to: file1.txt..path2 2024/04/12 01:40:05 NOTICE: - Path2 Queue copy to Path1 - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file1.txt..path2 2024/04/12 01:40:05 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:40:05 INFO : file1.txt..path2: Copied (server-side copy) 2024/04/12 01:40:05 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:40:06 INFO : file1.txt..path1: Copied (server-side copy) 2024/04/12 01:40:06 INFO : Updating listings 2024/04/12 01:40:06 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:06 INFO : Bisync successful 2024/04/12 01:40:06 (39) : test deletes on both sides with default suffix 2024/04/12 01:40:06 (40) : touch-glob 2005-01-02 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1R.txt 2024/04/12 01:40:06 (41) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file1.txt 2024/04/12 01:40:06 INFO : file1R.txt: Copied (new) to: file1.txt 2024/04/12 01:40:06 (42) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1R.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file2.txt 2024/04/12 01:40:07 INFO : file1R.txt: Copied (replaced existing) to: file2.txt 2024/04/12 01:40:07 (43) : touch-glob 2006-03-04 /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/ file1L.txt 2024/04/12 01:40:07 (44) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/ file1.txt 2024/04/12 01:40:07 INFO : file1L.txt: Copied (new) to: file1.txt 2024/04/12 01:40:07 (45) : copy-as /tmp/bs-013909-qu/datadir/test_resolve-rosusuw3/file1L.txt TestWebdavOwncloud,pass='NbDD7MbDeBF_S6tFJxRhrKWZW3Dloj1TzReBZ2yxTNL4tEw',url='http://127.0.0.1:38081/remote.php/webdav/',user='rclone',vendor='owncloud':rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/ file2.txt 2024/04/12 01:40:08 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2024/04/12 01:40:08 (46) : test bisync run with --conflict-resolve=newer --conflict-loser=delete 2024/04/12 01:40:08 (47) : bisync conflict-resolve=newer conflict-loser=delete 2024/04/12 01:40:09 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/12 01:40:09 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2024/04/12 01:40:09 INFO : Synching Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" with Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:09 INFO : Building Path1 and Path2 listings 2024/04/12 01:40:09 INFO : Path1 checking for diffs 2024/04/12 01:40:09 INFO : - Path1 File changed: size (larger), time (newer) - file2.txt 2024/04/12 01:40:09 INFO : - Path1 File is new - file1.txt 2024/04/12 01:40:09 INFO : Path1: 2 changes:  1 new,  1 modified,  0 deleted 2024/04/12 01:40:09 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/04/12 01:40:09 INFO : Path2 checking for diffs 2024/04/12 01:40:09 INFO : - Path2 File changed: size (larger), time (newer) - file2.txt 2024/04/12 01:40:09 INFO : - Path2 File is new - file1.txt 2024/04/12 01:40:09 INFO : Path2: 2 changes:  1 new,  1 modified,  0 deleted 2024/04/12 01:40:09 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/04/12 01:40:09 INFO : Applying changes 2024/04/12 01:40:09 INFO : Checking potential conflicts... 2024/04/12 01:40:09 ERROR : file1.txt: md5 differ 2024/04/12 01:40:09 ERROR : file2.txt: md5 differ 2024/04/12 01:40:09 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 2 differences found 2024/04/12 01:40:09 NOTICE: webdav root 'rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2': 2 errors while checking 2024/04/12 01:40:09 INFO : Finished checking the potential conflicts. 2 differences found 2024/04/12 01:40:09 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/04/12 01:40:09 INFO : file1.txt: Path1 is newer. Path1: 2006-03-04 00:00:00 +0000 UTC, Path2: 2005-01-02 00:00:00 +0000 UTC, Difference: 10224h0m0s 2024/04/12 01:40:09 INFO : file1.txt: The winner is: Path1 2024/04/12 01:40:09 NOTICE: - Path2 Deleting Path2 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/file1.txt 2024/04/12 01:40:09 INFO : file1.txt: Deleted 2024/04/12 01:40:09 INFO : - Path1 Queue copy to Path2 - file1.txt 2024/04/12 01:40:09 NOTICE: - WARNING New or changed in both paths - file2.txt 2024/04/12 01:40:09 INFO : file2.txt: Path2 is newer. Path1: 2005-01-02 00:00:00 +0000 UTC, Path2: 2006-03-04 00:00:00 +0000 UTC, Difference: 10224h0m0s 2024/04/12 01:40:09 INFO : file2.txt: The winner is: Path2 2024/04/12 01:40:09 NOTICE: - Path1 Deleting Path1 copy - TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/file2.txt 2024/04/12 01:40:09 INFO : file2.txt: Deleted 2024/04/12 01:40:09 INFO : - Path2 Queue copy to Path1 - file2.txt 2024/04/12 01:40:09 INFO : - Path2 Do queued copies to - Path1 2024/04/12 01:40:10 INFO : file2.txt: Copied (server-side copy) 2024/04/12 01:40:10 INFO : - Path1 Do queued copies to - Path2 2024/04/12 01:40:10 INFO : file1.txt: Copied (server-side copy) 2024/04/12 01:40:10 INFO : Updating listings 2024/04/12 01:40:10 ERROR : file1.txt: failed to check as hash is missing 2024/04/12 01:40:10 INFO : file1.txt: files not equal on recheck: file1.txt file1.txt 2024/04/12 01:40:10 INFO : Validating listings for Path1 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path1/" vs Path2 "TestWebdavOwncloud{KfslQ}:rclone-test-yojohit7gitoriv2napojuj0/bs-013909-qu/resolve/path2/" 2024/04/12 01:40:10 INFO : Bisync successful 2024/04/12 01:40:10 ---------------------------------------------------------- 2024/04/12 01:40:10 | MISCOMPARE -Golden vs +Results for test.log 2024/04/12 01:40:10 | @@ -284,0 +285,2 @@ 2024/04/12 01:40:10 | +ERROR : file1.txt: failed to check as hash is missing 2024/04/12 01:40:10 | +INFO : file1.txt: files not equal on recheck: file1.txt file1.txt 2024/04/12 01:40:10 ---------------------------------------------------------- bisync_test.go:539: TEST resolve FAILED - 1 MISCOMPARED FILES 2024/04/12 01:40:11 DEBUG : webdav root 'rclone-test-yojohit7gitoriv2napojuj0': Purge remote --- FAIL: TestBisyncRemoteRemote (61.38s) --- FAIL: TestBisyncRemoteRemote/ext_paths (33.89s) --- FAIL: TestBisyncRemoteRemote/resolve (27.31s) FAIL 2024/04/12 01:40:11 DEBUG : webdav root 'rclone-test-ruqogaw5cixetic2jeqoqaf0': Purge remote 2024/04/12 01:40:11 DEBUG : pacer: Reducing sleep to 1.125s 2024/04/12 01:40:11 purge failed: rmdir failed: File with name rclone-test-ruqogaw5cixetic2jeqoqaf0 could not be located: Sabre\DAV\Exception\NotFound: 404 Not Found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestWebdavOwncloud: -verbose -test.run '^TestBisyncRemoteRemote$/^(ext_paths|resolve)$'" - Finished ERROR in 1m6.768275936s (try 4/5): exit status 1: Failed [TestBisyncRemoteRemote/ext_paths TestBisyncRemoteRemote/resolve]