"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCompressSwift: -verbose -test.run '^TestBisyncRemoteRemote$/^(extended_char_paths|normalization)$'" - Starting (try 5/5) 2024/04/11 05:27:41 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-xanibov4miwinof0qiyuvim7" 2024/04/11 05:27:41 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 05:27:42 DEBUG : Creating backend with remote "/tmp/rclone3406683627" === RUN TestBisyncRemoteRemote 2024/04/11 05:27:42 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4" 2024/04/11 05:27:42 remote: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4 === RUN TestBisyncRemoteRemote/extended_char_paths 2024/04/11 05:27:42 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka" 2024/04/11 05:27:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/11 05:27:44 DEBUG : path1: Making directory 2024/04/11 05:27:44 INFO : Swift container rclone-test-wotazub0pakaqer4hucecom4 path bs-052742-ka: Container "rclone-test-wotazub0pakaqer4hucecom4" created 2024/04/11 05:27:44 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1" 2024/04/11 05:27:45 DEBUG : path2: Making directory 2024/04/11 05:27:45 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2" 2024/04/11 05:27:46 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_char_paths/initial" 2024/04/11 05:27:46 DEBUG : Creating backend with remote "/tmp/bs-052742-ka/initdir/test_extended_char_paths-fixukaz9" 2024/04/11 05:27:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/11 05:27:46 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/11 05:27:46 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : Local file system at /tmp/bs-052742-ka/initdir/test_extended_char_paths-fixukaz9: Waiting for checks to finish 2024/04/11 05:27:46 DEBUG : Local file system at /tmp/bs-052742-ka/initdir/test_extended_char_paths-fixukaz9: Waiting for transfers to finish 2024/04/11 05:27:46 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:46 DEBUG : file1.txt.yepukaj0.partial: renamed to: file1.txt 2024/04/11 05:27:46 INFO : file1.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:27:46 DEBUG : RCLONE_TEST.nazobex6.partial: renamed to: RCLONE_TEST 2024/04/11 05:27:46 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:27:46 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:46 DEBUG : Русский.txt.gelexec2.partial: renamed to: Русский.txt 2024/04/11 05:27:46 INFO : Русский.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:46 DEBUG : filename_contains_ࢺ_.txt.jewitow7.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/11 05:27:46 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:46 DEBUG : 測試_check file.juwafor5.partial: renamed to: 測試_check file 2024/04/11 05:27:46 INFO : 測試_check file: Copied (new) 2024/04/11 05:27:46 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:46 DEBUG : file_enconde_mañana_funcionará.txt.ninocef0.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/11 05:27:46 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.yulomat5.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/11 05:27:46 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:27:46 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:46 DEBUG : filename_contains_ě_.txt.devecil2.partial: renamed to: filename_contains_ě_.txt 2024/04/11 05:27:46 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.wacupij5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/11 05:27:46 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.xodeyic0.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/11 05:27:46 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:46 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/11 05:27:46 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_char_paths/modfiles" 2024/04/11 05:27:46 DEBUG : Creating backend with remote "/tmp/bs-052742-ka/datadir/test_extended_char_paths-cucisec1" 2024/04/11 05:27:46 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:46 DEBUG : Local file system at /tmp/bs-052742-ka/datadir/test_extended_char_paths-cucisec1: Waiting for checks to finish 2024/04/11 05:27:46 DEBUG : Local file system at /tmp/bs-052742-ka/datadir/test_extended_char_paths-cucisec1: Waiting for transfers to finish 2024/04/11 05:27:46 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/11 05:27:46 DEBUG : 測試_filtersfile.txt.hamituw6.partial: renamed to: 測試_filtersfile.txt 2024/04/11 05:27:46 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/11 05:27:46 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/11 05:27:46 DEBUG : file1.txt.hiqeguh9.partial: renamed to: file1.txt 2024/04/11 05:27:46 INFO : file1.txt: Copied (new) 2024/04/11 05:27:47 checking initFs Local file system at /tmp/bs-052742-ka/initdir/test_extended_char_paths-fixukaz9 2024/04/11 05:27:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/11 05:27:47 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:47 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1: Waiting for checks to finish 2024/04/11 05:27:47 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1: Waiting for transfers to finish 2024/04/11 05:27:47 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:27:47 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:27:48 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:48 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/11 05:27:48 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:48 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:48 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:48 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:48 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:48 INFO : file1.txt: Copied (new) 2024/04/11 05:27:48 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:48 INFO : Русский.txt: Copied (new) 2024/04/11 05:27:48 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:48 INFO : 測試_check file: Copied (new) 2024/04/11 05:27:49 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:49 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:50 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:50 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:50 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:50 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:27:50 checking Path1 Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1 2024/04/11 05:27:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/11 05:27:52 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:27:52 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2: Waiting for checks to finish 2024/04/11 05:27:52 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2: Waiting for transfers to finish 2024/04/11 05:27:53 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:27:53 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:27:53 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:53 INFO : file1.txt: Copied (new) 2024/04/11 05:27:53 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:53 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/11 05:27:53 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:53 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:53 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:53 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:54 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:54 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:54 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:27:54 INFO : Русский.txt: Copied (new) 2024/04/11 05:27:54 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:54 INFO : 測試_check file: Copied (new) 2024/04/11 05:27:54 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:27:54 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:27:54 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:27:54 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:27:54 checking path2 Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2 2024/04/11 05:27:56 (01) : test extended-char-paths 2024/04/11 05:27:56 (02) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/11 05:28:00 (03) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/11 05:28:04 (04) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/11 05:28:05 (05) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/11 05:28:05 (06) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/11 05:28:06 (07) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/11 05:28:06 (08) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1/測試_check{spc}file 2024/04/11 05:28:07 (09) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2/測試_check{spc}file 2024/04/11 05:28:08 (10) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/11 05:28:08 (11) : fix-names TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/extended_char_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/11 05:28:09 (12) : test resync subdirs with extended chars 2024/04/11 05:28:09 (13) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync 2024/04/11 05:28:13 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:20 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:20 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:28:20 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/11 05:28:20 ERROR : /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck: error reading lock file: open /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck: file name too long 2024/04/11 05:28:20 ERROR : /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck: err: invalid argument 2024/04/11 05:28:20 ERROR : /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck: error closing file: invalid argument 2024/04/11 05:28:20 INFO : /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck: Valid lock file found. Expires at 0001-01-01 00:00:00 +0000 UTC. (2562047h47m16.854775807s from now) 2024/04/11 05:28:20 INFO : Lockfile info: { "Session": "", "PID": "", "TimeRenewed": "0001-01-01T00:00:00Z", "TimeExpires": "0001-01-01T00:00:00Z" } 2024/04/11 05:28:20 Bisync error: prior lock file found: /tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck Tip: this indicates that another bisync run (of these same paths) either is still running or was interrupted before completion. If you're SURE you want to override this safety feature, you can delete the lock file with the following command, then run bisync again: rclone deletefile "/tmp/bs-052742-ka/workdir/TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path1_測試_Русский_____ě_áñ..TestCompressSwift_rclone-test-wotazub0pakaqer4hucecom4_bs-052742-ka_extended_char_paths_path2_測試_Русский_____ě_áñ.lck" 2024/04/11 05:28:20 (14) : copy-listings resync bisync_test.go:474: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:474 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:344 Error: test step failed Test: TestBisyncRemoteRemote/extended_char_paths Messages: step 14 failed: listings not found === RUN TestBisyncRemoteRemote/normalization 2024/04/11 05:28:20 DEBUG : path1: Making directory 2024/04/11 05:28:20 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1" 2024/04/11 05:28:22 DEBUG : path2: Making directory 2024/04/11 05:28:22 DEBUG : Creating backend with remote "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2" 2024/04/11 05:28:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/initial" 2024/04/11 05:28:23 DEBUG : Creating backend with remote "/tmp/bs-052742-ka/initdir/test_normalization-jexotaz5" 2024/04/11 05:28:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/11 05:28:23 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z) 2024/04/11 05:28:23 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : Local file system at /tmp/bs-052742-ka/initdir/test_normalization-jexotaz5: Waiting for checks to finish 2024/04/11 05:28:23 DEBUG : Local file system at /tmp/bs-052742-ka/initdir/test_normalization-jexotaz5: Waiting for transfers to finish 2024/04/11 05:28:23 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:28:23 DEBUG : file1.txt.desuzey6.partial: renamed to: file1.txt 2024/04/11 05:28:23 INFO : file1.txt: Copied (new) 2024/04/11 05:28:23 DEBUG : RCLONE_TEST.gajurir2.partial: renamed to: RCLONE_TEST 2024/04/11 05:28:23 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.xigofoz4.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:23 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.yamorub4.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/11 05:28:23 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.litahuh2.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/11 05:28:23 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:28:23 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/11 05:28:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_normalization/modfiles" 2024/04/11 05:28:23 DEBUG : Creating backend with remote "/tmp/bs-052742-ka/datadir/test_normalization-lonaxak6" 2024/04/11 05:28:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : Local file system at /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6: Waiting for checks to finish 2024/04/11 05:28:23 DEBUG : Local file system at /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6: Waiting for transfers to finish 2024/04/11 05:28:23 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/11 05:28:23 DEBUG : file1.txt.xoqovov5.partial: renamed to: file1.txt 2024/04/11 05:28:23 INFO : file1.txt: Copied (new) 2024/04/11 05:28:23 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/11 05:28:23 DEBUG : 測試_filtersfile.txt.heyevax4.partial: renamed to: 測試_filtersfile.txt 2024/04/11 05:28:23 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/11 05:28:23 checking initFs Local file system at /tmp/bs-052742-ka/initdir/test_normalization-jexotaz5 2024/04/11 05:28:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:28:23 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1: Waiting for checks to finish 2024/04/11 05:28:23 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1: Waiting for transfers to finish 2024/04/11 05:28:24 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:28:24 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:28:25 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:25 INFO : file1.txt: Copied (new) 2024/04/11 05:28:25 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:25 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:28:25 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:25 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:28:25 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:25 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:28:25 checking Path1 Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1 2024/04/11 05:28:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/11 05:28:26 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:26 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/11 05:28:26 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:26 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/11 05:28:26 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/11 05:28:26 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: Waiting for checks to finish 2024/04/11 05:28:26 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: Waiting for transfers to finish 2024/04/11 05:28:27 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/11 05:28:27 INFO : RCLONE_TEST: Copied (new) 2024/04/11 05:28:27 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:27 INFO : file1.txt: Copied (new) 2024/04/11 05:28:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:27 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/11 05:28:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/11 05:28:27 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/11 05:28:28 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/11 05:28:28 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/11 05:28:28 checking path2 Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2 2024/04/11 05:28:28 (01) : test normalization 2024/04/11 05:28:28 (02) : touch-copy 2001-01-02 /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:28:30 INFO : file1.txt: Copied (replaced existing) 2024/04/11 05:28:30 (03) : test initial bisync 2024/04/11 05:28:30 (04) : bisync resync 2024/04/11 05:28:34 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:36 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:28:37 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/11 05:28:37 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:28:37 INFO : Copying Path2 files to Path1 2024/04/11 05:28:37 INFO : - Path2 Resync is copying files to - Path1 2024/04/11 05:28:37 INFO : - Path1 Resync is copying files to - Path2 2024/04/11 05:28:39 INFO : file1.txt: Copied (server-side copy) 2024/04/11 05:28:39 INFO : Resync updating listings 2024/04/11 05:28:39 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" vs Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:28:39 INFO : Bisync successful 2024/04/11 05:28:39 (05) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2024/04/11 05:28:41 INFO : file1.txt: Copied (new) to: 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2024/04/11 05:28:41 (06) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/folder éééö.txt 2024/04/11 05:28:43 INFO : file1.txt: Copied (new) to: éééö.txt 2024/04/11 05:28:43 (07) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/folder HeLlO,wOrLd!.txt 2024/04/11 05:28:44 INFO : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt 2024/04/11 05:28:44 (08) : touch-copy 2001-01-03 /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:28:46 INFO : file1.txt: Copied (replaced existing) 2024/04/11 05:28:46 (09) : copy-as-NFD /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/folder éééö.txt 2024/04/11 05:28:48 INFO : file1.txt: Copied (new) to: éééö.txt 2024/04/11 05:28:48 (10) : copy-as-NFD /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/folder hello,WORLD!.txt 2024/04/11 05:28:50 INFO : file1.txt: Copied (new) to: hello,WORLD!.txt 2024/04/11 05:28:50 (11) : test bisync run with fix-case 2024/04/11 05:28:50 (12) : bisync fix-case 2024/04/11 05:28:53 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:54 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:28:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:28:55 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/11 05:28:55 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:28:55 INFO : Building Path1 and Path2 listings 2024/04/11 05:28:55 INFO : Path1 checking for diffs 2024/04/11 05:28:55 INFO : - Path1 File is new - folder/HeLlO,wOrLd!.txt 2024/04/11 05:28:55 INFO : - Path1 File is new - folder/éééö.txt 2024/04/11 05:28:55 INFO : - Path1 File is new - "測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:28:55 INFO : Path1: 3 changes:  3 new,  0 modified,  0 deleted 2024/04/11 05:28:55 INFO : Path2 checking for diffs 2024/04/11 05:28:55 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2024/04/11 05:28:55 INFO : - Path2 File is new - folder/éééö.txt 2024/04/11 05:28:55 INFO : - Path2 File is new - folder/hello,WORLD!.txt 2024/04/11 05:28:55 INFO : Path2: 3 changes:  2 new,  1 modified,  0 deleted 2024/04/11 05:28:55 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/04/11 05:28:55 INFO : Applying changes 2024/04/11 05:28:55 INFO : Checking potential conflicts... 2024/04/11 05:28:56 NOTICE: Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: 0 differences found 2024/04/11 05:28:56 NOTICE: Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: 2 matching files 2024/04/11 05:28:56 INFO : Finished checking the potential conflicts. %!s() 2024/04/11 05:28:56 NOTICE: - WARNING New or changed in both paths - folder/HeLlO,wOrLd!.txt 2024/04/11 05:28:56 INFO : folder/hello,WORLD!.txt: Files are equal but will copy anyway to fix case to folder/HeLlO,wOrLd!.txt 2024/04/11 05:28:56 NOTICE: - WARNING New or changed in both paths - folder/éééö.txt 2024/04/11 05:28:56 INFO : folder/éééö.txt: Files are equal but will copy anyway to fix case to folder/éééö.txt 2024/04/11 05:28:56 INFO : - Path1 Queue copy to Path2 - "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:28:56 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/file1.txt 2024/04/11 05:28:56 INFO : - Path2 Do queued copies to - Path1 2024/04/11 05:28:57 INFO : file1.txt: Copied (server-side copy) 2024/04/11 05:28:57 INFO : - Path1 Do queued copies to - Path2 2024/04/11 05:28:58 INFO : folder/HeLlO,wOrLd!.txt: Updated modification time in destination 2024/04/11 05:28:58 INFO : folder/éééö.txt: Updated modification time in destination 2024/04/11 05:28:58 ERROR : folder/éééö.txt: Failed to copy: Object Not Found 2024/04/11 05:28:58 ERROR : folder/éééö.txt: Not deleting source as copy failed: Object Not Found 2024/04/11 05:28:58 ERROR : folder/éééö.txt: Error while attempting to rename to folder/éééö.txt: Object Not Found 2024/04/11 05:28:58 ERROR : folder/hello,WORLD!.txt: Failed to copy: Object Not Found 2024/04/11 05:28:58 ERROR : folder/hello,WORLD!.txt: Not deleting source as copy failed: Object Not Found 2024/04/11 05:28:58 ERROR : folder/hello,WORLD!.txt: Error while attempting to rename to folder/HeLlO,wOrLd!.txt: Object Not Found 2024/04/11 05:28:58 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy) 2024/04/11 05:28:58 ERROR : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: not deleting files as there were IO errors 2024/04/11 05:28:58 ERROR : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: not deleting directories as there were IO errors 2024/04/11 05:28:58 ERROR : Bisync critical error: Object Not Found 2024/04/11 05:28:58 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/11 05:28:58 Bisync error: bisync aborted 2024/04/11 05:28:58 (13) : purge-children TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/ 2024/04/11 05:28:59 INFO : folder/HeLlO,wOrLd!.txt.bin: Deleted 2024/04/11 05:28:59 INFO : folder/éééö.txt.bin: Deleted 2024/04/11 05:28:59 INFO : folder/éééö.txt.json: Deleted 2024/04/11 05:28:59 INFO : folder/HeLlO,wOrLd!.txt.json: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/測試_check file.json: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/測試_check file.bin: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt.bin: Deleted 2024/04/11 05:29:00 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt.json: Deleted 2024/04/11 05:29:00 (14) : purge-children TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/測試_check file.bin: Deleted 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:01 INFO : 測試_Русский_ _ _ě_áñ/測試_check file.json: Deleted 2024/04/11 05:29:02 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt.bin: Deleted 2024/04/11 05:29:02 INFO : 測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Русский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt.json: Deleted 2024/04/11 05:29:02 (15) : touch-copy 2001-01-02 /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:29:03 INFO : file1.txt: Copied (new) 2024/04/11 05:29:03 (16) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ file2.txt 2024/04/11 05:29:05 INFO : file1.txt: Copied (new) to: file2.txt 2024/04/11 05:29:05 (17) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/ file3.txt 2024/04/11 05:29:07 INFO : file1.txt: Copied (new) to: file3.txt 2024/04/11 05:29:07 (18) : bisync resync 2024/04/11 05:29:11 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:13 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:29:13 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/11 05:29:13 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:13 INFO : Copying Path2 files to Path1 2024/04/11 05:29:13 INFO : - Path2 Resync is copying files to - Path1 2024/04/11 05:29:14 INFO : file1.txt: Copied (server-side copy) 2024/04/11 05:29:15 INFO : file2.txt: Copied (server-side copy) 2024/04/11 05:29:15 INFO : - Path1 Resync is copying files to - Path2 2024/04/11 05:29:16 INFO : file3.txt: Copied (server-side copy) 2024/04/11 05:29:16 INFO : Resync updating listings 2024/04/11 05:29:16 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" vs Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:16 INFO : Bisync successful 2024/04/11 05:29:16 (19) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2024/04/11 05:29:18 INFO : file1.txt: Copied (new) to: 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2024/04/11 05:29:18 (20) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder éééö.txt 2024/04/11 05:29:20 INFO : file1.txt: Copied (new) to: éééö.txt 2024/04/11 05:29:20 (21) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder HeLlO,wOrLd!.txt 2024/04/11 05:29:22 INFO : file1.txt: Copied (new) to: HeLlO,wOrLd!.txt 2024/04/11 05:29:22 (22) : touch-copy 2001-01-03 /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:29:23 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:23 (23) : copy-as-NFD /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/newfolder éééö.txt 2024/04/11 05:29:26 INFO : file1.txt: Copied (new) to: éééö.txt 2024/04/11 05:29:26 (24) : copy-as-NFD /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/newfolder hello,WORLD!.txt 2024/04/11 05:29:28 INFO : file1.txt: Copied (new) to: hello,WORLD!.txt 2024/04/11 05:29:28 (25) : test bisync run with normalization 2024/04/11 05:29:28 (26) : bisync norm force 2024/04/11 05:29:30 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:32 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:32 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:29: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/11 05:29:32 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:32 INFO : Building Path1 and Path2 listings 2024/04/11 05:29:33 INFO : Path1 checking for diffs 2024/04/11 05:29:33 INFO : - Path1 File is new - newfolder/HeLlO,wOrLd!.txt 2024/04/11 05:29:33 INFO : - Path1 File is new - newfolder/éééö.txt 2024/04/11 05:29:33 INFO : - Path1 File is new - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:29:33 INFO : Path1: 3 changes:  3 new,  0 modified,  0 deleted 2024/04/11 05:29:33 INFO : Path2 checking for diffs 2024/04/11 05:29:33 INFO : - Path2 File changed: time (newer) - file1.txt 2024/04/11 05:29:33 INFO : - Path2 File is new - newfolder/éééö.txt 2024/04/11 05:29:33 INFO : - Path2 File is new - newfolder/hello,WORLD!.txt 2024/04/11 05:29:33 INFO : Path2: 3 changes:  2 new,  1 modified,  0 deleted 2024/04/11 05:29:33 INFO : (Modified:  1 newer,  0 older) 2024/04/11 05:29:33 INFO : Applying changes 2024/04/11 05:29:33 INFO : Checking potential conflicts... 2024/04/11 05:29:33 NOTICE: Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: 0 differences found 2024/04/11 05:29:33 NOTICE: Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2: 2 matching files 2024/04/11 05:29:33 INFO : Finished checking the potential conflicts. %!s() 2024/04/11 05:29:33 NOTICE: - WARNING New or changed in both paths - newfolder/HeLlO,wOrLd!.txt 2024/04/11 05:29:33 INFO : newfolder/HeLlO,wOrLd!.txt: Files are equal but will copy anyway to update modtime (will not rename) 2024/04/11 05:29:33 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder/HeLlO,wOrLd!.txt 2024/04/11 05:29:33 NOTICE: - WARNING New or changed in both paths - newfolder/éééö.txt 2024/04/11 05:29:33 INFO : newfolder/éééö.txt: Files are equal but will copy anyway to update modtime (will not rename) 2024/04/11 05:29:33 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder/éééö.txt 2024/04/11 05:29:33 INFO : - Path1 Queue copy to Path2 - "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:29:33 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/file1.txt 2024/04/11 05:29:33 INFO : - Path2 Do queued copies to - Path1 2024/04/11 05:29:33 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:34 INFO : newfolder/hello,WORLD!.txt: Updated modification time in destination 2024/04/11 05:29:34 INFO : newfolder/éééö.txt: Updated modification time in destination 2024/04/11 05:29:34 INFO : - Path1 Do queued copies to - Path2 2024/04/11 05:29:35 INFO : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Copied (server-side copy) 2024/04/11 05:29:35 INFO : Updating listings 2024/04/11 05:29:35 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" vs Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:35 INFO : Bisync successful 2024/04/11 05:29:35 (27) : test resync 2024/04/11 05:29:35 (28) : bisync resync norm 2024/04/11 05:29:38 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:40 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:40 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:29: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/11 05:29:40 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:40 INFO : Copying Path2 files to Path1 2024/04/11 05:29:40 INFO : - Path2 Resync is copying files to - Path1 2024/04/11 05:29:41 INFO : - Path1 Resync is copying files to - Path2 2024/04/11 05:29:41 INFO : Resync updating listings 2024/04/11 05:29:42 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" vs Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:42 INFO : Bisync successful 2024/04/11 05:29:42 (29) : test changed on one path 2024/04/11 05:29:42 (30) : touch-copy 2001-01-05 /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/ 2024/04/11 05:29:43 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:43 (31) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt 2024/04/11 05:29:44 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:44 (32) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder éééö.txt 2024/04/11 05:29:45 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:45 (33) : copy-as-NFC /tmp/bs-052742-ka/datadir/test_normalization-lonaxak6/file1.txt TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/newfolder HeLlO,wOrLd!.txt 2024/04/11 05:29:47 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:47 (34) : bisync norm 2024/04/11 05:29:50 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:51 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/11 05:29:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/11 05:29: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/11 05:29:52 INFO : Synching Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" with Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:52 INFO : Building Path1 and Path2 listings 2024/04/11 05:29:53 INFO : Path1 checking for diffs 2024/04/11 05:29:53 INFO : - Path1 File changed: time (newer) - newfolder/HeLlO,wOrLd!.txt 2024/04/11 05:29:53 INFO : - Path1 File changed: time (newer) - newfolder/éééö.txt 2024/04/11 05:29:53 INFO : - Path1 File changed: time (newer) - "測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:29:53 INFO : Path1: 3 changes:  0 new,  3 modified,  0 deleted 2024/04/11 05:29:53 INFO : (Modified:  3 newer,  0 older) 2024/04/11 05:29:53 INFO : Path2 checking for diffs 2024/04/11 05:29:53 INFO : - Path2 File changed: time (newer) - file1.txt 2024/04/11 05:29:53 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2024/04/11 05:29:53 INFO : (Modified:  1 newer,  0 older) 2024/04/11 05:29:53 INFO : Applying changes 2024/04/11 05:29:53 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/newfolder/hello,WORLD!.txt 2024/04/11 05:29:53 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/newfolder/éééö.txt 2024/04/11 05:29:53 INFO : - Path1 Queue copy to Path2 - "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö.txt" 2024/04/11 05:29:53 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/file1.txt 2024/04/11 05:29:53 INFO : - Path2 Do queued copies to - Path1 2024/04/11 05:29:53 INFO : file1.txt: Updated modification time in destination 2024/04/11 05:29:53 INFO : - Path1 Do queued copies to - Path2 2024/04/11 05:29:54 INFO : newfolder/éééö.txt: Updated modification time in destination 2024/04/11 05:29:54 INFO : newfolder/HeLlO,wOrLd!.txt: Updated modification time in destination 2024/04/11 05:29:54 INFO : 測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö/測試_Руский___ě_áñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö.txt: Updated modification time in destination 2024/04/11 05:29:54 INFO : Updating listings 2024/04/11 05:29:54 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path1/" vs Path2 "TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4/bs-052742-ka/normalization/path2/" 2024/04/11 05:29:54 INFO : Bisync successful 2024/04/11 05:29:54 ---------------------------------------------------------- 2024/04/11 05:29:54 MISCOMPARE - Number of Golden and Results files do not match: 2024/04/11 05:29:54 Golden count: 9 2024/04/11 05:29:54 Result count: 11 2024/04/11 05:29:54 Golden files: _testdir_path1.._testdir_path2.copy1to2.que, _testdir_path1.._testdir_path2.copy2to1.que, _testdir_path1.._testdir_path2.path1.lst, _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path1.lst-old, _testdir_path1.._testdir_path2.path2.lst, _testdir_path1.._testdir_path2.path2.lst-new, _testdir_path1.._testdir_path2.path2.lst-old, test.log 2024/04/11 05:29:54 Result files: _testdir_path1.._testdir_path2.copy1to2.que, _testdir_path1.._testdir_path2.copy2to1.que, _testdir_path1.._testdir_path2.path1.lst, _testdir_path1.._testdir_path2.path1.lst-err, _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path1.lst-old, _testdir_path1.._testdir_path2.path2.lst, _testdir_path1.._testdir_path2.path2.lst-err, _testdir_path1.._testdir_path2.path2.lst-new, _testdir_path1.._testdir_path2.path2.lst-old, test.log 2024/04/11 05:29:54 File found in Results but not in Golden: _testdir_path1.._testdir_path2.path1.lst-err 2024/04/11 05:29:54 File found in Results but not in Golden: _testdir_path1.._testdir_path2.path2.lst-err 2024/04/11 05:29:54 ---------------------------------------------------------- 2024/04/11 05:29:54 | MISCOMPARE -Golden vs +Results for test.log 2024/04/11 05:29:54 | @@ -66,5 +66,11 @@ 2024/04/11 05:29:54 | -INFO : folder/hello,WORLD!.txt: Fixed case by renaming to: folder/HeLlO,wOrLd!.txt 2024/04/11 05:29:54 | -INFO : folder/éééö.txt: Fixed case by renaming to: folder/éééö.txt 2024/04/11 05:29:54 | -INFO : Updating listings 2024/04/11 05:29:54 | -INFO : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}" 2024/04/11 05:29:54 | -INFO : Bisync successful 2024/04/11 05:29:54 | +ERROR : folder/éééö.txt: Failed to copy: Object Not Found 2024/04/11 05:29:54 | +ERROR : folder/éééö.txt: Not deleting source as copy failed: Object Not Found 2024/04/11 05:29:54 | +ERROR : folder/éééö.txt: Error while attempting to rename to folder/éééö.txt: Object Not Found 2024/04/11 05:29:54 | +ERROR : folder/hello,WORLD!.txt: Failed to copy: Object Not Found 2024/04/11 05:29:54 | +ERROR : folder/hello,WORLD!.txt: Not deleting source as copy failed: Object Not Found 2024/04/11 05:29:54 | +ERROR : folder/hello,WORLD!.txt: Error while attempting to rename to folder/HeLlO,wOrLd!.txt: Object Not Found 2024/04/11 05:29:54 | +ERROR : {path2String}: not deleting files as there were IO errors 2024/04/11 05:29:54 | +ERROR : {path2String}: not deleting directories as there were IO errors 2024/04/11 05:29:54 | +ERROR : Bisync critical error: Object Not Found 2024/04/11 05:29:54 | +ERROR : Bisync aborted. Must run --resync to recover. 2024/04/11 05:29:54 | +Bisync error: bisync aborted 2024/04/11 05:29:54 ---------------------------------------------------------- bisync_test.go:539: TEST normalization FAILED - 3 MISCOMPARED FILES 2024/04/11 05:29:56 DEBUG : Compressed: TestCompressSwift:rclone-test-wotazub0pakaqer4hucecom4: Purge remote 2024/04/11 05:29:56 DEBUG : Waiting for deletions to finish 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/file1.txt.json: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/RCLONE_TEST.json: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/RCLONE_TEST.bin: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/file1.txt.bin: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/file_enconde_mañana_funcionará.txt.json: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:56 INFO : bs-052742-ka/extended_char_paths/path1/Русский.txt.bin: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/file_enconde_mañana_funcionará.txt.bin: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_check file.bin: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/Русский.txt.json: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_check file.json: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:29:57 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file.bin: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/RCLONE_TEST.bin: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file.json: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/file1.txt.bin: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/RCLONE_TEST.json: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/file1.txt.json: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/file_enconde_mañana_funcionará.txt.bin: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:58 INFO : bs-052742-ka/extended_char_paths/path2/file_enconde_mañana_funcionará.txt.json: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/Русский.txt.bin: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/Русский.txt.json: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_check file.json: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.json: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file.bin: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.EAEAAAAAAAA.gz: Deleted 2024/04/11 05:29:59 INFO : bs-052742-ka/extended_char_paths/path2/測試_check file.bin: Deleted 2024/04/11 05:30:00 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file.json: Deleted 2024/04/11 05:30:00 INFO : bs-052742-ka/extended_char_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.json: Deleted 2024/04/11 05:30:00 INFO : Swift container rclone-test-wotazub0pakaqer4hucecom4: Container "rclone-test-wotazub0pakaqer4hucecom4" removed --- FAIL: TestBisyncRemoteRemote (138.18s) --- FAIL: TestBisyncRemoteRemote/extended_char_paths (37.98s) --- FAIL: TestBisyncRemoteRemote/normalization (95.18s) FAIL 2024/04/11 05:30:00 DEBUG : Compressed: TestCompressSwift:rclone-test-xanibov4miwinof0qiyuvim7: Purge remote 2024/04/11 05:30:00 DEBUG : Waiting for deletions to finish 2024/04/11 05:30:00 purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestCompressSwift: -verbose -test.run '^TestBisyncRemoteRemote$/^(extended_char_paths|normalization)$'" - Finished ERROR in 2m19.051468755s (try 5/5): exit status 1: Failed [TestBisyncRemoteRemote/extended_char_paths TestBisyncRemoteRemote/normalization]