"./bisync.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestBisyncLocalRemote$/^ext_paths$'" - Starting (try 2/5) 2024/04/17 05:20:40 DEBUG : Creating backend with remote "TestBox:rclone-test-gaduhux7gevodic8hocitiz9" 2024/04/17 05:20:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/17 05:20:40 DEBUG : box root 'rclone-test-gaduhux7gevodic8hocitiz9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:20:41 DEBUG : Creating backend with remote "/tmp/rclone2071637634" === RUN TestBisyncLocalRemote 2024/04/17 05:20:41 DEBUG : Creating backend with remote "TestBox:rclone-test-becohix3boxevej1juvepec7" 2024/04/17 05:20:41 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:20:42 remote: TestBox:rclone-test-becohix3boxevej1juvepec7 === RUN TestBisyncLocalRemote/ext_paths 2024/04/17 05:20:42 DEBUG : Creating backend with remote "/tmp/052042ni" 2024/04/17 05:20:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/17 05:20:42 DEBUG : Creating backend with remote "/tmp/052042ni/ext_paths/path1" 2024/04/17 05:20:42 DEBUG : Creating backend with remote "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni" 2024/04/17 05:20:42 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7/052042ni': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:20:44 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:20:44 DEBUG : path2: Making directory 2024/04/17 05:20:44 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7/052042ni': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:20:47 DEBUG : Creating backend with remote "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2" 2024/04/17 05:20:50 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/initial" 2024/04/17 05:20:50 DEBUG : Creating backend with remote "/tmp/052042ni/initdir/test_ext_paths-gucexup9" 2024/04/17 05:20:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ: Making directory with metadata 2024/04/17 05:20:50 INFO : 測試Русский ěáñ: Made directory with metadata (mtime=2024-04-17T01:00:03.064427195Z) 2024/04/17 05:20:50 DEBUG : Added delayed dir = "測試Русский ěáñ", newDst=測試Русский ěáñ 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : Local file system at /tmp/052042ni/initdir/test_ext_paths-gucexup9: Waiting for checks to finish 2024/04/17 05:20:50 DEBUG : Local file system at /tmp/052042ni/initdir/test_ext_paths-gucexup9: Waiting for transfers to finish 2024/04/17 05:20:50 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:50 DEBUG : file1.txt.wugepac5.partial: renamed to: file1.txt 2024/04/17 05:20:50 INFO : file1.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:50 DEBUG : file_enconde_mañana_funcionará.txt.muwoyet6.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/17 05:20:50 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/17 05:20:50 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:50 DEBUG : filename_contains_ě_.txt.palamol9.partial: renamed to: filename_contains_ě_.txt 2024/04/17 05:20:50 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : RCLONE_TEST.mifaduf2.partial: renamed to: RCLONE_TEST 2024/04/17 05:20:50 INFO : RCLONE_TEST: Copied (new) 2024/04/17 05:20:50 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:50 DEBUG : 測試_check file.lojihuv0.partial: renamed to: 測試_check file 2024/04/17 05:20:50 INFO : 測試_check file: Copied (new) 2024/04/17 05:20:50 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:50 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:50 DEBUG : Русский.txt.balugin7.partial: renamed to: Русский.txt 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:50 INFO : Русский.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : filename_contains_ࢺ_.txt.juxumun6.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/17 05:20:50 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt.munuyit8.partial: renamed to: 測試Русский ěáñ/filename_contains_ě_.txt 2024/04/17 05:20:50 INFO : 測試Русский ěáñ/filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt.valumaz8.partial: renamed to: 測試Русский ěáñ/filename_contains_ࢺ_.txt 2024/04/17 05:20:50 INFO : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:50 DEBUG : 測試Русский ěáñ/測試_check file.rugotuw4.partial: renamed to: 測試Русский ěáñ/測試_check file 2024/04/17 05:20:50 INFO : 測試Русский ěáñ/測試_check file: Copied (new) 2024/04/17 05:20:50 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:20:50 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/modfiles" 2024/04/17 05:20:50 DEBUG : Creating backend with remote "/tmp/052042ni/datadir/test_ext_paths-homabip7" 2024/04/17 05:20:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:50 DEBUG : Local file system at /tmp/052042ni/datadir/test_ext_paths-homabip7: Waiting for checks to finish 2024/04/17 05:20:50 DEBUG : Local file system at /tmp/052042ni/datadir/test_ext_paths-homabip7: Waiting for transfers to finish 2024/04/17 05:20:50 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK 2024/04/17 05:20:50 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/04/17 05:20:50 DEBUG : 測試_filtersfile.txt.wocidim3.partial: renamed to: 測試_filtersfile.txt 2024/04/17 05:20:50 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/17 05:20:50 DEBUG : file1.txt.yasadil9.partial: renamed to: file1.txt 2024/04/17 05:20:50 INFO : file1.txt: Copied (new) 2024/04/17 05:20:50 ERROR : : error listing: directory not found 2024/04/17 05:20:51 ERROR : : error listing: directory not found 2024/04/17 05:20:51 checking initFs Local file system at /tmp/052042ni/initdir/test_ext_paths-gucexup9 2024/04/17 05:20:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ: Making directory with metadata 2024/04/17 05:20:51 INFO : 測試Русский ěáñ: Made directory with metadata (mtime=2024-04-17T01:00:03.064427195Z) 2024/04/17 05:20:51 DEBUG : Added delayed dir = "測試Русский ěáñ", newDst=測試Русский ěáñ 2024/04/17 05:20:51 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:51 DEBUG : file1.txt.pecucar1.partial: renamed to: file1.txt 2024/04/17 05:20:51 INFO : file1.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:51 DEBUG : file_enconde_mañana_funcionará.txt.vukutaw1.partial: renamed to: file_enconde_mañana_funcionará.txt 2024/04/17 05:20:51 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/04/17 05:20:51 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:51 DEBUG : Русский.txt.xuxohad4.partial: renamed to: Русский.txt 2024/04/17 05:20:51 INFO : Русский.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : Local file system at /tmp/052042ni/ext_paths/path1: Waiting for checks to finish 2024/04/17 05:20:51 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:51 DEBUG : Local file system at /tmp/052042ni/ext_paths/path1: Waiting for transfers to finish 2024/04/17 05:20:51 DEBUG : RCLONE_TEST.mozibez6.partial: renamed to: RCLONE_TEST 2024/04/17 05:20:51 INFO : RCLONE_TEST: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:51 DEBUG : filename_contains_ě_.txt.najujal7.partial: renamed to: filename_contains_ě_.txt 2024/04/17 05:20:51 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試_check file.zapuyor4.partial: renamed to: 測試_check file 2024/04/17 05:20:51 INFO : 測試_check file: Copied (new) 2024/04/17 05:20:51 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:51 DEBUG : filename_contains_ࢺ_.txt.tuyuqal2.partial: renamed to: filename_contains_ࢺ_.txt 2024/04/17 05:20:51 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/測試_check file.pewajoj8.partial: renamed to: 測試Русский ěáñ/測試_check file 2024/04/17 05:20:51 INFO : 測試Русский ěáñ/測試_check file: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt.vojosud6.partial: renamed to: 測試Русский ěáñ/filename_contains_ě_.txt 2024/04/17 05:20:51 INFO : 測試Русский ěáñ/filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt.finoraw1.partial: renamed to: 測試Русский ěáñ/filename_contains_ࢺ_.txt 2024/04/17 05:20:51 INFO : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:51 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:20:51 checking Path1 Local file system at /tmp/052042ni/ext_paths/path1 2024/04/17 05:20:51 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : Русский.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:51 DEBUG : 測試Русский ěáñ: Making directory 2024/04/17 05:20:55 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:55 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination 2024/04/17 05:20:55 DEBUG : 測試Русский ěáñ/測試_check file: Need to transfer - File not found at Destination 2024/04/17 05:20:55 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2': Waiting for checks to finish 2024/04/17 05:20:55 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2': Waiting for transfers to finish 2024/04/17 05:20:56 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/17 05:20:56 INFO : file1.txt: Copied (new) 2024/04/17 05:20:56 DEBUG : filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2024/04/17 05:20:56 INFO : filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:56 DEBUG : file_enconde_mañana_funcionará.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/17 05:20:56 INFO : file_enconde_mañana_funcionará.txt: Copied (new) 2024/04/17 05:20:56 DEBUG : filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2024/04/17 05:20:56 INFO : filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:58 DEBUG : Русский.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/17 05:20:58 INFO : Русский.txt: Copied (new) 2024/04/17 05:20:58 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2024/04/17 05:20:58 INFO : RCLONE_TEST: Copied (new) 2024/04/17 05:20:58 DEBUG : 測試_check file: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/17 05:20:58 INFO : 測試_check file: Copied (new) 2024/04/17 05:20:58 DEBUG : 測試Русский ěáñ/filename_contains_ě_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2024/04/17 05:20:58 INFO : 測試Русский ěáñ/filename_contains_ě_.txt: Copied (new) 2024/04/17 05:20:59 DEBUG : 測試Русский ěáñ/filename_contains_ࢺ_.txt: sha1 = 70063f4264b2f671d8a31fa59ff565147c6e51ce OK 2024/04/17 05:20:59 INFO : 測試Русский ěáñ/filename_contains_ࢺ_.txt: Copied (new) 2024/04/17 05:20:59 DEBUG : 測試Русский ěáñ/測試_check file: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2024/04/17 05:20:59 INFO : 測試Русский ěáñ/測試_check file: Copied (new) 2024/04/17 05:20:59 checking path2 box root 'rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2' 2024/04/17 05:21:00 (01) : test extended-char-paths 2024/04/17 05:21:00 (02) : fix-names /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ/ 2024/04/17 05:21:00 ERROR : Local file system at /tmp/052042ni/ext_paths/path1/測試Русский ěáñ-rclone-move-tehuhuj1: Server side directory move failed: rename /tmp/052042ni/ext_paths/path1/測試Русский ěáñ /tmp/052042ni/ext_paths/path1/測試Русский ěáñ-rclone-move-tehuhuj1: no such file or directory 2024/04/17 05:21:00 INFO : Local file system at /tmp/052042ni/ext_paths/path1/測試Русский ěáñ: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/17 05:21:00 ERROR : Local file system at /tmp/052042ni/ext_paths/path1/測試Русский ěáñ-rclone-move-tehuhuj1: error reading source root directory: directory not found 2024/04/17 05:21:00 (03) : fix-names TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский{spc}{spc}ěáñ/ 2024/04/17 05:21:05 ERROR : TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/: error getting Fs: is a directory not a file 2024/04/17 05:21:05 (04) : fix-names /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ/測試_check{spc}file 2024/04/17 05:21:05 (05) : fix-names TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский{spc}{spc}ěáñ/測試_check{spc}file 2024/04/17 05:21:10 (06) : fix-names /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ/filename_contains_ě_.txt 2024/04/17 05:21:10 (07) : fix-names TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский{spc}{spc}ěáñ/filename_contains_ě_.txt 2024/04/17 05:21:14 INFO : rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/filename_contains_ě_.txt: Moved (server-side) to: rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/filename_contains_ě_.txt-rclone-move-xagakec1 2024/04/17 05:21:15 INFO : rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/filename_contains_ě_.txt-rclone-move-xagakec1: Moved (server-side) to: rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/filename_contains_ě_.txt 2024/04/17 05:21:15 (08) : fix-names /tmp/052042ni/ext_paths/path1/測試_check{spc}file 2024/04/17 05:21:15 (09) : fix-names TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試_check{spc}file 2024/04/17 05:21:18 (10) : fix-names /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ/測試_check{spc}file 2024/04/17 05:21:18 (11) : fix-names TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский{spc}{spc}ěáñ/測試_check{spc}file 2024/04/17 05:21:21 (12) : test resync subdirs with extended chars 2024/04/17 05:21:21 (13) : bisync subdir=測試Русский{spc}{spc}ěáñ resync 2024/04/17 05:21:27 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21:27 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/17 05:21:27 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/測試Русский ěáñ/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/" 2024/04/17 05:21:27 INFO : Copying Path2 files to Path1 2024/04/17 05:21:27 INFO : - Path2 Resync is copying files to - Path1 2024/04/17 05:21:27 INFO : - Path1 Resync is copying files to - Path2 2024/04/17 05:21:28 INFO : Resync updating listings 2024/04/17 05:21:28 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/測試Русский ěáñ/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/" 2024/04/17 05:21:28 INFO : Bisync successful 2024/04/17 05:21:28 (14) : copy-listings resync 2024/04/17 05:21:28 (15) : test place new files with extended chars on each side 2024/04/17 05:21:28 (16) : touch-glob 2001-01-02 /tmp/052042ni/datadir/test_ext_paths-homabip7/ file1.txt 2024/04/17 05:21:28 (17) : copy-as /tmp/052042ni/datadir/test_ext_paths-homabip7/file1.txt /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ 測試_file1p1 2024/04/17 05:21:28 INFO : file1.txt: Copied (new) to: 測試_file1p1 2024/04/17 05:21:28 (18) : copy-as /tmp/052042ni/datadir/test_ext_paths-homabip7/file1.txt TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский{spc}{spc}ěáñ 測試_file1p2 2024/04/17 05:21:32 INFO : file1.txt: Copied (new) to: 測試_file1p2 2024/04/17 05:21:32 (19) : test normal sync of subdirs with extended chars 2024/04/17 05:21:32 (20) : bisync subdir=測試Русский{spc}{spc}ěáñ 2024/04/17 05:21:35 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21:35 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/17 05:21:35 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/測試Русский ěáñ/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/" 2024/04/17 05:21:35 INFO : Building Path1 and Path2 listings 2024/04/17 05:21:35 INFO : Path1 checking for diffs 2024/04/17 05:21:35 INFO : - Path1 File is new - 測試_file1p1 2024/04/17 05:21:35 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/17 05:21:35 INFO : Path2 checking for diffs 2024/04/17 05:21:35 INFO : - Path2 File is new - 測試_file1p2 2024/04/17 05:21:35 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/04/17 05:21:35 INFO : Applying changes 2024/04/17 05:21:35 INFO : - Path1 Queue copy to Path2 - TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/測試_file1p1 2024/04/17 05:21:35 INFO : - Path2 Queue copy to Path1 - /tmp/052042ni/ext_paths/path1/測試Русский ěáñ/測試_file1p2 2024/04/17 05:21:35 INFO : - Path2 Do queued copies to - Path1 2024/04/17 05:21:36 INFO : 測試_file1p2: Copied (new) 2024/04/17 05:21:36 INFO : - Path1 Do queued copies to - Path2 2024/04/17 05:21:39 INFO : 測試_file1p1: Copied (new) 2024/04/17 05:21:39 INFO : Updating listings 2024/04/17 05:21:39 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/測試Русский ěáñ/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/測試Русский ěáñ/" 2024/04/17 05:21:39 INFO : Bisync successful 2024/04/17 05:21:39 (21) : move-listings normal-sync 2024/04/17 05:21:39 (22) : test check-filename with extended chars. check should fail. 2024/04/17 05:21:39 (23) : bisync resync 2024/04/17 05:21:41 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:21:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21: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/17 05:21:41 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:41 INFO : Copying Path2 files to Path1 2024/04/17 05:21:41 INFO : - Path2 Resync is copying files to - Path1 2024/04/17 05:21:42 INFO : - Path1 Resync is copying files to - Path2 2024/04/17 05:21:43 INFO : Resync updating listings 2024/04/17 05:21:43 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:43 INFO : Bisync successful 2024/04/17 05:21:43 (24) : delete-file /tmp/052042ni/ext_paths/path1/測試Русский{spc}{spc}ěáñ/測試_check{spc}file 2024/04/17 05:21:43 INFO : 測試_check file: Deleted 2024/04/17 05:21:43 (25) : bisync check-access check-filename=測試_check{spc}file 2024/04/17 05:21:46 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:21:46 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21: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/17 05:21:46 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:46 INFO : Building Path1 and Path2 listings 2024/04/17 05:21:47 INFO : Path1 checking for diffs 2024/04/17 05:21:47 INFO : - Path1 File was deleted - 測試Русский ěáñ/測試_check file 2024/04/17 05:21:47 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2024/04/17 05:21:47 INFO : Path2 checking for diffs 2024/04/17 05:21:47 INFO : Checking access health 2024/04/17 05:21:47 ERROR : Access test failed: Path1 count 1, Path2 count 2 - 測試_check file 2024/04/17 05:21:47 ERROR : -  Access test failed: Path2 file not found in Path1 - 測試Русский ěáñ/測試_check file 2024/04/17 05:21:47 ERROR : Bisync critical error: check file check failed 2024/04/17 05:21:47 ERROR : Bisync aborted. Must run --resync to recover. 2024/04/17 05:21:47 Bisync error: bisync aborted 2024/04/17 05:21:47 (26) : copy-listings check-access-fail 2024/04/17 05:21:47 (27) : test check-filename with extended chars. check should pass. 2024/04/17 05:21:47 (28) : bisync resync 2024/04/17 05:21:50 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:21:50 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21: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/17 05:21:50 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:50 INFO : Copying Path2 files to Path1 2024/04/17 05:21:50 INFO : - Path2 Resync is copying files to - Path1 2024/04/17 05:21:52 INFO : 測試Русский ěáñ/測試_check file: Copied (new) 2024/04/17 05:21:52 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:21:52 INFO : - Path1 Resync is copying files to - Path2 2024/04/17 05:21:53 INFO : Resync updating listings 2024/04/17 05:21:53 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:53 INFO : Bisync successful 2024/04/17 05:21:53 (29) : bisync check-access check-filename=測試_check{spc}file 2024/04/17 05:21:56 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:21:56 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:21:56 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/17 05:21:56 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:56 INFO : Building Path1 and Path2 listings 2024/04/17 05:21:57 INFO : Path1 checking for diffs 2024/04/17 05:21:57 INFO : Path2 checking for diffs 2024/04/17 05:21:57 INFO : Checking access health 2024/04/17 05:21:57 INFO : Found 2 matching "測試_check file" files on both paths 2024/04/17 05:21:57 INFO : No changes found 2024/04/17 05:21:57 INFO : Updating listings 2024/04/17 05:21:57 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:21:57 INFO : Bisync successful 2024/04/17 05:21:57 (30) : move-listings check-access-pass 2024/04/17 05:21:57 (31) : test filters-file path with extended chars - masks /fileZ.txt 2024/04/17 05:21:57 (32) : copy-file /tmp/052042ni/datadir/test_ext_paths-homabip7/測試_filtersfile.txt /tmp/052042ni/workdir/ 2024/04/17 05:21:57 INFO : 測試_filtersfile.txt: Copied (new) 2024/04/17 05:21:57 (33) : bisync filters-file=/tmp/052042ni/workdir/測試_filtersfile.txt resync 2024/04/17 05:22:00 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:22:00 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:22:00 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/17 05:22:00 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:22:00 INFO : Using filters file /tmp/052042ni/workdir/測試_filtersfile.txt 2024/04/17 05:22:00 INFO : Storing filters file hash to /tmp/052042ni/workdir/測試_filtersfile.txt.md5 2024/04/17 05:22:00 INFO : Copying Path2 files to Path1 2024/04/17 05:22:00 INFO : - Path2 Resync is copying files to - Path1 2024/04/17 05:22:01 INFO : - Path1 Resync is copying files to - Path2 2024/04/17 05:22:02 INFO : Resync updating listings 2024/04/17 05:22:02 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:22:02 INFO : Bisync successful 2024/04/17 05:22:02 (34) : copy-as /tmp/052042ni/datadir/test_ext_paths-homabip7/file1.txt /tmp/052042ni/ext_paths/path1/ fileZ.txt 2024/04/17 05:22:02 INFO : file1.txt: Copied (new) to: fileZ.txt 2024/04/17 05:22:02 (35) : bisync filters-file=/tmp/052042ni/workdir/測試_filtersfile.txt 2024/04/17 05:22:05 INFO : 測試Русский ěáñ: Set directory modification time (using SetModTime) 2024/04/17 05:22:05 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/04/17 05:22:05 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/17 05:22:05 INFO : Synching Path1 "/tmp/052042ni/ext_paths/path1/" with Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:22:05 INFO : Using filters file /tmp/052042ni/workdir/測試_filtersfile.txt 2024/04/17 05:22:05 INFO : Building Path1 and Path2 listings 2024/04/17 05:22:06 INFO : Path1 checking for diffs 2024/04/17 05:22:06 INFO : Path2 checking for diffs 2024/04/17 05:22:06 INFO : No changes found 2024/04/17 05:22:06 INFO : Updating listings 2024/04/17 05:22:06 INFO : Validating listings for Path1 "/tmp/052042ni/ext_paths/path1/" vs Path2 "TestBox:rclone-test-becohix3boxevej1juvepec7/052042ni/ext_paths/path2/" 2024/04/17 05:22:06 INFO : Bisync successful bisync_test.go:539: TEST ext_paths PASSED 2024/04/17 05:22:10 DEBUG : box root 'rclone-test-becohix3boxevej1juvepec7': Purge remote --- PASS: TestBisyncLocalRemote (90.99s) --- PASS: TestBisyncLocalRemote/ext_paths (88.22s) PASS 2024/04/17 05:22:12 DEBUG : box root 'rclone-test-gaduhux7gevodic8hocitiz9': Purge remote 2024/04/17 05:22:12 DEBUG : box root 'rclone-test-gaduhux7gevodic8hocitiz9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/04/17 05:22:12 purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestBox: -verbose -test.run '^TestBisyncLocalRemote$/^ext_paths$'" - Finished OK in 1m32.784566972s (try 2/5)