"./bisync.test -test.v -test.timeout 1h0m0s -remote 'TestGoogleCloudStorage,directory_markers:' -verbose -test.run '^(TestBisyncLocalRemote|TestBisyncRemoteRemote)$/^(ext_paths|extended_filenames|filtersfile_checks)$'" - Starting (try 5/5) 2024/04/13 02:34:23 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fuhunij5geheyed3quvizad1" 2024/04/13 02:34:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/13 02:34:23 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:34:23 DEBUG : Creating backend with remote "/tmp/rclone3175224850" === RUN TestBisyncLocalRemote 2024/04/13 02:34:23 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4" 2024/04/13 02:34:23 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:34:23 remote: TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4 === RUN TestBisyncLocalRemote/ext_paths 2024/04/13 02:34:23 DEBUG : Creating backend with remote "/tmp/bs-023423-vu" 2024/04/13 02:34:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/13 02:34:23 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/ext_paths/path1" 2024/04/13 02:34:23 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" 2024/04/13 02:34:23 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:34:24 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" 2024/04/13 02:34:24 DEBUG : path2: Making directory 2024/04/13 02:34:26 DEBUG : path2/: Creating directory marker 2024/04/13 02:34:26 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2" 2024/04/13 02:34:26 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:34:27 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2" 2024/04/13 02:34:27 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/initial" 2024/04/13 02:34:27 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/initdir/test_ext_paths-xexaqiq4" 2024/04/13 02:34:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/13 02:34:27 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z) 2024/04/13 02:34:27 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:27 DEBUG : file1.txt.hutogin4.partial: renamed to: file1.txt 2024/04/13 02:34:27 INFO : file1.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:27 DEBUG : file_enconde_mañana_funcionará.txt.karubif5.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:34:27 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_ext_paths-xexaqiq4: Waiting for checks to finish 2024/04/13 02:34:27 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_ext_paths-xexaqiq4: Waiting for transfers to finish 2024/04/13 02:34:27 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:34:27 DEBUG : RCLONE_TEST.qataquc9.partial: renamed to: RCLONE_TEST 2024/04/13 02:34:27 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:34:27 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:27 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:27 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:27 DEBUG : filename_contains_ě_.txt.pujatey5.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:34:27 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : Русский.txt.cuzebos0.partial: renamed to: Русский.txt 2024/04/13 02:34:27 INFO : Русский.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:27 DEBUG : filename_contains_ࢺ_.txt.wumizef2.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:34:27 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_check file.pacugoz0.partial: renamed to: 測試_check file 2024/04/13 02:34:27 INFO : 測試_check file: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.jovexus9.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/13 02:34:27 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.duxawix8.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/13 02:34:27 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.sisuhor6.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/13 02:34:27 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:27 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:27 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/modfiles" 2024/04/13 02:34:27 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7" 2024/04/13 02:34:27 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:27 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7: Waiting for checks to finish 2024/04/13 02:34:27 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7: Waiting for transfers to finish 2024/04/13 02:34:27 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/13 02:34:27 DEBUG : file1.txt.pijijez5.partial: renamed to: file1.txt 2024/04/13 02:34:27 INFO : file1.txt: Copied (new) 2024/04/13 02:34:27 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/13 02:34:27 DEBUG : 測試_filtersfile.txt.silogik1.partial: renamed to: 測試_filtersfile.txt 2024/04/13 02:34:27 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/13 02:34:28 ERROR : : error listing: directory not found 2024/04/13 02:34:28 checking initFs Local file system at /tmp/bs-023423-vu/initdir/test_ext_paths-xexaqiq4 2024/04/13 02:34:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/13 02:34:28 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z) 2024/04/13 02:34:28 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:28 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:28 DEBUG : file1.txt.vilubov4.partial: renamed to: file1.txt 2024/04/13 02:34:28 INFO : file1.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : Local file system at /tmp/bs-023423-vu/ext_paths/path1: Waiting for checks to finish 2024/04/13 02:34:28 DEBUG : Local file system at /tmp/bs-023423-vu/ext_paths/path1: Waiting for transfers to finish 2024/04/13 02:34:28 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:34:28 DEBUG : RCLONE_TEST.kakemoy1.partial: renamed to: RCLONE_TEST 2024/04/13 02:34:28 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:34:28 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:28 DEBUG : filename_contains_ě_.txt.wakicar5.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:34:28 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:28 DEBUG : Русский.txt.xobokab4.partial: renamed to: Русский.txt 2024/04/13 02:34:28 INFO : Русский.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:28 DEBUG : 測試_check file.sirelat6.partial: renamed to: 測試_check file 2024/04/13 02:34:28 INFO : 測試_check file: Copied (new) 2024/04/13 02:34:28 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:28 DEBUG : filename_contains_ࢺ_.txt.yicikem0.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:34:28 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:28 DEBUG : file_enconde_mañana_funcionará.txt.fubozeg4.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:34:28 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.yatiper8.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/13 02:34:28 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.bisuper3.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/13 02:34:28 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:28 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.qowepol1.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/13 02:34:28 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:28 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:28 checking Path1 Local file system at /tmp/bs-023423-vu/ext_paths/path1 2024/04/13 02:34:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:29 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/13 02:34:30 DEBUG : 測試_Русский_ _ _ě_áñ/: Creating directory marker 2024/04/13 02:34:30 DEBUG : /: Creating directory marker 2024/04/13 02:34:30 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:30 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:30 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:34:30 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/ext_paths/path2: Waiting for checks to finish 2024/04/13 02:34:30 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/ext_paths/path2: Waiting for transfers to finish 2024/04/13 02:34:30 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:34:30 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:34:30 DEBUG : /: Creating directory marker 2024/04/13 02:34:30 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:30 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:31 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:31 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:31 INFO : file1.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:31 INFO : Русский.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:31 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:31 INFO : 測試_check file: Copied (new) 2024/04/13 02:34:31 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:31 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:31 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:31 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:34:31 checking path2 GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/ext_paths/path2 2024/04/13 02:34:32 (01) : test extended-char-paths 2024/04/13 02:34:32 (02) : fix-names /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/13 02:34:32 ERROR : Local file system at /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-pazejul5: Server side directory move failed: rename /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-pazejul5: no such file or directory 2024/04/13 02:34:32 INFO : Local file system at /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/13 02:34:32 ERROR : Local file system at /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-pazejul5: error reading source root directory: directory not found 2024/04/13 02:34:32 (03) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/13 02:34:33 ERROR : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ: error reading source root directory: directory not found 2024/04/13 02:34:34 ERROR : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-titibix8: error reading source root directory: directory not found 2024/04/13 02:34:34 (04) : fix-names /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:34:34 (05) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:34:35 (06) : fix-names /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/13 02:34:35 (07) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/13 02:34:37 (08) : fix-names /tmp/bs-023423-vu/ext_paths/path1/測試_check{spc}file 2024/04/13 02:34:37 (09) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_check{spc}file 2024/04/13 02:34:38 (10) : fix-names /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:34:38 (11) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:34:39 (12) : test resync subdirs with extended chars 2024/04/13 02:34:39 (13) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync 2024/04/13 02:34:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:41 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:41 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/13 02:34:41 INFO : Copying Path2 files to Path1 2024/04/13 02:34:41 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:34:41 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:34:41 INFO : Resync updating listings 2024/04/13 02:34:41 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/13 02:34:41 INFO : Bisync successful 2024/04/13 02:34:41 (14) : copy-listings resync 2024/04/13 02:34:41 (15) : test place new files with extended chars on each side 2024/04/13 02:34:41 (16) : touch-glob 2001-01-02 /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7/ file1.txt 2024/04/13 02:34:41 (17) : copy-as /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7/file1.txt /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p1 2024/04/13 02:34:41 INFO : file1.txt: Copied (new) to: 測試_file1p1 2024/04/13 02:34:41 (18) : copy-as /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p2 2024/04/13 02:34:42 INFO : file1.txt: Copied (new) to: 測試_file1p2 2024/04/13 02:34:42 (19) : test normal sync of subdirs with extended chars 2024/04/13 02:34:42 (20) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ 2024/04/13 02:34:44 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:44 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:44 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/13 02:34:44 INFO : Building Path1 and Path2 listings 2024/04/13 02:34:44 INFO : Path1 checking for diffs 2024/04/13 02:34:44 INFO : - Path1 File is new - 測試_file1p1 2024/04/13 02:34:44 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/13 02:34:44 INFO : Path2 checking for diffs 2024/04/13 02:34:44 INFO : - Path2 File is new - 測試_file1p2 2024/04/13 02:34:44 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/13 02:34:44 INFO : Applying changes 2024/04/13 02:34:44 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_file1p1 2024/04/13 02:34:44 INFO : - Path2 Queue copy to Path1 - /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_file1p2 2024/04/13 02:34:44 INFO : - Path2 Do queued copies to - Path1 2024/04/13 02:34:44 INFO : 測試_file1p2: Copied (new) 2024/04/13 02:34:44 INFO : - Path1 Do queued copies to - Path2 2024/04/13 02:34:45 INFO : 測試_file1p1: Copied (new) 2024/04/13 02:34:45 INFO : Updating listings 2024/04/13 02:34:45 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/13 02:34:45 INFO : Bisync successful 2024/04/13 02:34:45 (21) : move-listings normal-sync 2024/04/13 02:34:45 (22) : test check-filename with extended chars. check should fail. 2024/04/13 02:34:45 (23) : bisync resync 2024/04/13 02:34:46 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:46 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:46 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:46 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:46 INFO : Copying Path2 files to Path1 2024/04/13 02:34:46 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:34:47 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:34:47 INFO : Resync updating listings 2024/04/13 02:34:47 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:47 INFO : Bisync successful 2024/04/13 02:34:47 (24) : delete-file /tmp/bs-023423-vu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:34:47 INFO : 測試_check file: Deleted 2024/04/13 02:34:47 (25) : bisync check-access check-filename=測試_check{spc}file 2024/04/13 02:34:48 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:48 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:48 INFO : Building Path1 and Path2 listings 2024/04/13 02:34:49 INFO : Path1 checking for diffs 2024/04/13 02:34:49 INFO : - Path1 File was deleted - 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/13 02:34:49 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2024/04/13 02:34:49 INFO : Path2 checking for diffs 2024/04/13 02:34:49 INFO : Checking access health 2024/04/13 02:34:49 ERROR : Access test failed: Path1 count 1, Path2 count 2 - 測試_check file 2024/04/13 02:34:49 ERROR : -  Access test failed: Path2 file not found in Path1 - 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/13 02:34:49 ERROR : Bisync critical error: check file check failed 2024/04/13 02:34:49 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:34:49 Bisync error: bisync aborted 2024/04/13 02:34:49 (26) : copy-listings check-access-fail 2024/04/13 02:34:49 (27) : test check-filename with extended chars. check should pass. 2024/04/13 02:34:49 (28) : bisync resync 2024/04/13 02:34:50 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:50 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:50 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:50 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:50 INFO : Copying Path2 files to Path1 2024/04/13 02:34:50 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:34:50 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:34:50 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:50 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:34:51 INFO : Resync updating listings 2024/04/13 02:34:51 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:51 INFO : Bisync successful 2024/04/13 02:34:51 (29) : bisync check-access check-filename=測試_check{spc}file 2024/04/13 02:34:52 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:52 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:52 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:52 INFO : Building Path1 and Path2 listings 2024/04/13 02:34:52 INFO : Path1 checking for diffs 2024/04/13 02:34:52 INFO : Path2 checking for diffs 2024/04/13 02:34:52 INFO : Checking access health 2024/04/13 02:34:52 INFO : Found 2 matching "測試_check file" files on both paths 2024/04/13 02:34:52 INFO : No changes found 2024/04/13 02:34:52 INFO : Updating listings 2024/04/13 02:34:52 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:52 INFO : Bisync successful 2024/04/13 02:34:52 (30) : move-listings check-access-pass 2024/04/13 02:34:52 (31) : test filters-file path with extended chars - masks /fileZ.txt 2024/04/13 02:34:52 (32) : copy-file /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7/測試_filtersfile.txt /tmp/bs-023423-vu/workdir/ 2024/04/13 02:34:52 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/13 02:34:52 (33) : bisync filters-file=/tmp/bs-023423-vu/workdir/測試_filtersfile.txt resync 2024/04/13 02:34:53 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:53 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:53 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:53 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:53 INFO : Using filters file /tmp/bs-023423-vu/workdir/測試_filtersfile.txt 2024/04/13 02:34:53 INFO : Storing filters file hash to /tmp/bs-023423-vu/workdir/測試_filtersfile.txt.md5 2024/04/13 02:34:53 INFO : Copying Path2 files to Path1 2024/04/13 02:34:53 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:34:54 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:34:54 INFO : Resync updating listings 2024/04/13 02:34:54 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:54 INFO : Bisync successful 2024/04/13 02:34:54 (34) : copy-as /tmp/bs-023423-vu/datadir/test_ext_paths-boqamup7/file1.txt /tmp/bs-023423-vu/ext_paths/path1/ fileZ.txt 2024/04/13 02:34:54 INFO : file1.txt: Copied (new) to: fileZ.txt 2024/04/13 02:34:54 (35) : bisync filters-file=/tmp/bs-023423-vu/workdir/測試_filtersfile.txt 2024/04/13 02:34:55 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:34:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:34:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:34:55 INFO : Synching Path1 "/tmp/bs-023423-vu/ext_paths/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:55 INFO : Using filters file /tmp/bs-023423-vu/workdir/測試_filtersfile.txt 2024/04/13 02:34:55 INFO : Building Path1 and Path2 listings 2024/04/13 02:34:56 INFO : Path1 checking for diffs 2024/04/13 02:34:56 INFO : Path2 checking for diffs 2024/04/13 02:34:56 INFO : No changes found 2024/04/13 02:34:56 INFO : Updating listings 2024/04/13 02:34:56 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/ext_paths/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/path2/" 2024/04/13 02:34:56 INFO : Bisync successful bisync_test.go:539: TEST ext_paths PASSED === RUN TestBisyncLocalRemote/extended_filenames 2024/04/13 02:34:57 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/extended_filenames/path1" 2024/04/13 02:34:57 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" 2024/04/13 02:34:57 DEBUG : path2: Making directory 2024/04/13 02:34:57 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2" 2024/04/13 02:34:57 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:34:58 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2" 2024/04/13 02:34:58 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/initial" 2024/04/13 02:34:58 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/initdir/test_extended_filenames-neyiley3" 2024/04/13 02:34:58 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_: Making directory with metadata 2024/04/13 02:34:58 INFO : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:34:58 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_ 2024/04/13 02:34:58 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_extended_filenames-neyiley3: Waiting for checks to finish 2024/04/13 02:34:58 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_extended_filenames-neyiley3: Waiting for transfers to finish 2024/04/13 02:34:58 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:58 DEBUG : file1 - Copy (2).txt.wofirir1.partial: renamed to: file1 - Copy (2).txt 2024/04/13 02:34:58 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:34:58 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:58 DEBUG : file1.txt.gajevux2.partial: renamed to: file1.txt 2024/04/13 02:34:58 INFO : file1.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:34:58 DEBUG : RCLONE_TEST.jigubaf4.partial: renamed to: RCLONE_TEST 2024/04/13 02:34:58 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:34:58 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:58 DEBUG : file1 - Copy.txt.gitasag7.partial: renamed to: file1 - Copy.txt 2024/04/13 02:34:58 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:58 DEBUG : file_enconde_mañana_funcionará.txt.tawopex2.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:34:58 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:58 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:58 DEBUG : filename_contains_ě_.txt.ciguvip0.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:34:58 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : Русский.txt.bodeqij3.partial: renamed to: Русский.txt 2024/04/13 02:34:58 INFO : Русский.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:58 DEBUG : filename_contains_ࢺ_.txt.cesiwoc6.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:34:58 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.pepefeb7.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt 2024/04/13 02:34:58 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:58 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.riqeped8.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:34:58 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:58 INFO : subdir_with_ࢺ_: Set directory modification time (using SetModTime) 2024/04/13 02:34:58 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_extended_filenames/modfiles" 2024/04/13 02:34:58 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/datadir/test_extended_filenames-dusiloh1" 2024/04/13 02:34:58 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:58 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_extended_filenames-dusiloh1: Waiting for checks to finish 2024/04/13 02:34:58 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_extended_filenames-dusiloh1: Waiting for transfers to finish 2024/04/13 02:34:58 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/13 02:34:58 DEBUG : file1.txt.dezeduy9.partial: renamed to: file1.txt 2024/04/13 02:34:58 INFO : file1.txt: Copied (new) 2024/04/13 02:34:58 DEBUG : file2.txt: md5 = 40b811fb5009223b6da573f169619d8e OK 2024/04/13 02:34:58 DEBUG : file2.txt.gedizag0.partial: renamed to: file2.txt 2024/04/13 02:34:58 INFO : file2.txt: Copied (new) 2024/04/13 02:34:59 ERROR : : error listing: directory not found 2024/04/13 02:34:59 checking initFs Local file system at /tmp/bs-023423-vu/initdir/test_extended_filenames-neyiley3 2024/04/13 02:34:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_: Making directory with metadata 2024/04/13 02:34:59 INFO : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:34:59 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_ 2024/04/13 02:34:59 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:34:59 DEBUG : Local file system at /tmp/bs-023423-vu/extended_filenames/path1: Waiting for checks to finish 2024/04/13 02:34:59 DEBUG : Local file system at /tmp/bs-023423-vu/extended_filenames/path1: Waiting for transfers to finish 2024/04/13 02:34:59 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:34:59 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:59 DEBUG : RCLONE_TEST.bivapac2.partial: renamed to: RCLONE_TEST 2024/04/13 02:34:59 DEBUG : file1.txt.pemudaz9.partial: renamed to: file1.txt 2024/04/13 02:34:59 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:34:59 INFO : file1.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:59 DEBUG : file1 - Copy.txt.kitomer9.partial: renamed to: file1 - Copy.txt 2024/04/13 02:34:59 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:59 DEBUG : file_enconde_mañana_funcionará.txt.quponuk6.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:34:59 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:59 DEBUG : filename_contains_ࢺ_.txt.mubunoz0.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:34:59 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:59 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:59 DEBUG : Русский.txt.jefogon9.partial: renamed to: Русский.txt 2024/04/13 02:34:59 INFO : Русский.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.nitaciv2.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:34:59 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : filename_contains_ě_.txt.bemeneh8.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:34:59 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:34:59 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:34:59 DEBUG : file1 - Copy (2).txt.joneyiy7.partial: renamed to: file1 - Copy (2).txt 2024/04/13 02:34:59 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:34:59 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.kawokix1.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt 2024/04/13 02:34:59 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:34:59 INFO : subdir_with_ࢺ_: Set directory modification time (using SetModTime) 2024/04/13 02:34:59 checking Path1 Local file system at /tmp/bs-023423-vu/extended_filenames/path1 2024/04/13 02:35:00 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:00 DEBUG : subdir_with_ࢺ_: Making directory 2024/04/13 02:35:01 DEBUG : /: Creating directory marker 2024/04/13 02:35:01 DEBUG : subdir_with_ࢺ_/: Creating directory marker 2024/04/13 02:35:01 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:01 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:01 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:01 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/extended_filenames/path2: Waiting for checks to finish 2024/04/13 02:35:01 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/extended_filenames/path2: Waiting for transfers to finish 2024/04/13 02:35:01 DEBUG : /: Creating directory marker 2024/04/13 02:35:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:01 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:01 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:01 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:35:01 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:01 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:35:01 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:01 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:02 INFO : file1.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:02 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:02 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:02 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:02 INFO : Русский.txt: Copied (new) 2024/04/13 02:35:02 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:02 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:02 checking path2 GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/extended_filenames/path2 2024/04/13 02:35:02 (01) : test extended-filenames 2024/04/13 02:35:02 (02) : fix-names /tmp/bs-023423-vu/extended_filenames/path1/subdir_with_ࢺ_/ 2024/04/13 02:35:02 (03) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_/ 2024/04/13 02:35:02 (04) : fix-names /tmp/bs-023423-vu/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:35:02 (05) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:35:04 (06) : fix-names /tmp/bs-023423-vu/extended_filenames/path1/Русский.txt 2024/04/13 02:35:04 (07) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2/Русский.txt 2024/04/13 02:35:05 (08) : fix-names /tmp/bs-023423-vu/extended_filenames/path1/file_enconde_mañana_funcionará.txt 2024/04/13 02:35:05 (09) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/path2/file_enconde_mañana_funcionará.txt 2024/04/13 02:35:06 (10) : test initial bisync 2024/04/13 02:35:06 (11) : bisync resync 2024/04/13 02:35:08 NOTICE: Local file system at /tmp/bs-023423-vu/extended_filenames/path1: Replacing invalid UTF-8 characters in "ě\x81\xfe áñhࢺ_測試Рускийěáñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" 2024/04/13 02:35:08 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:35:08 NOTICE: Local file system at /tmp/bs-023423-vu/extended_filenames/path1: Replacing invalid UTF-8 characters in "ě\x81\xfe áñhࢺ_測試Рускийěáñ👸🏼🧝🏾\u200d♀️💆🏿\u200d♂️🐨🤙🏼🤮🧑🏻\u200d🔧🧑\u200d🔬éö" 2024/04/13 02:35:08 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:35:08 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:35:10 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) bisync_test.go:998: Fs is incapable of running test as the paths produce different results, skipping: extended_filenames (path1: file1 - Copy (2).txtfile1 - Copy (2).txt (BOTH) path2: subdir_with_ࢺ_subdir_with_ࢺ_ (subdir_with_ࢺ_subdir_with_ࢺ_)) (fs1: Local file system at /tmp/bs-023423-vu/extended_filenames/path1 fs2: GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/extended_filenames/path2) @@ -1 +1 @@ -file1 - Copy (2).txtfile1 - Copy (2).txt+subdir_with_ࢺ_subdir_with_ࢺ_ === RUN TestBisyncLocalRemote/filtersfile_checks 2024/04/13 02:35:10 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/filtersfile_checks/path1" 2024/04/13 02:35:10 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu" 2024/04/13 02:35:10 DEBUG : path2: Making directory 2024/04/13 02:35:10 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2" 2024/04/13 02:35:10 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:35:11 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2" 2024/04/13 02:35:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/initial" 2024/04/13 02:35:11 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/initdir/test_filtersfile_checks-getezeh2" 2024/04/13 02:35:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : subdir: Making directory with metadata 2024/04/13 02:35:11 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:35:11 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/04/13 02:35:11 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:11 DEBUG : file1.txt.dafokoj6.partial: renamed to: file1.txt 2024/04/13 02:35:11 INFO : file1.txt: Copied (new) 2024/04/13 02:35:11 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_filtersfile_checks-getezeh2: Waiting for checks to finish 2024/04/13 02:35:11 DEBUG : Local file system at /tmp/bs-023423-vu/initdir/test_filtersfile_checks-getezeh2: Waiting for transfers to finish 2024/04/13 02:35:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:11 DEBUG : file2.txt.riziquq9.partial: renamed to: file2.txt 2024/04/13 02:35:11 INFO : file2.txt: Copied (new) 2024/04/13 02:35:11 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:11 DEBUG : RCLONE_TEST.haqodiv1.partial: renamed to: RCLONE_TEST 2024/04/13 02:35:11 DEBUG : subdir/file20.txt.sadagil1.partial: renamed to: subdir/file20.txt 2024/04/13 02:35:11 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:11 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:35:11 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/modfiles" 2024/04/13 02:35:11 DEBUG : Creating backend with remote "/tmp/bs-023423-vu/datadir/test_filtersfile_checks-yefivom6" 2024/04/13 02:35:11 DEBUG : filtersfile.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : filtersfile2.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : hold.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:11 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_filtersfile_checks-yefivom6: Waiting for checks to finish 2024/04/13 02:35:11 DEBUG : Local file system at /tmp/bs-023423-vu/datadir/test_filtersfile_checks-yefivom6: Waiting for transfers to finish 2024/04/13 02:35:11 DEBUG : filtersfile2.txt: md5 = 95acc1e3b5beda465d9b2cb32808588c OK 2024/04/13 02:35:11 DEBUG : filtersfile.txt: md5 = cbf4389cb34d9e160689d1578eb5707f OK 2024/04/13 02:35:11 DEBUG : filtersfile2.txt.cizulat6.partial: renamed to: filtersfile2.txt 2024/04/13 02:35:11 INFO : filtersfile2.txt: Copied (new) 2024/04/13 02:35:11 DEBUG : filtersfile.txt.pavenon6.partial: renamed to: filtersfile.txt 2024/04/13 02:35:11 INFO : filtersfile.txt: Copied (new) 2024/04/13 02:35:11 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2024/04/13 02:35:11 DEBUG : hold.txt.taxebep2.partial: renamed to: hold.txt 2024/04/13 02:35:11 INFO : hold.txt: Copied (new) 2024/04/13 02:35:12 ERROR : : error listing: directory not found 2024/04/13 02:35:12 checking initFs Local file system at /tmp/bs-023423-vu/initdir/test_filtersfile_checks-getezeh2 2024/04/13 02:35:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:12 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:12 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:12 DEBUG : subdir: Making directory with metadata 2024/04/13 02:35:12 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:35:12 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/04/13 02:35:12 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:12 DEBUG : file1.txt.fiyipal3.partial: renamed to: file1.txt 2024/04/13 02:35:12 INFO : file1.txt: Copied (new) 2024/04/13 02:35:12 DEBUG : Local file system at /tmp/bs-023423-vu/filtersfile_checks/path1: Waiting for checks to finish 2024/04/13 02:35:12 DEBUG : Local file system at /tmp/bs-023423-vu/filtersfile_checks/path1: Waiting for transfers to finish 2024/04/13 02:35:12 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:12 DEBUG : subdir/file20.txt.fafecep3.partial: renamed to: subdir/file20.txt 2024/04/13 02:35:12 DEBUG : file2.txt.girepej4.partial: renamed to: file2.txt 2024/04/13 02:35:12 INFO : file2.txt: Copied (new) 2024/04/13 02:35:12 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:35:12 DEBUG : RCLONE_TEST.sadeyeg9.partial: renamed to: RCLONE_TEST 2024/04/13 02:35:12 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:12 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:12 checking Path1 Local file system at /tmp/bs-023423-vu/filtersfile_checks/path1 2024/04/13 02:35:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:13 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:13 DEBUG : subdir: Making directory 2024/04/13 02:35:14 DEBUG : subdir/: Creating directory marker 2024/04/13 02:35:14 DEBUG : /: Creating directory marker 2024/04/13 02:35:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:14 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/filtersfile_checks/path2: Waiting for checks to finish 2024/04/13 02:35:14 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/filtersfile_checks/path2: Waiting for transfers to finish 2024/04/13 02:35:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:14 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:14 DEBUG : /: Creating directory marker 2024/04/13 02:35:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:14 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:35:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:14 INFO : file1.txt: Copied (new) 2024/04/13 02:35:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:14 INFO : file2.txt: Copied (new) 2024/04/13 02:35:14 checking path2 GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4 path bs-023423-vu/filtersfile_checks/path2 2024/04/13 02:35:15 (01) : test filtersfile-checks 2024/04/13 02:35:15 (02) : test initial bisync 2024/04/13 02:35:15 (03) : bisync resync 2024/04/13 02:35:16 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:16 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:16 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:16 INFO : Copying Path2 files to Path1 2024/04/13 02:35:16 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:35:16 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:35:16 INFO : Resync updating listings 2024/04/13 02:35:16 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:16 INFO : Bisync successful 2024/04/13 02:35:16 (04) : test 1. inject filters file in workdir. 2024/04/13 02:35:16 (05) : copy-file /tmp/bs-023423-vu/datadir/test_filtersfile_checks-yefivom6/filtersfile.txt /tmp/bs-023423-vu/workdir/ 2024/04/13 02:35:16 INFO : filtersfile.txt: Copied (new) 2024/04/13 02:35:16 (06) : test 2. run with filters-file but without md5. should abort. 2024/04/13 02:35:16 (07) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:17 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:17 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:17 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:17 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:17 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:17 ERROR : Bisync critical error: filters file md5 hash not found (must run --resync): /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:17 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:35:17 Bisync error: bisync aborted 2024/04/13 02:35:17 (08) : test 3. run without filters-file. should be blocked due to prior abort. 2024/04/13 02:35:17 (09) : bisync 2024/04/13 02:35:19 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:19 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:19 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:19 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:19 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run Tip: here are the filenames we were looking for. Do they exist? Path1: /tmp/bs-023423-vu/workdir/tmp_bs-023423-vu_filtersfile_checks_path1..TestGoogleCloudStorage_rclone-test-fimuxey6fipemig5xuhujuh4_bs-023423-vu_filtersfile_checks_path2.path1.lst Path2: /tmp/bs-023423-vu/workdir/tmp_bs-023423-vu_filtersfile_checks_path1..TestGoogleCloudStorage_rclone-test-fimuxey6fipemig5xuhujuh4_bs-023423-vu_filtersfile_checks_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/bs-023423-vu/workdir" 2024/04/13 02:35:19 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:35:19 Bisync error: bisync aborted 2024/04/13 02:35:19 (10) : test 4. run with filters-file and resync. 2024/04/13 02:35:19 (11) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt resync 2024/04/13 02:35:20 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:20 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:20 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:20 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:20 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:20 INFO : Storing filters file hash to /tmp/bs-023423-vu/workdir/filtersfile.txt.md5 2024/04/13 02:35:20 INFO : Copying Path2 files to Path1 2024/04/13 02:35:20 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:35:20 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:35:20 INFO : Resync updating listings 2024/04/13 02:35:20 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:20 INFO : Bisync successful 2024/04/13 02:35:20 (12) : test 5. run with filters-file alone. should run. 2024/04/13 02:35:20 (13) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:21 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:21 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:21 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:21 INFO : Building Path1 and Path2 listings 2024/04/13 02:35:22 INFO : Path1 checking for diffs 2024/04/13 02:35:22 INFO : Path2 checking for diffs 2024/04/13 02:35:22 INFO : No changes found 2024/04/13 02:35:22 INFO : Updating listings 2024/04/13 02:35:22 INFO : Validating listings for Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:22 INFO : Bisync successful 2024/04/13 02:35:22 (14) : test 6. push changed filters-file to workdir. 2024/04/13 02:35:22 (15) : copy-as /tmp/bs-023423-vu/datadir/test_filtersfile_checks-yefivom6/filtersfile2.txt /tmp/bs-023423-vu/workdir/ filtersfile.txt 2024/04/13 02:35:22 INFO : filtersfile2.txt: Copied (replaced existing) to: filtersfile.txt 2024/04/13 02:35:22 (16) : test 7. run with filters-file alone. should abort. 2024/04/13 02:35:22 (17) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:22 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:22 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:22 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:22 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:22 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:22 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:22 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:35:22 Bisync error: bisync aborted 2024/04/13 02:35:22 (18) : test 8. run with filters-file and resync and dry-run. should do the dry-run but still cause next non-resync run to abort. 2024/04/13 02:35:22 (19) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt resync dry-run 2024/04/13 02:35:24 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:24 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:24 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:24 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:24 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:24 INFO : Skipped storing filters file hash to /tmp/bs-023423-vu/workdir/filtersfile.txt.md5 as --dry-run is set 2024/04/13 02:35:24 INFO : Copying Path2 files to Path1 2024/04/13 02:35:24 NOTICE: - Path2 Resync is copying files to - Path1 2024/04/13 02:35:24 NOTICE: - Path1 Resync is copying files to - Path2 2024/04/13 02:35:25 INFO : Resync updating listings 2024/04/13 02:35:25 INFO : Bisync successful 2024/04/13 02:35:25 (20) : test 9. run with filters-file alone. should abort. 2024/04/13 02:35:25 (21) : bisync filters-file=/tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:26 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:35:26 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:35:26 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/04/13 02:35:26 INFO : Synching Path1 "/tmp/bs-023423-vu/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/path2/" 2024/04/13 02:35:26 INFO : Using filters file /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:26 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/bs-023423-vu/workdir/filtersfile.txt 2024/04/13 02:35:26 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:35:26 Bisync error: bisync aborted bisync_test.go:539: TEST filtersfile_checks PASSED 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/RCLONE_TEST" 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/file1 - Copy (2).txt" 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/file1 - Copy.txt" 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/file1.txt" 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/file_enconde_mañana_funcionará.txt" 2024/04/13 02:35:27 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/filename_contains_ě_.txt" 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/filename_contains_ࢺ_.txt" 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt" 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ࢺ_.txt" 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge object "bs-023423-vu/extended_filenames/path2/Русский.txt" 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/path2" 2024/04/13 02:35:28 DEBUG : bs-023423-vu/path2/: Removing directory marker 2024/04/13 02:35:28 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/filtersfile_checks/path2" 2024/04/13 02:35:28 DEBUG : bs-023423-vu/filtersfile_checks/path2/: Removing directory marker 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/filtersfile_checks" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/filtersfile_checks/: Removing directory marker 2024/04/13 02:35:29 purge failed to rmdir "bs-023423-vu/filtersfile_checks": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/filtersfile_checks/, notFound 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/extended_filenames/path2/subdir_with_ࢺ_/: Removing directory marker 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/extended_filenames/path2" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/extended_filenames/path2/: Removing directory marker 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/extended_filenames" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/extended_filenames/: Removing directory marker 2024/04/13 02:35:29 purge failed to rmdir "bs-023423-vu/extended_filenames": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/extended_filenames/, notFound 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/ext_paths/path2" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/ext_paths/path2/: Removing directory marker 2024/04/13 02:35:29 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu/ext_paths" 2024/04/13 02:35:29 DEBUG : bs-023423-vu/ext_paths/: Removing directory marker 2024/04/13 02:35:30 purge failed to rmdir "bs-023423-vu/ext_paths": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/ext_paths/, notFound 2024/04/13 02:35:30 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "bs-023423-vu" 2024/04/13 02:35:30 DEBUG : bs-023423-vu/: Removing directory marker 2024/04/13 02:35:30 purge failed to rmdir "bs-023423-vu": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-fimuxey6fipemig5xuhujuh4/bs-023423-vu/, notFound 2024/04/13 02:35:30 DEBUG : GCS bucket rclone-test-fimuxey6fipemig5xuhujuh4: Purge dir "" --- PASS: TestBisyncLocalRemote (66.66s) --- PASS: TestBisyncLocalRemote/ext_paths (33.59s) --- SKIP: TestBisyncLocalRemote/extended_filenames (13.10s) --- PASS: TestBisyncLocalRemote/filtersfile_checks (16.43s) === RUN TestBisyncRemoteRemote 2024/04/13 02:35:30 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4" 2024/04/13 02:35:30 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:35:30 remote: TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4 === RUN TestBisyncRemoteRemote/ext_paths 2024/04/13 02:35:30 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:35:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/13 02:35:30 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:35:31 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:35:31 DEBUG : path1: Making directory 2024/04/13 02:35:32 DEBUG : path1/: Creating directory marker 2024/04/13 02:35:33 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1" 2024/04/13 02:35:33 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:35:33 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1" 2024/04/13 02:35:33 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:35:33 DEBUG : path2: Making directory 2024/04/13 02:35:33 DEBUG : path2/: Creating directory marker 2024/04/13 02:35:34 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2" 2024/04/13 02:35:34 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:35:34 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2" 2024/04/13 02:35:34 DEBUG : Creating backend with remote "/tmp/bs-023530-je/initdir/test_ext_paths-vosuwag1" 2024/04/13 02:35:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata 2024/04/13 02:35:34 INFO : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z) 2024/04/13 02:35:34 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:34 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_ext_paths-vosuwag1: Waiting for checks to finish 2024/04/13 02:35:34 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_ext_paths-vosuwag1: Waiting for transfers to finish 2024/04/13 02:35:34 DEBUG : file1.txt.basebav7.partial: renamed to: file1.txt 2024/04/13 02:35:34 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:34 INFO : file1.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : file_enconde_mañana_funcionará.txt.tuqoder2.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:35:34 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:34 DEBUG : Русский.txt.wotayuk4.partial: renamed to: Русский.txt 2024/04/13 02:35:34 INFO : Русский.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:34 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:34 DEBUG : 測試_check file.tayikam7.partial: renamed to: 測試_check file 2024/04/13 02:35:34 DEBUG : filename_contains_ě_.txt.giyudat3.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:35:34 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:34 INFO : 測試_check file: Copied (new) 2024/04/13 02:35:34 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:34 DEBUG : filename_contains_ࢺ_.txt.fohutop6.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:35:34 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : RCLONE_TEST.xonojoy2.partial: renamed to: RCLONE_TEST 2024/04/13 02:35:34 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.dekequw9.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file 2024/04/13 02:35:34 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.quyecah2.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt 2024/04/13 02:35:34 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:34 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.comopem8.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt 2024/04/13 02:35:34 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:34 INFO : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime) 2024/04/13 02:35:34 DEBUG : Creating backend with remote "/tmp/bs-023530-je/datadir/test_ext_paths-tixitet7" 2024/04/13 02:35:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:34 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_ext_paths-tixitet7: Waiting for checks to finish 2024/04/13 02:35:34 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_ext_paths-tixitet7: Waiting for transfers to finish 2024/04/13 02:35:34 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/13 02:35:34 DEBUG : file1.txt.sefecaz3.partial: renamed to: file1.txt 2024/04/13 02:35:34 INFO : file1.txt: Copied (new) 2024/04/13 02:35:34 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/13 02:35:34 DEBUG : 測試_filtersfile.txt.zakefen4.partial: renamed to: 測試_filtersfile.txt 2024/04/13 02:35:34 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/13 02:35:37 checking initFs Local file system at /tmp/bs-023530-je/initdir/test_ext_paths-vosuwag1 2024/04/13 02:35:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:38 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/13 02:35:39 DEBUG : /: Creating directory marker 2024/04/13 02:35:39 DEBUG : 測試_Русский_ _ _ě_áñ/: Creating directory marker 2024/04/13 02:35:39 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:39 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:39 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:39 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path1: Waiting for checks to finish 2024/04/13 02:35:39 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path1: Waiting for transfers to finish 2024/04/13 02:35:39 DEBUG : /: Creating directory marker 2024/04/13 02:35:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:39 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:39 INFO : file1.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:40 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:40 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:40 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:40 INFO : Русский.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:40 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:40 INFO : 測試_check file: Copied (new) 2024/04/13 02:35:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:40 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:40 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:35:40 checking Path1 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path1 2024/04/13 02:35:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:41 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory 2024/04/13 02:35:42 DEBUG : /: Creating directory marker 2024/04/13 02:35:42 DEBUG : 測試_Русский_ _ _ě_áñ/: Creating directory marker 2024/04/13 02:35:42 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:42 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:35:42 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination 2024/04/13 02:35:42 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path2: Waiting for checks to finish 2024/04/13 02:35:42 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path2: Waiting for transfers to finish 2024/04/13 02:35:42 DEBUG : /: Creating directory marker 2024/04/13 02:35:42 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:35:42 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:35:43 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:43 INFO : file1.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:43 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:43 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:43 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:43 INFO : Русский.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:43 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:43 INFO : 測試_check file: Copied (new) 2024/04/13 02:35:43 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:35:43 INFO : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:35:43 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:35:43 INFO : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new) 2024/04/13 02:35:43 checking path2 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path2 2024/04/13 02:35:43 (01) : test extended-char-paths 2024/04/13 02:35:43 (02) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/13 02:35:45 ERROR : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ: error reading source root directory: directory not found 2024/04/13 02:35:46 ERROR : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-vawafiz0: error reading source root directory: directory not found 2024/04/13 02:35:46 (03) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/ 2024/04/13 02:35:47 ERROR : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ: error reading source root directory: directory not found 2024/04/13 02:35:48 ERROR : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-bodopoy9: error reading source root directory: directory not found 2024/04/13 02:35:48 (04) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:35:50 (05) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:35:51 (06) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/13 02:35:52 (07) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt 2024/04/13 02:35:54 (08) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_check{spc}file 2024/04/13 02:35:55 (09) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_check{spc}file 2024/04/13 02:35:56 (10) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:35:57 (11) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file 2024/04/13 02:35:58 (12) : test resync subdirs with extended chars 2024/04/13 02:35:58 (13) : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync 2024/04/13 02:36:02 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:36:02 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/13 02:36:02 INFO : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage{juk_h}_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage{juk_h}_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: renaming to: /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst 2024/04/13 02:36:02 INFO : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage{juk_h}_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage{juk_h}_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: renaming to: /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst 2024/04/13 02:36:02 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ/" 2024/04/13 02:36:02 INFO : Copying Path2 files to Path1 2024/04/13 02:36:02 ERROR : &{[] map[] 0}: error saving ls1 from resync: open /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst-new: file name too long 2024/04/13 02:36:02 ERROR : &{[] map[] 0}: error saving ls2 from resync: open /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst-new: file name too long 2024/04/13 02:36:02 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:36:02 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:36:02 INFO : Resync updating listings 2024/04/13 02:36:02 ERROR : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: error saving old Path1 listing: stat /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst: file name too long 2024/04/13 02:36:02 ERROR : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: error saving old Path2 listing: stat /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: file name too long 2024/04/13 02:36:02 ERROR : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst-new: error replacing Path1 listing: stat /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path1.lst-new: file name too long 2024/04/13 02:36:02 ERROR : /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst-new: error replacing Path2 listing: stat /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst-new: file name too long 2024/04/13 02:36:02 ERROR : Bisync critical error: cannot read prior listing: open /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path1_測試_Русский_____ě_áñ..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_ext_paths_path2_測試_Русский_____ě_áñ.path2.lst: file name too long 2024/04/13 02:36:02 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:36:02 Bisync error: bisync aborted 2024/04/13 02:36:02 (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/ext_paths Messages: step 14 failed: listings not found === RUN TestBisyncRemoteRemote/extended_filenames 2024/04/13 02:36:02 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:36:02 DEBUG : path1: Making directory 2024/04/13 02:36:02 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1" 2024/04/13 02:36:02 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:36:03 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1" 2024/04/13 02:36:03 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:36:03 DEBUG : path2: Making directory 2024/04/13 02:36:03 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2" 2024/04/13 02:36:03 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:36:03 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2" 2024/04/13 02:36:03 DEBUG : Creating backend with remote "/tmp/bs-023530-je/initdir/test_extended_filenames-zebetoc7" 2024/04/13 02:36:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_: Making directory with metadata 2024/04/13 02:36:03 INFO : subdir_with_ࢺ_: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:36:03 DEBUG : Added delayed dir = "subdir_with_ࢺ_", newDst=subdir_with_ࢺ_ 2024/04/13 02:36:03 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:03 DEBUG : file1 - Copy (2).txt.zuyaqiv6.partial: renamed to: file1 - Copy (2).txt 2024/04/13 02:36:03 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:36:03 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:03 DEBUG : file1 - Copy.txt.hogeyif4.partial: renamed to: file1 - Copy.txt 2024/04/13 02:36:03 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:36:03 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_extended_filenames-zebetoc7: Waiting for checks to finish 2024/04/13 02:36:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:03 DEBUG : RCLONE_TEST.juwinol2.partial: renamed to: RCLONE_TEST 2024/04/13 02:36:03 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:36:03 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_extended_filenames-zebetoc7: Waiting for transfers to finish 2024/04/13 02:36:03 DEBUG : file1.txt.zutuyej0.partial: renamed to: file1.txt 2024/04/13 02:36:03 INFO : file1.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:03 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:03 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:03 DEBUG : Русский.txt.vajopeq1.partial: renamed to: Русский.txt 2024/04/13 02:36:03 INFO : Русский.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : filename_contains_ě_.txt.bikagij7.partial: renamed to: filename_contains_ě_.txt 2024/04/13 02:36:03 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : file_enconde_mañana_funcionará.txt.janopek2.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/13 02:36:03 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:03 DEBUG : filename_contains_ࢺ_.txt.fesodek4.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/13 02:36:03 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt.vabiram0.partial: renamed to: subdir_with_ࢺ_/filename_contains_ࢺ_.txt 2024/04/13 02:36:03 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt.zanojaw0.partial: renamed to: subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:03 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:03 INFO : subdir_with_ࢺ_: Set directory modification time (using SetModTime) 2024/04/13 02:36:03 DEBUG : Creating backend with remote "/tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5" 2024/04/13 02:36:03 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:03 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5: Waiting for checks to finish 2024/04/13 02:36:03 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5: Waiting for transfers to finish 2024/04/13 02:36:03 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/13 02:36:03 DEBUG : file2.txt: md5 = 40b811fb5009223b6da573f169619d8e OK 2024/04/13 02:36:03 DEBUG : file1.txt.lijuhuz1.partial: renamed to: file1.txt 2024/04/13 02:36:03 INFO : file1.txt: Copied (new) 2024/04/13 02:36:03 DEBUG : file2.txt.waqoxay5.partial: renamed to: file2.txt 2024/04/13 02:36:03 INFO : file2.txt: Copied (new) 2024/04/13 02:36:07 checking initFs Local file system at /tmp/bs-023530-je/initdir/test_extended_filenames-zebetoc7 2024/04/13 02:36:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:07 DEBUG : subdir_with_ࢺ_: Making directory 2024/04/13 02:36:08 DEBUG : subdir_with_ࢺ_/: Creating directory marker 2024/04/13 02:36:08 DEBUG : /: Creating directory marker 2024/04/13 02:36:08 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:08 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:36:08 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:08 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:08 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:08 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path1: Waiting for checks to finish 2024/04/13 02:36:08 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path1: Waiting for transfers to finish 2024/04/13 02:36:08 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:08 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:36:08 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:08 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:36:08 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:08 INFO : file1.txt: Copied (new) 2024/04/13 02:36:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:36:08 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:36:08 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:08 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:09 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:09 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:09 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:09 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:09 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:09 INFO : Русский.txt: Copied (new) 2024/04/13 02:36:09 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:09 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:09 checking Path1 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path1 2024/04/13 02:36:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : file1 - Copy (2).txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : file1 - Copy.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : subdir_with_ࢺ_: Making directory 2024/04/13 02:36:10 DEBUG : /: Creating directory marker 2024/04/13 02:36:10 DEBUG : subdir_with_ࢺ_/: Creating directory marker 2024/04/13 02:36:10 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/13 02:36:10 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path2: Waiting for checks to finish 2024/04/13 02:36:10 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path2: Waiting for transfers to finish 2024/04/13 02:36:11 DEBUG : /: Creating directory marker 2024/04/13 02:36:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:36:11 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:36:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:11 INFO : file1.txt: Copied (new) 2024/04/13 02:36:11 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:11 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/13 02:36:11 DEBUG : file1 - Copy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:11 INFO : file1 - Copy.txt: Copied (new) 2024/04/13 02:36:11 DEBUG : file1 - Copy (2).txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:11 INFO : file1 - Copy (2).txt: Copied (new) 2024/04/13 02:36:11 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:11 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:11 DEBUG : subdir_with_ࢺ_/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:11 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Copied (new) 2024/04/13 02:36:12 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:12 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:12 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:36:12 INFO : Русский.txt: Copied (new) 2024/04/13 02:36:12 DEBUG : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/13 02:36:12 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_.txt: Copied (new) 2024/04/13 02:36:12 checking path2 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/extended_filenames/path2 2024/04/13 02:36:12 (01) : test extended-filenames 2024/04/13 02:36:12 (02) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/ 2024/04/13 02:36:12 (03) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/ 2024/04/13 02:36:12 (04) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:13 (05) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:15 (06) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/Русский.txt 2024/04/13 02:36:16 (07) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/Русский.txt 2024/04/13 02:36:17 (08) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/file_enconde_mañana_funcionará.txt 2024/04/13 02:36:19 (09) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/file_enconde_mañana_funcionará.txt 2024/04/13 02:36:20 (10) : test initial bisync 2024/04/13 02:36:20 (11) : bisync resync 2024/04/13 02:36:23 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:36:25 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:36:25 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:36:25 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/13 02:36:25 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/" 2024/04/13 02:36:25 INFO : Copying Path2 files to Path1 2024/04/13 02:36:25 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:36:25 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:36:26 INFO : Resync updating listings 2024/04/13 02:36:26 INFO : Validating listings for Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/" 2024/04/13 02:36:26 INFO : Bisync successful 2024/04/13 02:36:26 (12) : test place a newer files on both paths 2024/04/13 02:36:26 (13) : touch-glob 2001-01-02 /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/ file1.txt 2024/04/13 02:36:26 (14) : touch-glob 2001-01-02 /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/ file2.txt 2024/04/13 02:36:26 (15) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/ New_top_level_mañana_funcionará.txt 2024/04/13 02:36:27 INFO : file1.txt: Copied (new) to: New_top_level_mañana_funcionará.txt 2024/04/13 02:36:27 (16) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/ file_enconde_mañana_funcionará.txt 2024/04/13 02:36:28 INFO : file1.txt: Copied (replaced existing) to: file_enconde_mañana_funcionará.txt 2024/04/13 02:36:28 (17) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/ filename_contains_ࢺ_p1m.txt 2024/04/13 02:36:30 INFO : file1.txt: Copied (new) to: filename_contains_ࢺ_p1m.txt 2024/04/13 02:36:30 (18) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/ Русский.txt 2024/04/13 02:36:31 INFO : file1.txt: Copied (replaced existing) to: Русский.txt 2024/04/13 02:36:31 (19) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/ file1_with{spc}white{spc}space.txt 2024/04/13 02:36:32 INFO : file1.txt: Copied (new) to: file1_with white space.txt 2024/04/13 02:36:32 (20) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_ test.txt 2024/04/13 02:36:33 INFO : file1.txt: Copied (new) to: test.txt 2024/04/13 02:36:33 (21) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_ mañana_funcionará.txt 2024/04/13 02:36:35 INFO : file1.txt: Copied (new) to: mañana_funcionará.txt 2024/04/13 02:36:35 (22) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_ file_with_測試_.txt 2024/04/13 02:36:36 INFO : file1.txt: Copied (new) to: file_with_測試_.txt 2024/04/13 02:36:36 (23) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_ filename_contains_ࢺ_p2s.txt 2024/04/13 02:36:38 INFO : file1.txt: Copied (new) to: filename_contains_ࢺ_p2s.txt 2024/04/13 02:36:38 (24) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir{spc}with{eol}white{spc}space.txt file2{spc}with{eol}white{spc}space.txt 2024/04/13 02:36:40 INFO : file1.txt: Copied (new) to: file2 with white space.txt 2024/04/13 02:36:40 (25) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_rawchars_{chr:19}_{chr:81}_{chr:fe} file3_{chr:19}_{chr:81}_{chr:fe} 2024/04/13 02:36:42 INFO : file1.txt: Copied (new) to: file3___ 2024/04/13 02:36:42 (26) : fix-names TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir{spc}with{eol}white{spc}space.txt/file2{spc}with{eol}white{spc}space.txt 2024/04/13 02:36:42 (27) : test place a new file on both paths 2024/04/13 02:36:42 (28) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file2.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_ filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:44 INFO : file2.txt: Copied (new) to: filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:44 (29) : touch-glob 2001-01-03 /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/ file1.txt 2024/04/13 02:36:44 (30) : copy-as /tmp/bs-023530-je/datadir/test_extended_filenames-cahewuz5/file1.txt TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_ filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:45 INFO : file1.txt: Copied (new) to: filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:45 (31) : test delete files on both paths 2024/04/13 02:36:45 (32) : delete-file TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/filename_contains_ࢺ_.txt 2024/04/13 02:36:46 INFO : filename_contains_ࢺ_.txt: Deleted 2024/04/13 02:36:46 (33) : delete-file TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:47 INFO : filename_contains_ě_.txt: Deleted 2024/04/13 02:36:47 (34) : delete-file TestGoogleCloudStorage,directory_markers='true':rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/Русский.txt 2024/04/13 02:36:47 INFO : Русский.txt: Deleted 2024/04/13 02:36:47 (35) : test bisync run 2024/04/13 02:36:47 (36) : bisync 2024/04/13 02:36:50 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:36:51 INFO : ě�� áñhࢺ_測試рускийěáñ👸🏼🧝🏾‍♀️💆🏿‍♂️🐨🤙🏼🤮🧑🏻‍🔧🧑‍🔬éö: Copied (new) 2024/04/13 02:36:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:36:51 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/13 02:36:51 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/" 2024/04/13 02:36:51 INFO : Building Path1 and Path2 listings 2024/04/13 02:36:52 ERROR : subdir_rawchars_␙__: Entry doesn't belong in directory "subdir_rawchars_␙_\x81_\xfe" (too short) - ignoring 2024/04/13 02:36:52 INFO : Path1 checking for diffs 2024/04/13 02:36:52 INFO : - Path1 File was deleted - Русский.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - file1_with white space.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - filename_contains_ࢺ_p1m.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - subdir_with_ࢺ_/file_with_測試_.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - subdir_with_ࢺ_/mañana_funcionará.txt 2024/04/13 02:36:52 INFO : - Path1 File is new - subdir_with_ࢺ_/test.txt 2024/04/13 02:36:52 INFO : Path1: 7 changes:  6 new,  0 modified,  1 deleted 2024/04/13 02:36:52 INFO : Path2 checking for diffs 2024/04/13 02:36:52 INFO : - Path2 File changed: size (larger), time (newer) - file_enconde_mañana_funcionará.txt 2024/04/13 02:36:52 INFO : - Path2 File was deleted - filename_contains_ࢺ_.txt 2024/04/13 02:36:52 INFO : - Path2 File was deleted - subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:52 INFO : - Path2 File changed: size (larger), time (newer) - Русский.txt 2024/04/13 02:36:52 INFO : - Path2 File is new - New_top_level_mañana_funcionará.txt 2024/04/13 02:36:52 INFO : - Path2 File is new - subdir with␊white space.txt/file2 with␊white space.txt 2024/04/13 02:36:52 INFO : - Path2 File is new - "subdir_rawchars_␙_\x81_\xfe/file3_␙_\x81_\xfe" 2024/04/13 02:36:52 INFO : - Path2 File is new - subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:52 INFO : - Path2 File is new - subdir_with_ࢺ_/filename_contains_ࢺ_p2s.txt 2024/04/13 02:36:52 INFO : Path2: 9 changes:  5 new,  2 modified,  2 deleted 2024/04/13 02:36:52 INFO : (Modified:  2 newer,  0 older,  2 larger,  0 smaller) 2024/04/13 02:36:52 INFO : Applying changes 2024/04/13 02:36:52 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/file1_with white space.txt 2024/04/13 02:36:52 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/filename_contains_ࢺ_p1m.txt 2024/04/13 02:36:52 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/file_with_測試_.txt 2024/04/13 02:36:52 NOTICE: - WARNING New or changed in both paths - subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt 2024/04/13 02:36:52 NOTICE: - Path1 Renaming Path1 copy - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict1 2024/04/13 02:36:53 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt: Copied (server-side copy) to: subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict1 2024/04/13 02:36:53 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt: Deleted 2024/04/13 02:36:53 NOTICE: - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict1 2024/04/13 02:36:53 NOTICE: - Path2 Renaming Path2 copy - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict2 2024/04/13 02:36:54 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt: Copied (server-side copy) to: subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict2 2024/04/13 02:36:54 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt: Deleted 2024/04/13 02:36:54 NOTICE: - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict2 2024/04/13 02:36:54 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/mañana_funcionará.txt 2024/04/13 02:36:54 INFO : - Path1 Queue copy to Path2 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/subdir_with_ࢺ_/test.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/Русский.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/New_top_level_mañana_funcionará.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/file_enconde_mañana_funcionará.txt 2024/04/13 02:36:54 INFO : - Path1 Queue delete - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/filename_contains_ࢺ_.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir with␊white space.txt/file2 with␊white space.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_rawchars_␙_\x81_\xfe/file3_␙_\x81_\xfe" 2024/04/13 02:36:54 INFO : - Path1 Queue delete - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ě_.txt 2024/04/13 02:36:54 INFO : - Path2 Queue copy to Path1 - TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/subdir_with_ࢺ_/filename_contains_ࢺ_p2s.txt 2024/04/13 02:36:54 INFO : - Path2 Do queued copies to - Path1 2024/04/13 02:36:55 INFO : New_top_level_mañana_funcionará.txt: Copied (server-side copy) 2024/04/13 02:36:56 ERROR : subdir_rawchars_␙__: Entry doesn't belong in directory "subdir_rawchars_␙_\x81_\xfe" (too short) - ignoring 2024/04/13 02:36:56 INFO : file_enconde_mañana_funcionará.txt: Copied (server-side copy) 2024/04/13 02:36:56 INFO : Русский.txt: Copied (server-side copy) 2024/04/13 02:36:56 INFO : subdir with␊white space.txt/file2 with␊white space.txt: Copied (server-side copy) 2024/04/13 02:36:56 INFO : subdir_rawchars_␙__/file3_␙__: Copied (server-side copy) 2024/04/13 02:36:56 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict2: Copied (server-side copy) 2024/04/13 02:36:56 INFO : subdir_with_ࢺ_/filename_contains_ࢺ_p2s.txt: Copied (server-side copy) 2024/04/13 02:36:57 INFO : subdir_with_ࢺ_/filename_contains_ě_.txt: Deleted 2024/04/13 02:36:57 INFO : filename_contains_ࢺ_.txt: Deleted 2024/04/13 02:36:57 INFO : - Path1 Do queued copies to - Path2 2024/04/13 02:36:57 INFO : file1_with white space.txt: Copied (server-side copy) 2024/04/13 02:36:57 INFO : filename_contains_ࢺ_p1m.txt: Copied (server-side copy) 2024/04/13 02:36:57 INFO : subdir_with_ࢺ_/filechangedbothpaths_ࢺ_.txt.conflict1: Copied (server-side copy) 2024/04/13 02:36:58 INFO : subdir_with_ࢺ_/file_with_測試_.txt: Copied (server-side copy) 2024/04/13 02:36:58 INFO : subdir_with_ࢺ_/mañana_funcionará.txt: Copied (server-side copy) 2024/04/13 02:36:58 INFO : subdir_with_ࢺ_/test.txt: Copied (server-side copy) 2024/04/13 02:36:58 INFO : Updating listings 2024/04/13 02:36:58 INFO : Validating listings for Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/path2/" 2024/04/13 02:36:58 INFO : Bisync successful 2024/04/13 02:36:58 ---------------------------------------------------------- 2024/04/13 02:36:58 | MISCOMPARE -Golden vs +Results for test.log 2024/04/13 02:36:58 | @@ -65,0 +66 @@ 2024/04/13 02:36:58 | +ERROR : subdir_rawchars_␙__: Entry doesn't belong in directory "subdir_rawchars_␙_\x81_\xfe" (too short) - ignoring 2024/04/13 02:36:58 | @@ -106,0 +108 @@ 2024/04/13 02:36:58 | +ERROR : subdir_rawchars_␙__: Entry doesn't belong in directory "subdir_rawchars_␙_\x81_\xfe" (too short) - ignoring 2024/04/13 02:36:58 ---------------------------------------------------------- bisync_test.go:539: TEST extended_filenames FAILED - 1 MISCOMPARED FILES === RUN TestBisyncRemoteRemote/filtersfile_checks 2024/04/13 02:37:01 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:37:01 DEBUG : path1: Making directory 2024/04/13 02:37:01 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1" 2024/04/13 02:37:01 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:37:01 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1" 2024/04/13 02:37:01 DEBUG : fs cache: switching user supplied name "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" for canonical name "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je" 2024/04/13 02:37:01 DEBUG : path2: Making directory 2024/04/13 02:37:01 DEBUG : Creating backend with remote "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2" 2024/04/13 02:37:01 DEBUG : TestGoogleCloudStorage: detected overridden config - adding "{juk_h}" suffix to name 2024/04/13 02:37:02 DEBUG : fs cache: renaming cache item "TestGoogleCloudStorage,directory_markers:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2" to be canonical "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2" 2024/04/13 02:37:02 DEBUG : Creating backend with remote "/tmp/bs-023530-je/initdir/test_filtersfile_checks-pibizox7" 2024/04/13 02:37:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : subdir: Making directory with metadata 2024/04/13 02:37:02 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/04/13 02:37:02 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/04/13 02:37:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:02 DEBUG : file1.txt.wiqejun2.partial: renamed to: file1.txt 2024/04/13 02:37:02 INFO : file1.txt: Copied (new) 2024/04/13 02:37:02 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:02 DEBUG : file2.txt.jaqayer3.partial: renamed to: file2.txt 2024/04/13 02:37:02 INFO : file2.txt: Copied (new) 2024/04/13 02:37:02 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:37:02 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_filtersfile_checks-pibizox7: Waiting for checks to finish 2024/04/13 02:37:02 DEBUG : Local file system at /tmp/bs-023530-je/initdir/test_filtersfile_checks-pibizox7: Waiting for transfers to finish 2024/04/13 02:37:02 DEBUG : RCLONE_TEST.masokux5.partial: renamed to: RCLONE_TEST 2024/04/13 02:37:02 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:37:02 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:02 DEBUG : subdir/file20.txt.yodemul0.partial: renamed to: subdir/file20.txt 2024/04/13 02:37:02 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:37:02 INFO : subdir: Set directory modification time (using SetModTime) 2024/04/13 02:37:02 DEBUG : Creating backend with remote "/tmp/bs-023530-je/datadir/test_filtersfile_checks-cenezus5" 2024/04/13 02:37:02 DEBUG : filtersfile.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : filtersfile2.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : hold.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:02 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_filtersfile_checks-cenezus5: Waiting for checks to finish 2024/04/13 02:37:02 DEBUG : Local file system at /tmp/bs-023530-je/datadir/test_filtersfile_checks-cenezus5: Waiting for transfers to finish 2024/04/13 02:37:02 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2024/04/13 02:37:02 DEBUG : hold.txt.cocaxus2.partial: renamed to: hold.txt 2024/04/13 02:37:02 INFO : hold.txt: Copied (new) 2024/04/13 02:37:02 DEBUG : filtersfile.txt: md5 = cbf4389cb34d9e160689d1578eb5707f OK 2024/04/13 02:37:02 DEBUG : filtersfile.txt.yodesep8.partial: renamed to: filtersfile.txt 2024/04/13 02:37:02 INFO : filtersfile.txt: Copied (new) 2024/04/13 02:37:02 DEBUG : filtersfile2.txt: md5 = 95acc1e3b5beda465d9b2cb32808588c OK 2024/04/13 02:37:02 DEBUG : filtersfile2.txt.bazanam8.partial: renamed to: filtersfile2.txt 2024/04/13 02:37:02 INFO : filtersfile2.txt: Copied (new) 2024/04/13 02:37:05 checking initFs Local file system at /tmp/bs-023530-je/initdir/test_filtersfile_checks-pibizox7 2024/04/13 02:37:06 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:37:06 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:06 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:06 DEBUG : subdir: Making directory 2024/04/13 02:37:07 DEBUG : /: Creating directory marker 2024/04/13 02:37:07 DEBUG : subdir/: Creating directory marker 2024/04/13 02:37:07 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:07 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path1: Waiting for checks to finish 2024/04/13 02:37:07 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path1: Waiting for transfers to finish 2024/04/13 02:37:07 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:37:07 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:37:07 DEBUG : /: Creating directory marker 2024/04/13 02:37:07 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:07 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:37:07 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:07 INFO : file1.txt: Copied (new) 2024/04/13 02:37:07 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:07 INFO : file2.txt: Copied (new) 2024/04/13 02:37:07 checking Path1 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path1 2024/04/13 02:37:08 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/13 02:37:08 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:08 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:08 DEBUG : subdir: Making directory 2024/04/13 02:37:09 DEBUG : subdir/: Creating directory marker 2024/04/13 02:37:09 DEBUG : /: Creating directory marker 2024/04/13 02:37:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/04/13 02:37:09 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path2: Waiting for checks to finish 2024/04/13 02:37:09 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path2: Waiting for transfers to finish 2024/04/13 02:37:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/13 02:37:09 INFO : RCLONE_TEST: Copied (new) 2024/04/13 02:37:09 DEBUG : /: Creating directory marker 2024/04/13 02:37:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:09 INFO : subdir/file20.txt: Copied (new) 2024/04/13 02:37:09 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:09 INFO : file1.txt: Copied (new) 2024/04/13 02:37:09 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/13 02:37:09 INFO : file2.txt: Copied (new) 2024/04/13 02:37:09 checking path2 GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4 path bs-023530-je/filtersfile_checks/path2 2024/04/13 02:37:09 (01) : test filtersfile-checks 2024/04/13 02:37:09 (02) : test initial bisync 2024/04/13 02:37:09 (03) : bisync resync 2024/04/13 02:37:11 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:37:11 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/13 02:37:11 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:11 INFO : Copying Path2 files to Path1 2024/04/13 02:37:11 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:37:12 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:37:12 INFO : Resync updating listings 2024/04/13 02:37:12 INFO : Validating listings for Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:12 INFO : Bisync successful 2024/04/13 02:37:12 (04) : test 1. inject filters file in workdir. 2024/04/13 02:37:12 (05) : copy-file /tmp/bs-023530-je/datadir/test_filtersfile_checks-cenezus5/filtersfile.txt /tmp/bs-023530-je/workdir/ 2024/04/13 02:37:12 INFO : filtersfile.txt: Copied (new) 2024/04/13 02:37:12 (06) : test 2. run with filters-file but without md5. should abort. 2024/04/13 02:37:12 (07) : bisync filters-file=/tmp/bs-023530-je/workdir/filtersfile.txt 2024/04/13 02:37:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:37: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/13 02:37:13 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:13 INFO : Using filters file /tmp/bs-023530-je/workdir/filtersfile.txt 2024/04/13 02:37:13 ERROR : Bisync critical error: filters file md5 hash not found (must run --resync): /tmp/bs-023530-je/workdir/filtersfile.txt 2024/04/13 02:37:13 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:37:13 Bisync error: bisync aborted 2024/04/13 02:37:13 (08) : test 3. run without filters-file. should be blocked due to prior abort. 2024/04/13 02:37:13 (09) : bisync 2024/04/13 02:37:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:37:16 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/13 02:37:16 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:16 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run Tip: here are the filenames we were looking for. Do they exist? Path1: /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_filtersfile_checks_path1..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_filtersfile_checks_path2.path1.lst Path2: /tmp/bs-023530-je/workdir/TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_filtersfile_checks_path1..TestGoogleCloudStorage_rclone-test-xayuzot7xafiyic3lojamaf4_bs-023530-je_filtersfile_checks_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/bs-023530-je/workdir" 2024/04/13 02:37:16 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/13 02:37:16 Bisync error: bisync aborted 2024/04/13 02:37:16 (10) : test 4. run with filters-file and resync. 2024/04/13 02:37:16 (11) : bisync filters-file=/tmp/bs-023530-je/workdir/filtersfile.txt resync 2024/04/13 02:37:18 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/13 02:37:18 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/13 02:37:18 INFO : Synching Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" with Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:18 INFO : Using filters file /tmp/bs-023530-je/workdir/filtersfile.txt 2024/04/13 02:37:18 INFO : Storing filters file hash to /tmp/bs-023530-je/workdir/filtersfile.txt.md5 2024/04/13 02:37:18 INFO : Copying Path2 files to Path1 2024/04/13 02:37:18 INFO : - Path2 Resync is copying files to - Path1 2024/04/13 02:37:18 INFO : - Path1 Resync is copying files to - Path2 2024/04/13 02:37:19 INFO : Resync updating listings 2024/04/13 02:37:19 INFO : Validating listings for Path1 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path1/" vs Path2 "TestGoogleCloudStorage{juk_h}:rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/" 2024/04/13 02:37:19 INFO : Bisync successful 2024/04/13 02:37:19 (12) : test 5. run with filters-file alone. should run. 2024/04/13 02:37:19 (13) : bisync filters-file=/tmp/bs-023530-je/workdir/filtersfile.txt bisync_test.go:940: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:940 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:951 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1015 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:726 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:473 /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:344 Error: Received unexpected error: googleapi: Error 429: The object rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/path2/modtime_write_test exceeded the rate limit for object mutation operations (create, update, and delete). Please reduce your request rate. See https://cloud.google.com/storage/docs/gcs429., rateLimitExceeded Test: TestBisyncRemoteRemote/filtersfile_checks 2024/04/13 02:37:20 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/RCLONE_TEST" 2024/04/13 02:37:20 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/file1.txt" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/file_enconde_mañana_funcionará.txt" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/filename_contains_ě_.txt" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/filename_contains_ࢺ_.txt" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/Русский.txt" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/測試_check file" 2024/04/13 02:37:21 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt" 2024/04/13 02:37:22 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt" 2024/04/13 02:37:22 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_check file" 2024/04/13 02:37:22 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/RCLONE_TEST" 2024/04/13 02:37:22 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/file1.txt" 2024/04/13 02:37:22 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/file_enconde_mañana_funcionará.txt" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/filename_contains_ě_.txt" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/filename_contains_ࢺ_.txt" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/Русский.txt" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/測試_check file" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt" 2024/04/13 02:37:23 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_check file" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path1/RCLONE_TEST" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path1/file1.txt" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path1/file2.txt" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path1/subdir/file20.txt" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path2/RCLONE_TEST" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path2/file1.txt" 2024/04/13 02:37:24 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path2/file2.txt" 2024/04/13 02:37:25 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge object "bs-023530-je/filtersfile_checks/path2/subdir/file20.txt" 2024/04/13 02:37:25 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/path2" 2024/04/13 02:37:25 DEBUG : bs-023530-je/path2/: Removing directory marker 2024/04/13 02:37:25 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/path1" 2024/04/13 02:37:25 DEBUG : bs-023530-je/path1/: Removing directory marker 2024/04/13 02:37:25 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/filtersfile_checks/path2/subdir" 2024/04/13 02:37:25 DEBUG : bs-023530-je/filtersfile_checks/path2/subdir/: Removing directory marker 2024/04/13 02:37:25 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/filtersfile_checks/path2" 2024/04/13 02:37:25 DEBUG : bs-023530-je/filtersfile_checks/path2/: Removing directory marker 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/filtersfile_checks/path1/subdir" 2024/04/13 02:37:26 DEBUG : bs-023530-je/filtersfile_checks/path1/subdir/: Removing directory marker 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/filtersfile_checks/path1" 2024/04/13 02:37:26 DEBUG : bs-023530-je/filtersfile_checks/path1/: Removing directory marker 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/filtersfile_checks" 2024/04/13 02:37:26 DEBUG : bs-023530-je/filtersfile_checks/: Removing directory marker 2024/04/13 02:37:26 purge failed to rmdir "bs-023530-je/filtersfile_checks": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/filtersfile_checks/, notFound 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/extended_filenames/path2" 2024/04/13 02:37:26 DEBUG : bs-023530-je/extended_filenames/path2/: Removing directory marker 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/extended_filenames/path1" 2024/04/13 02:37:26 DEBUG : bs-023530-je/extended_filenames/path1/: Removing directory marker 2024/04/13 02:37:26 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/extended_filenames" 2024/04/13 02:37:26 DEBUG : bs-023530-je/extended_filenames/: Removing directory marker 2024/04/13 02:37:27 purge failed to rmdir "bs-023530-je/extended_filenames": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/extended_filenames/, notFound 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ" 2024/04/13 02:37:27 DEBUG : bs-023530-je/ext_paths/path2/測試_Русский_ _ _ě_áñ/: Removing directory marker 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/ext_paths/path2" 2024/04/13 02:37:27 DEBUG : bs-023530-je/ext_paths/path2/: Removing directory marker 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ" 2024/04/13 02:37:27 DEBUG : bs-023530-je/ext_paths/path1/測試_Русский_ _ _ě_áñ/: Removing directory marker 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/ext_paths/path1" 2024/04/13 02:37:27 DEBUG : bs-023530-je/ext_paths/path1/: Removing directory marker 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je/ext_paths" 2024/04/13 02:37:27 DEBUG : bs-023530-je/ext_paths/: Removing directory marker 2024/04/13 02:37:27 purge failed to rmdir "bs-023530-je/ext_paths": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/ext_paths/, notFound 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "bs-023530-je" 2024/04/13 02:37:27 DEBUG : bs-023530-je/: Removing directory marker 2024/04/13 02:37:27 purge failed to rmdir "bs-023530-je": removing directory marker failed: googleapi: Error 404: No such object: rclone-test-xayuzot7xafiyic3lojamaf4/bs-023530-je/, notFound 2024/04/13 02:37:27 DEBUG : GCS bucket rclone-test-xayuzot7xafiyic3lojamaf4: Purge dir "" --- FAIL: TestBisyncRemoteRemote (118.08s) --- FAIL: TestBisyncRemoteRemote/ext_paths (31.83s) --- FAIL: TestBisyncRemoteRemote/extended_filenames (58.75s) --- FAIL: TestBisyncRemoteRemote/filtersfile_checks (19.28s) FAIL 2024/04/13 02:37:29 DEBUG : GCS bucket rclone-test-fuhunij5geheyed3quvizad1: Purge dir "" 2024/04/13 02:37:29 purge failed to rmdir "": googleapi: Error 404: The specified bucket does not exist., notFound 2024/04/13 02:37:29 purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote 'TestGoogleCloudStorage,directory_markers:' -verbose -test.run '^(TestBisyncLocalRemote|TestBisyncRemoteRemote)$/^(ext_paths|extended_filenames|filtersfile_checks)$'" - Finished ERROR in 3m5.488835517s (try 5/5): exit status 1: Failed [TestBisyncRemoteRemote/ext_paths TestBisyncRemoteRemote/extended_filenames TestBisyncRemoteRemote/filtersfile_checks]