"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBisyncLocalRemote|TestBisyncRemoteLocal)$/^(ext_paths|rclone_args|resync)$'" - Starting (try 3/5)
2024/04/14 02:12:59 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-kofific9xerocaq4qosigix3"
2024/04/14 02:12:59 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/14 02:12:59 DEBUG : Starting OpenDrive session with ID: 2bd3818c2cf783fd91231641879bda8315c4783060c73d46beb070a67e17c17d
2024/04/14 02:13:00 DEBUG : Creating backend with remote "/tmp/rclone3820857970"
=== RUN   TestBisyncRemoteLocal
2024/04/14 02:13:00 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0"
2024/04/14 02:13:00 DEBUG : Starting OpenDrive session with ID: 6e2177d4d0bb45a2ba0693ece5bca529bad8e1a88247b6c4b3945fbeb27bf689
2024/04/14 02:13:00 remote: TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0
=== RUN   TestBisyncRemoteLocal/ext_paths
2024/04/14 02:13:00 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we"
2024/04/14 02:13:00 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/14 02:13:00 DEBUG : Starting OpenDrive session with ID: 80243ec9d66d15789133bff1cfb230667e9bb2369824817b26dfd44b16a5f23d
2024/04/14 02:13:01 DEBUG : path1: Making directory
2024/04/14 02:13:02 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1"
2024/04/14 02:13:02 DEBUG : Starting OpenDrive session with ID: 9e81446193b4c0fdeaedc06b7f8b5058e39c8d5f806188b654bcdd1119125102
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/tmp/bs-021300-we"
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/tmp/bs-021300-we/ext_paths/path2"
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/initial"
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/tmp/bs-021300-we/initdir/test_ext_paths-laliway2"
2024/04/14 02:13:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2024/04/14 02:13:03 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z)
2024/04/14 02:13:03 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:03 DEBUG : file1.txt.lahowap8.partial: renamed to: file1.txt
2024/04/14 02:13:03 INFO  : file1.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:03 DEBUG : file_enconde_mañana_funcionará.txt.siyuqet1.partial: renamed to: file_enconde_mañana_funcionará.txt
2024/04/14 02:13:03 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:03 DEBUG : Русский.txt.fulaxeh7.partial: renamed to: Русский.txt
2024/04/14 02:13:03 INFO  : Русский.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:03 DEBUG : 測試_check file.ridadar0.partial: renamed to: 測試_check file
2024/04/14 02:13:03 INFO  : 測試_check file: Copied (new)
2024/04/14 02:13:03 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:03 DEBUG : filename_contains_ࢺ_.txt.rukufaz2.partial: renamed to: filename_contains_ࢺ_.txt
2024/04/14 02:13:03 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.hunakov4.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2024/04/14 02:13:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.rukesid1.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:13:03 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:13:03 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:03 DEBUG : filename_contains_ě_.txt.duleqif1.partial: renamed to: filename_contains_ě_.txt
2024/04/14 02:13:03 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:03 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.jigihey3.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2024/04/14 02:13:03 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_ext_paths-laliway2: Waiting for checks to finish
2024/04/14 02:13:03 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_ext_paths-laliway2: Waiting for transfers to finish
2024/04/14 02:13:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:13:03 DEBUG : RCLONE_TEST.lulikac2.partial: renamed to: RCLONE_TEST
2024/04/14 02:13:03 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:13:03 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ext_paths/modfiles"
2024/04/14 02:13:03 DEBUG : Creating backend with remote "/tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2"
2024/04/14 02:13:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:03 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2: Waiting for checks to finish
2024/04/14 02:13:03 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2: Waiting for transfers to finish
2024/04/14 02:13:03 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:13:03 DEBUG : file1.txt.bobimog5.partial: renamed to: file1.txt
2024/04/14 02:13:03 INFO  : file1.txt: Copied (new)
2024/04/14 02:13:03 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2024/04/14 02:13:03 DEBUG : 測試_filtersfile.txt.mifopog4.partial: renamed to: 測試_filtersfile.txt
2024/04/14 02:13:03 INFO  : 測試_filtersfile.txt: Copied (new)
2024/04/14 02:13:04 ERROR : : error listing: directory not found
2024/04/14 02:13:04 ERROR : : error listing: directory not found
2024/04/14 02:13:04 checking initFs Local file system at /tmp/bs-021300-we/initdir/test_ext_paths-laliway2
2024/04/14 02:13:04 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:04 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory
2024/04/14 02:13:05 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:05 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:05 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:05 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1': Waiting for checks to finish
2024/04/14 02:13:05 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1': Waiting for transfers to finish
2024/04/14 02:13:07 DEBUG : filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:13:08 DEBUG : filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:13:08 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:08 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:08 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:08 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:13:09 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:13:09 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:09 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:09 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:09 INFO  : file1.txt: Copied (new)
2024/04/14 02:13:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:13:09 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:13:10 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:13:10 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:13:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:11 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:11 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:11 INFO  : Русский.txt: Copied (new)
2024/04/14 02:13:11 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:11 INFO  : 測試_check file: Copied (new)
2024/04/14 02:13:11 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:11 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:13:15 checking Path1 OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1'
2024/04/14 02:13:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z)
2024/04/14 02:13:15 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:13:15 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 DEBUG : file1.txt.nineret5.partial: renamed to: file1.txt
2024/04/14 02:13:15 INFO  : file1.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : Local file system at /tmp/bs-021300-we/ext_paths/path2: Waiting for checks to finish
2024/04/14 02:13:15 DEBUG : Local file system at /tmp/bs-021300-we/ext_paths/path2: Waiting for transfers to finish
2024/04/14 02:13:15 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 DEBUG : file_enconde_mañana_funcionará.txt.wawugop0.partial: renamed to: file_enconde_mañana_funcionará.txt
2024/04/14 02:13:15 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:15 DEBUG : filename_contains_ࢺ_.txt.herarij6.partial: renamed to: filename_contains_ࢺ_.txt
2024/04/14 02:13:15 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 DEBUG : 測試_check file.zazemab3.partial: renamed to: 測試_check file
2024/04/14 02:13:15 INFO  : 測試_check file: Copied (new)
2024/04/14 02:13:15 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 DEBUG : Русский.txt.javisof0.partial: renamed to: Русский.txt
2024/04/14 02:13:15 INFO  : Русский.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.cepotul9.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.nanixik2.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:13:15 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.raqobov6.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:13:15 DEBUG : filename_contains_ě_.txt.duqacoz8.partial: renamed to: filename_contains_ě_.txt
2024/04/14 02:13:15 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:13:15 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:13:15 DEBUG : RCLONE_TEST.gehanix2.partial: renamed to: RCLONE_TEST
2024/04/14 02:13:15 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:13:15 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:15 checking path2 Local file system at /tmp/bs-021300-we/ext_paths/path2
2024/04/14 02:13:15 (01)  : test extended-char-paths
2024/04/14 02:13:15 (02)  : fix-names TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/
2024/04/14 02:13:21 ERROR : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-helevul0': Server side directory move failed: directory not found
2024/04/14 02:13:22 INFO  : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/04/14 02:13:23 ERROR : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-helevul0': error reading source root directory: directory not found
2024/04/14 02:13:23 (03)  : fix-names /tmp/bs-021300-we/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/
2024/04/14 02:13:23 ERROR : Local file system at /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-rinevaw4: Server side directory move failed: rename /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-rinevaw4: no such file or directory
2024/04/14 02:13:23 INFO  : Local file system at /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/04/14 02:13:23 ERROR : Local file system at /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-rinevaw4: error reading source root directory: directory not found
2024/04/14 02:13:23 (04)  : fix-names TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:13:26 (05)  : fix-names /tmp/bs-021300-we/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:13:26 (06)  : fix-names TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt
2024/04/14 02:13:27 (07)  : fix-names /tmp/bs-021300-we/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt
2024/04/14 02:13:27 (08)  : fix-names TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_check{spc}file
2024/04/14 02:13:28 (09)  : fix-names /tmp/bs-021300-we/ext_paths/path2/測試_check{spc}file
2024/04/14 02:13:28 (10)  : fix-names TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:13:30 (11)  : fix-names /tmp/bs-021300-we/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:13:30 (12)  : test resync subdirs with extended chars
2024/04/14 02:13:30 (13)  : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync
2024/04/14 02:13:33 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13:33 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/14 02:13:33 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:13:33 INFO  : Copying Path2 files to Path1
2024/04/14 02:13:33 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:13:33 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:13:33 INFO  : Resync updating listings
2024/04/14 02:13:33 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:13:33 INFO  : Bisync successful
2024/04/14 02:13:33 (14)  : copy-listings resync
2024/04/14 02:13:33 (15)  : test place new files with extended chars on each side
2024/04/14 02:13:33 (16)  : touch-glob 2001-01-02 /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2/ file1.txt
2024/04/14 02:13:33 (17)  : copy-as /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p1
2024/04/14 02:13:37 INFO  : file1.txt: Copied (new) to: 測試_file1p1
2024/04/14 02:13:37 (18)  : copy-as /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2/file1.txt /tmp/bs-021300-we/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p2
2024/04/14 02:13:37 INFO  : file1.txt: Copied (new) to: 測試_file1p2
2024/04/14 02:13:37 (19)  : test normal sync of subdirs with extended chars
2024/04/14 02:13:37 (20)  : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ
2024/04/14 02:13:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13:39 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/14 02:13:39 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:13:39 INFO  : Building Path1 and Path2 listings
2024/04/14 02:13:39 INFO  : Path1 checking for diffs
2024/04/14 02:13:39 INFO  : - Path1    File is new               - 測試_file1p1
2024/04/14 02:13:39 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2024/04/14 02:13:39 INFO  : Path2 checking for diffs
2024/04/14 02:13:39 INFO  : - Path2    File is new               - 測試_file1p2
2024/04/14 02:13:39 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2024/04/14 02:13:39 INFO  : Applying changes
2024/04/14 02:13:39 INFO  : - Path1    Queue copy to Path2       - /tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_file1p1
2024/04/14 02:13:39 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_file1p2
2024/04/14 02:13:39 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:13:42 INFO  : 測試_file1p2: Copied (new)
2024/04/14 02:13:42 INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:13:42 INFO  : 測試_file1p1: Copied (new)
2024/04/14 02:13:42 INFO  : Updating listings
2024/04/14 02:13:42 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:13:42 INFO  : Bisync successful
2024/04/14 02:13:42 (21)  : move-listings normal-sync
2024/04/14 02:13:42 (22)  : test check-filename with extended chars. check should fail.
2024/04/14 02:13:42 (23)  : bisync resync
2024/04/14 02:13:44 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:44 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13: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/14 02:13:44 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:44 INFO  : Copying Path2 files to Path1
2024/04/14 02:13:44 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:13:45 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:13:45 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:45 INFO  : Resync updating listings
2024/04/14 02:13:45 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:45 INFO  : Bisync successful
2024/04/14 02:13:45 (24)  : delete-file TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:13:46 INFO  : 測試_check file: Deleted
2024/04/14 02:13:46 (25)  : bisync check-access check-filename=測試_check{spc}file
2024/04/14 02:13:48 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13: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/14 02:13:48 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:48 INFO  : Building Path1 and Path2 listings
2024/04/14 02:13:48 INFO  : Path1 checking for diffs
2024/04/14 02:13:48 INFO  : - Path1    File was deleted          - 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:13:48 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2024/04/14 02:13:48 INFO  : Path2 checking for diffs
2024/04/14 02:13:48 INFO  : Checking access health
2024/04/14 02:13:48 ERROR : Access test failed: Path1 count 1, Path2 count 2 - 測試_check file
2024/04/14 02:13:48 ERROR : -          Access test failed: Path2 file not found in Path1 - 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:13:48 ERROR : Bisync critical error: check file check failed
2024/04/14 02:13:48 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:13:48 Bisync error: bisync aborted
2024/04/14 02:13:48 (26)  : copy-listings check-access-fail
2024/04/14 02:13:48 (27)  : test check-filename with extended chars. check should pass.
2024/04/14 02:13:48 (28)  : bisync resync
2024/04/14 02:13:50 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13: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/14 02:13:50 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:50 INFO  : Copying Path2 files to Path1
2024/04/14 02:13:50 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:13:53 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:13:53 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:13:53 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:53 INFO  : Resync updating listings
2024/04/14 02:13:53 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:53 INFO  : Bisync successful
2024/04/14 02:13:53 (29)  : bisync check-access check-filename=測試_check{spc}file
2024/04/14 02:13:55 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13: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/14 02:13:55 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:55 INFO  : Building Path1 and Path2 listings
2024/04/14 02:13:55 INFO  : Path1 checking for diffs
2024/04/14 02:13:55 INFO  : Path2 checking for diffs
2024/04/14 02:13:55 INFO  : Checking access health
2024/04/14 02:13:55 INFO  : Found 2 matching "測試_check file" files on both paths
2024/04/14 02:13:55 INFO  : No changes found
2024/04/14 02:13:55 INFO  : Updating listings
2024/04/14 02:13:55 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:55 INFO  : Bisync successful
2024/04/14 02:13:55 (30)  : move-listings check-access-pass
2024/04/14 02:13:55 (31)  : test filters-file path with extended chars - masks /fileZ.txt
2024/04/14 02:13:55 (32)  : copy-file /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2/測試_filtersfile.txt /tmp/bs-021300-we/workdir/
2024/04/14 02:13:55 INFO  : 測試_filtersfile.txt: Copied (new)
2024/04/14 02:13:55 (33)  : bisync filters-file=/tmp/bs-021300-we/workdir/測試_filtersfile.txt resync
2024/04/14 02:13:58 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:13:58 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/14 02:13:58 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:58 INFO  : Using filters file /tmp/bs-021300-we/workdir/測試_filtersfile.txt
2024/04/14 02:13:58 INFO  : Storing filters file hash to /tmp/bs-021300-we/workdir/測試_filtersfile.txt.md5
2024/04/14 02:13:58 INFO  : Copying Path2 files to Path1
2024/04/14 02:13:58 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:13:58 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:13:58 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:13:58 INFO  : Resync updating listings
2024/04/14 02:13:58 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:13:58 INFO  : Bisync successful
2024/04/14 02:13:58 (34)  : copy-as /tmp/bs-021300-we/datadir/test_ext_paths-peyaqab2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/ fileZ.txt
2024/04/14 02:14:01 INFO  : file1.txt: Copied (new) to: fileZ.txt
2024/04/14 02:14:01 (35)  : bisync filters-file=/tmp/bs-021300-we/workdir/測試_filtersfile.txt
2024/04/14 02:14:03 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:14:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:14:03 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/14 02:14:03 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" with Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:14:03 INFO  : Using filters file /tmp/bs-021300-we/workdir/測試_filtersfile.txt
2024/04/14 02:14:03 INFO  : Building Path1 and Path2 listings
2024/04/14 02:14:06 INFO  : Path1 checking for diffs
2024/04/14 02:14:06 INFO  : Path2 checking for diffs
2024/04/14 02:14:06 INFO  : No changes found
2024/04/14 02:14:06 INFO  : Updating listings
2024/04/14 02:14:06 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/ext_paths/path1/" vs Path2 "/tmp/bs-021300-we/ext_paths/path2/"
2024/04/14 02:14:06 INFO  : Bisync successful
    bisync_test.go:539: TEST ext_paths PASSED
=== RUN   TestBisyncRemoteLocal/rclone_args
2024/04/14 02:14:06 DEBUG : path1: Making directory
2024/04/14 02:14:06 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1"
2024/04/14 02:14:07 DEBUG : Starting OpenDrive session with ID: 5013fd49efe722486788a3f0a2dad2a6c9f0c7a51cd4c1bc791587645af1cc90
2024/04/14 02:14:08 DEBUG : Creating backend with remote "/tmp/bs-021300-we/rclone_args/path2"
2024/04/14 02:14:08 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/initial"
2024/04/14 02:14:08 DEBUG : Creating backend with remote "/tmp/bs-021300-we/initdir/test_rclone_args-cetozeh0"
2024/04/14 02:14:08 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : subdir: Making directory with metadata
2024/04/14 02:14:08 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/04/14 02:14:08 DEBUG : Added delayed dir = "subdir", newDst=subdir
2024/04/14 02:14:08 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_rclone_args-cetozeh0: Waiting for checks to finish
2024/04/14 02:14:08 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_rclone_args-cetozeh0: Waiting for transfers to finish
2024/04/14 02:14:08 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:08 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:08 DEBUG : subdir/file20.txt.peciwig1.partial: renamed to: subdir/file20.txt
2024/04/14 02:14:08 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : file2.txt.nuxigok6.partial: renamed to: file2.txt
2024/04/14 02:14:08 INFO  : file2.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:08 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:14:08 DEBUG : subdir/file21.txt.satekes8.partial: renamed to: subdir/file21.txt
2024/04/14 02:14:08 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : file1.txt.tejotuk2.partial: renamed to: file1.txt
2024/04/14 02:14:08 INFO  : file1.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:14:08 DEBUG : RCLONE_TEST.dekuzoh3.partial: renamed to: RCLONE_TEST
2024/04/14 02:14:08 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:14:08 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:08 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/modfiles"
2024/04/14 02:14:08 DEBUG : Creating backend with remote "/tmp/bs-021300-we/datadir/test_rclone_args-lepoper2"
2024/04/14 02:14:08 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2: Waiting for checks to finish
2024/04/14 02:14:08 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2: Waiting for transfers to finish
2024/04/14 02:14:08 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:14:08 DEBUG : file1.txt.yebebuv1.partial: renamed to: file1.txt
2024/04/14 02:14:08 INFO  : file1.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:08 DEBUG : file20.txt.jujeber0.partial: renamed to: file20.txt
2024/04/14 02:14:08 INFO  : file20.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:14:08 DEBUG : file2.txt.liposod7.partial: renamed to: file2.txt
2024/04/14 02:14:08 INFO  : file2.txt: Copied (new)
2024/04/14 02:14:08 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:08 DEBUG : file21.txt.duwemeb1.partial: renamed to: file21.txt
2024/04/14 02:14:08 INFO  : file21.txt: Copied (new)
2024/04/14 02:14:08 ERROR : : error listing: directory not found
2024/04/14 02:14:08 ERROR : : error listing: directory not found
2024/04/14 02:14:08 checking initFs Local file system at /tmp/bs-021300-we/initdir/test_rclone_args-cetozeh0
2024/04/14 02:14:08 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:14:08 DEBUG : subdir: Making directory
2024/04/14 02:14:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:09 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:09 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1': Waiting for checks to finish
2024/04/14 02:14:09 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1': Waiting for transfers to finish
2024/04/14 02:14:10 DEBUG : file1.txt: Uploading chunk 0, size=19, remain=0
2024/04/14 02:14:11 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:14:11 INFO  : file1.txt: Copied (new)
2024/04/14 02:14:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:11 INFO  : file2.txt: Copied (new)
2024/04/14 02:14:12 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:14:12 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:12 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:14:13 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:13 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:14:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:14:13 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:14:13 checking Path1 OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1'
2024/04/14 02:14:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:14:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:14 DEBUG : subdir: Making directory with metadata
2024/04/14 02:14:14 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/04/14 02:14:14 DEBUG : Added delayed dir = "subdir", newDst=subdir
2024/04/14 02:14:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:14 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:14:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:14 DEBUG : file2.txt.japifux7.partial: renamed to: file2.txt
2024/04/14 02:14:14 INFO  : file2.txt: Copied (new)
2024/04/14 02:14:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:14 DEBUG : subdir/file20.txt.cayegeb4.partial: renamed to: subdir/file20.txt
2024/04/14 02:14:14 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:14:14 DEBUG : Local file system at /tmp/bs-021300-we/rclone_args/path2: Waiting for checks to finish
2024/04/14 02:14:14 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:14:14 DEBUG : Local file system at /tmp/bs-021300-we/rclone_args/path2: Waiting for transfers to finish
2024/04/14 02:14:14 DEBUG : subdir/file21.txt.xubetax3.partial: renamed to: subdir/file21.txt
2024/04/14 02:14:14 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:14:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:14:14 DEBUG : RCLONE_TEST.puxuwow3.partial: renamed to: RCLONE_TEST
2024/04/14 02:14:14 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:14:14 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:14:14 DEBUG : file1.txt.fifixog2.partial: renamed to: file1.txt
2024/04/14 02:14:14 INFO  : file1.txt: Copied (new)
2024/04/14 02:14:14 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:14 checking path2 Local file system at /tmp/bs-021300-we/rclone_args/path2
2024/04/14 02:14:14 (01)  : test rclone-args
2024/04/14 02:14:14 (02)  : touch-glob 2001-01-02 /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/ *
2024/04/14 02:14:14 (03)  : test initial bisync
2024/04/14 02:14:14 (04)  : bisync resync checksum
2024/04/14 02:14:16 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:14:16 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:16 INFO  : Copying Path2 files to Path1
2024/04/14 02:14:16 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:14:16 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:14:16 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:17 INFO  : Resync updating listings
2024/04/14 02:14:17 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:17 INFO  : Bisync successful
2024/04/14 02:14:17 (05)  : test place newer files on both paths
2024/04/14 02:14:17 (06)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/
2024/04/14 02:14:19 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:19 (07)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file2.txt /tmp/bs-021300-we/rclone_args/path2/
2024/04/14 02:14:19 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:14:19 (08)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file20.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir
2024/04/14 02:14:23 INFO  : file20.txt: Copied (replaced existing)
2024/04/14 02:14:23 (09)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file21.txt /tmp/bs-021300-we/rclone_args/path2/subdir
2024/04/14 02:14:23 INFO  : file21.txt: Updated modification time in destination
2024/04/14 02:14:23 (10)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/ file1.txt
2024/04/14 02:14:25 (11)  : touch-glob 2001-01-02 /tmp/bs-021300-we/rclone_args/path2/ file2.txt
2024/04/14 02:14:25 (12)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:14:27 (13)  : touch-glob 2001-01-02 /tmp/bs-021300-we/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:14:27 (14)  : test run bisync with custom options
2024/04/14 02:14:27 (15)  : bisync checksum
2024/04/14 02:14:29 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:14:29 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:29 INFO  : Building Path1 and Path2 listings
2024/04/14 02:14:29 INFO  : Path1 checking for diffs
2024/04/14 02:14:29 INFO  : - Path1    File changed: hash - file1.txt
2024/04/14 02:14:29 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:14:29 INFO  : (Modified:    1 hash differs)
2024/04/14 02:14:29 INFO  : Path2 checking for diffs
2024/04/14 02:14:29 INFO  : - Path2    File changed: size (larger), hash - file2.txt
2024/04/14 02:14:29 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:14:29 INFO  : (Modified:    1 larger,    0 smaller,    1 hash differs)
2024/04/14 02:14:29 INFO  : Applying changes
2024/04/14 02:14:29 INFO  : - Path1    Queue copy to Path2       - /tmp/bs-021300-we/rclone_args/path2/file1.txt
2024/04/14 02:14:29 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/file2.txt
2024/04/14 02:14:29 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:14:30 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:14:30 INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:14:31 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:31 INFO  : Updating listings
2024/04/14 02:14:31 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:31 INFO  : Bisync successful
2024/04/14 02:14:31 (16)  : touch-glob 2007-07-23 /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/ *
2024/04/14 02:14:31 (17)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/
2024/04/14 02:14:33 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:33 (18)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file2.txt /tmp/bs-021300-we/rclone_args/path2/
2024/04/14 02:14:33 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:14:33 (19)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file20.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir
2024/04/14 02:14:37 INFO  : file20.txt: Copied (replaced existing)
2024/04/14 02:14:37 (20)  : copy-as /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file21.txt /tmp/bs-021300-we/rclone_args/path2/ file1.txt
2024/04/14 02:14:37 INFO  : file21.txt: Copied (replaced existing) to: file1.txt
2024/04/14 02:14:37 (21)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/ file1.txt
2024/04/14 02:14:37 (22)  : touch-glob 2007-07-23 /tmp/bs-021300-we/rclone_args/path2/ file2.txt
2024/04/14 02:14:37 (23)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:14:38 (24)  : touch-glob 2007-07-23 /tmp/bs-021300-we/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:14:38 (25)  : bisync size-only
2024/04/14 02:14:41 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:41 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:14:41 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/14 02:14:41 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:41 INFO  : Building Path1 and Path2 listings
2024/04/14 02:14:41 INFO  : Path1 checking for diffs
2024/04/14 02:14:41 INFO  : Path2 checking for diffs
2024/04/14 02:14:41 INFO  : - Path2    File changed: size (smaller) - file1.txt
2024/04/14 02:14:41 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:14:41 INFO  : (Modified:    0 larger,    1 smaller)
2024/04/14 02:14:41 INFO  : Applying changes
2024/04/14 02:14:41 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/file1.txt
2024/04/14 02:14:41 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:14:43 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:43 INFO  : Updating listings
2024/04/14 02:14:43 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:43 INFO  : Bisync successful
2024/04/14 02:14:43 (26)  : bisync resync
2024/04/14 02:14:45 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:14:45 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/14 02:14:45 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:45 INFO  : Copying Path2 files to Path1
2024/04/14 02:14:45 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:14:46 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:14:46 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:46 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:14:46 INFO  : subdir/file20.txt: Updated modification time in destination
2024/04/14 02:14:46 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:14:46 INFO  : Resync updating listings
2024/04/14 02:14:46 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:46 INFO  : Bisync successful
2024/04/14 02:14:46 (27)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/
2024/04/14 02:14:49 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:49 (28)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file2.txt /tmp/bs-021300-we/rclone_args/path2/
2024/04/14 02:14:49 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:14:49 (29)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file20.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir
2024/04/14 02:14:50 (30)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file21.txt /tmp/bs-021300-we/rclone_args/path2/subdir
2024/04/14 02:14:50 INFO  : file21.txt: Updated modification time in destination
2024/04/14 02:14:50 (31)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/ file1.txt
2024/04/14 02:14:50 (32)  : touch-glob 2007-07-23 /tmp/bs-021300-we/rclone_args/path2/ file2.txt
2024/04/14 02:14:50 (33)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:14:52 (34)  : touch-glob 2007-07-23 /tmp/bs-021300-we/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:14:52 (35)  : bisync ignore-size
2024/04/14 02:14:54 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:14:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": false,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/14 02:14:54 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:54 INFO  : Building Path1 and Path2 listings
2024/04/14 02:14:54 INFO  : Path1 checking for diffs
2024/04/14 02:14:54 INFO  : Path2 checking for diffs
2024/04/14 02:14:54 INFO  : - Path2    File changed: time (newer) - file2.txt
2024/04/14 02:14:54 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2024/04/14 02:14:54 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:14:54 INFO  : (Modified:    2 newer,    0 older)
2024/04/14 02:14:54 INFO  : Applying changes
2024/04/14 02:14:54 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/file2.txt
2024/04/14 02:14:54 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/file21.txt
2024/04/14 02:14:54 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:14:55 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:14:55 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:14:55 INFO  : Updating listings
2024/04/14 02:14:55 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:55 INFO  : Bisync successful
2024/04/14 02:14:55 (36)  : bisync resync compare-all
2024/04/14 02:14:57 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:57 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:14:57 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:57 INFO  : Copying Path2 files to Path1
2024/04/14 02:14:57 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:14:57 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:14:57 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:14:58 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:14:58 INFO  : Resync updating listings
2024/04/14 02:14:58 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:14:58 INFO  : Bisync successful
2024/04/14 02:14:58 (37)  : copy-as /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file21.txt /tmp/bs-021300-we/rclone_args/path2/ file2.txt
2024/04/14 02:14:58 INFO  : file21.txt: Copied (replaced existing) to: file2.txt
2024/04/14 02:14:58 (38)  : touch-glob 2023-08-26 /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/ *
2024/04/14 02:14:58 (39)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file1.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/
2024/04/14 02:15:00 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:15:00 (40)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file20.txt TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir
2024/04/14 02:15:02 INFO  : file20.txt: Copied (replaced existing)
2024/04/14 02:15:02 (41)  : copy-file /tmp/bs-021300-we/datadir/test_rclone_args-lepoper2/file21.txt /tmp/bs-021300-we/rclone_args/path2/subdir
2024/04/14 02:15:02 INFO  : file21.txt: Updated modification time in destination
2024/04/14 02:15:02 (42)  : touch-glob 2007-07-23 /tmp/bs-021300-we/rclone_args/path2/ file2.txt
2024/04/14 02:15:02 (43)  : touch-glob 2023-08-26 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/ file1.txt
2024/04/14 02:15:03 (44)  : touch-glob 2023-08-26 TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:15:03 (45)  : touch-glob 2023-08-26 /tmp/bs-021300-we/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:15:03 (46)  : bisync compare-all
2024/04/14 02:15:05 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:15:05 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:15:05 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" with Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:15:05 INFO  : Building Path1 and Path2 listings
2024/04/14 02:15:06 INFO  : Path1 checking for diffs
2024/04/14 02:15:06 INFO  : - Path1    File changed: time (newer) - file1.txt
2024/04/14 02:15:06 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2024/04/14 02:15:06 INFO  : Path1:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:15:06 INFO  : (Modified:    2 newer,    0 older)
2024/04/14 02:15:06 INFO  : Path2 checking for diffs
2024/04/14 02:15:06 INFO  : - Path2    File changed: size (smaller), hash - file2.txt
2024/04/14 02:15:06 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2024/04/14 02:15:06 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:15:06 INFO  : (Modified:    1 newer,    0 older,    0 larger,    1 smaller,    1 hash differs)
2024/04/14 02:15:06 INFO  : Applying changes
2024/04/14 02:15:06 INFO  : - Path1    Queue copy to Path2       - /tmp/bs-021300-we/rclone_args/path2/file1.txt
2024/04/14 02:15:06 INFO  : - Path1    Queue copy to Path2       - /tmp/bs-021300-we/rclone_args/path2/subdir/file20.txt
2024/04/14 02:15:06 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/file2.txt
2024/04/14 02:15:06 INFO  : - Path2    Queue copy to Path1       - TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/subdir/file21.txt
2024/04/14 02:15:06 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:15:06 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:15:07 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:15:07 INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:15:09 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:15:09 INFO  : file1.txt: Updated modification time in destination
2024/04/14 02:15:09 INFO  : subdir/file20.txt: Updated modification time in destination
2024/04/14 02:15:09 INFO  : Updating listings
2024/04/14 02:15:09 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/rclone_args/path1/" vs Path2 "/tmp/bs-021300-we/rclone_args/path2/"
2024/04/14 02:15:09 INFO  : Bisync successful
    bisync_test.go:539: TEST rclone_args PASSED
=== RUN   TestBisyncRemoteLocal/resync
2024/04/14 02:15:09 DEBUG : path1: Making directory
2024/04/14 02:15:09 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1"
2024/04/14 02:15:10 DEBUG : Starting OpenDrive session with ID: 0d813b0ad4cc36784e7cef2ce20c67afe979ed4c6eea0c1aa7aca3af702e7dad
2024/04/14 02:15:11 DEBUG : Creating backend with remote "/tmp/bs-021300-we/resync/path2"
2024/04/14 02:15:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2024/04/14 02:15:11 DEBUG : Creating backend with remote "/tmp/bs-021300-we/initdir/test_resync-hubugeh9"
2024/04/14 02:15:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file1.txt.xodubij6.partial: renamed to: file1.txt
2024/04/14 02:15:11 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file2.txt.tenedag8.partial: renamed to: file2.txt
2024/04/14 02:15:11 INFO  : file2.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file3.txt.refinic8.partial: renamed to: file3.txt
2024/04/14 02:15:11 INFO  : file3.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file4.txt.xidekis9.partial: renamed to: file4.txt
2024/04/14 02:15:11 INFO  : file4.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file5.txt.zoliyos5.partial: renamed to: file5.txt
2024/04/14 02:15:11 INFO  : file5.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file6.txt.qikifeb1.partial: renamed to: file6.txt
2024/04/14 02:15:11 INFO  : file6.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:11 DEBUG : file7.txt.fojelar1.partial: renamed to: file7.txt
2024/04/14 02:15:11 INFO  : file7.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_resync-hubugeh9: Waiting for checks to finish
2024/04/14 02:15:11 DEBUG : Local file system at /tmp/bs-021300-we/initdir/test_resync-hubugeh9: Waiting for transfers to finish
2024/04/14 02:15:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:11 DEBUG : RCLONE_TEST.tiluyam2.partial: renamed to: RCLONE_TEST
2024/04/14 02:15:11 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2024/04/14 02:15:11 DEBUG : Creating backend with remote "/tmp/bs-021300-we/datadir/test_resync-nufosur3"
2024/04/14 02:15:11 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_resync-nufosur3: Waiting for checks to finish
2024/04/14 02:15:11 DEBUG : Local file system at /tmp/bs-021300-we/datadir/test_resync-nufosur3: Waiting for transfers to finish
2024/04/14 02:15:11 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:15:11 DEBUG : fileB.txt.donuxow2.partial: renamed to: fileB.txt
2024/04/14 02:15:11 INFO  : fileB.txt: Copied (new)
2024/04/14 02:15:11 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:15:11 DEBUG : fileA.txt.kovexor6.partial: renamed to: fileA.txt
2024/04/14 02:15:11 INFO  : fileA.txt: Copied (new)
2024/04/14 02:15:11 ERROR : : error listing: directory not found
2024/04/14 02:15:11 ERROR : : error listing: directory not found
2024/04/14 02:15:11 checking initFs Local file system at /tmp/bs-021300-we/initdir/test_resync-hubugeh9
2024/04/14 02:15:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:11 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1': Waiting for checks to finish
2024/04/14 02:15:11 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1': Waiting for transfers to finish
2024/04/14 02:15:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:14 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:16 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:16 INFO  : file4.txt: Copied (new)
2024/04/14 02:15:17 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:17 INFO  : file3.txt: Copied (new)
2024/04/14 02:15:17 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:17 INFO  : file5.txt: Copied (new)
2024/04/14 02:15:18 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:15:19 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:19 INFO  : file2.txt: Copied (new)
2024/04/14 02:15:20 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:20 INFO  : file7.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 INFO  : file6.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:21 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:21 checking Path1 OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1'
2024/04/14 02:15:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : Local file system at /tmp/bs-021300-we/resync/path2: Waiting for checks to finish
2024/04/14 02:15:21 DEBUG : Local file system at /tmp/bs-021300-we/resync/path2: Waiting for transfers to finish
2024/04/14 02:15:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file2.txt.pegawab9.partial: renamed to: file2.txt
2024/04/14 02:15:21 INFO  : file2.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file1.txt.ziboroj1.partial: renamed to: file1.txt
2024/04/14 02:15:21 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file4.txt.nubuzal4.partial: renamed to: file4.txt
2024/04/14 02:15:21 INFO  : file4.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file6.txt.soqacup9.partial: renamed to: file6.txt
2024/04/14 02:15:21 INFO  : file6.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:21 DEBUG : file3.txt.dofegur2.partial: renamed to: file3.txt
2024/04/14 02:15:21 INFO  : file3.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : file5.txt.rozubed2.partial: renamed to: file5.txt
2024/04/14 02:15:21 DEBUG : file7.txt.yakimer1.partial: renamed to: file7.txt
2024/04/14 02:15:21 INFO  : file7.txt: Copied (new)
2024/04/14 02:15:21 INFO  : file5.txt: Copied (new)
2024/04/14 02:15:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:21 DEBUG : RCLONE_TEST.zuruliv9.partial: renamed to: RCLONE_TEST
2024/04/14 02:15:21 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:21 checking path2 Local file system at /tmp/bs-021300-we/resync/path2
2024/04/14 02:15:21 (01)  : test resync
2024/04/14 02:15:21 (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2024/04/14 02:15:21 (03)  : purge-children TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1/
2024/04/14 02:15:23 (04)  : bisync resync
2024/04/14 02:15:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:15:25 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/14 02:15:25 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1/" with Path2 "/tmp/bs-021300-we/resync/path2/"
2024/04/14 02:15:25 INFO  : Copying Path2 files to Path1
2024/04/14 02:15:25 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:15:27 INFO  : file4.txt: Copied (new)
2024/04/14 02:15:27 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:28 INFO  : file2.txt: Copied (new)
2024/04/14 02:15:28 INFO  : file3.txt: Copied (new)
2024/04/14 02:15:29 INFO  : file7.txt: Copied (new)
2024/04/14 02:15:30 INFO  : file5.txt: Copied (new)
2024/04/14 02:15:30 INFO  : file6.txt: Copied (new)
2024/04/14 02:15:32 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:32 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:15:32 INFO  : Resync updating listings
2024/04/14 02:15:32 INFO  : Validating listings for Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1/" vs Path2 "/tmp/bs-021300-we/resync/path2/"
2024/04/14 02:15:32 INFO  : Bisync successful
2024/04/14 02:15:32 (05)  : move-listings empty-path1
2024/04/14 02:15:32 (06)  : test 2. resync with empty path2, resulting in synching all content to path2.
2024/04/14 02:15:32 (07)  : purge-children /tmp/bs-021300-we/resync/path2/
2024/04/14 02:15:32 (08)  : bisync resync
2024/04/14 02:15:34 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:15:34 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/14 02:15:34 INFO  : Synching Path1 "TestOpenDrive:rclone-test-wohuvub2bumemix1letagag0/bs-021300-we/resync/path1/" with Path2 "/tmp/bs-021300-we/resync/path2/"
2024/04/14 02:15:34 INFO  : Copying Path2 files to Path1
2024/04/14 02:15:34 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:15:34 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:15:35 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:35 ERROR : file1.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file2.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file3.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file5.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file6.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file7.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : file4.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:15:35 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:15:35 Bisync error: bisync aborted
2024/04/14 02:15:35 (09)  : move-listings empty-path2
2024/04/14 02:15:35 (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2024/04/14 02:15:35 (11)  : touch-glob 2002-02-02 /tmp/bs-021300-we/datadir/test_resync-nufosur3/ fileA.txt
2024/04/14 02:15:35 (12)  : touch-glob 1999-09-09 /tmp/bs-021300-we/datadir/test_resync-nufosur3/ fileB.txt
2024/04/14 02:15:35 (13)  : test = file      - path1      - path2      - expected action    - who wins
2024/04/14 02:15:35 (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2024/04/14 02:15:35 (15)  : delete-file /tmp/bs-021300-we/resync/path2/file1.txt
    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:       	TestBisyncRemoteLocal/resync
        	Messages:   	step 15 failed: object not found
2024/04/14 02:15:35 DEBUG : OpenDrive root 'rclone-test-wohuvub2bumemix1letagag0': Purge remote
--- FAIL: TestBisyncRemoteLocal (156.24s)
    --- PASS: TestBisyncRemoteLocal/ext_paths (66.10s)
    --- PASS: TestBisyncRemoteLocal/rclone_args (63.03s)
    --- FAIL: TestBisyncRemoteLocal/resync (25.83s)
=== RUN   TestBisyncLocalRemote
2024/04/14 02:15:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3"
2024/04/14 02:15:36 DEBUG : Starting OpenDrive session with ID: bd379a2392376efa33c551cf608eebcae564153dac8432f14b08b53fc24deb9b
2024/04/14 02:15:36 remote: TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3
=== RUN   TestBisyncLocalRemote/ext_paths
2024/04/14 02:15:36 DEBUG : Creating backend with remote "/tmp/bs-021536-nu"
2024/04/14 02:15:36 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/14 02:15:36 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/ext_paths/path1"
2024/04/14 02:15:36 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu"
2024/04/14 02:15:37 DEBUG : Starting OpenDrive session with ID: e3d3ef3fcb91595afb7cb20ee520507d4f74a7b5a2e16bfe3aca9266f97038bf
2024/04/14 02:15:37 DEBUG : path2: Making directory
2024/04/14 02:15:38 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2"
2024/04/14 02:15:38 DEBUG : Starting OpenDrive session with ID: f4f0f856c2070dffa560374dbd307b4fb86e9663647e7e6446ca8bda994c0427
2024/04/14 02:15:40 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/initdir/test_ext_paths-xojizic7"
2024/04/14 02:15:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z)
2024/04/14 02:15:40 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : file1.txt.cagifar7.partial: renamed to: file1.txt
2024/04/14 02:15:40 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_ext_paths-xojizic7: Waiting for checks to finish
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_ext_paths-xojizic7: Waiting for transfers to finish
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt.cedikes7.partial: renamed to: file_enconde_mañana_funcionará.txt
2024/04/14 02:15:40 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:40 DEBUG : RCLONE_TEST.jayenat0.partial: renamed to: RCLONE_TEST
2024/04/14 02:15:40 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : 測試_check file.duqoxan5.partial: renamed to: 測試_check file
2024/04/14 02:15:40 INFO  : 測試_check file: Copied (new)
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt.jirisot2.partial: renamed to: filename_contains_ě_.txt
2024/04/14 02:15:40 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : Русский.txt.xiwumam2.partial: renamed to: Русский.txt
2024/04/14 02:15:40 INFO  : Русский.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.joditaw7.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.caxomib6.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt.fufavew6.partial: renamed to: filename_contains_ࢺ_.txt
2024/04/14 02:15:40 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.regohad0.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:15:40 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5"
2024/04/14 02:15:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_filtersfile.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5: Waiting for checks to finish
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5: Waiting for transfers to finish
2024/04/14 02:15:40 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:15:40 DEBUG : file1.txt.wezokox1.partial: renamed to: file1.txt
2024/04/14 02:15:40 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_filtersfile.txt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2024/04/14 02:15:40 DEBUG : 測試_filtersfile.txt.qekuguq2.partial: renamed to: 測試_filtersfile.txt
2024/04/14 02:15:40 INFO  : 測試_filtersfile.txt: Copied (new)
2024/04/14 02:15:40 ERROR : : error listing: directory not found
2024/04/14 02:15:40 ERROR : : error listing: directory not found
2024/04/14 02:15:40 checking initFs Local file system at /tmp/bs-021536-nu/initdir/test_ext_paths-xojizic7
2024/04/14 02:15:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory with metadata
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ: Made directory with metadata (mtime=2024-04-12T01:00:04.064484529Z)
2024/04/14 02:15:40 DEBUG : Added delayed dir = "測試_Русский_ _ _ě_áñ", newDst=測試_Русский_ _ _ě_áñ
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/ext_paths/path1: Waiting for checks to finish
2024/04/14 02:15:40 DEBUG : Local file system at /tmp/bs-021536-nu/ext_paths/path1: Waiting for transfers to finish
2024/04/14 02:15:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : file1.txt.higiveg3.partial: renamed to: file1.txt
2024/04/14 02:15:40 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : RCLONE_TEST.qulevoq8.partial: renamed to: RCLONE_TEST
2024/04/14 02:15:40 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt.lurazup2.partial: renamed to: file_enconde_mañana_funcionará.txt
2024/04/14 02:15:40 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : 測試_check file.xocivit6.partial: renamed to: 測試_check file
2024/04/14 02:15:40 INFO  : 測試_check file: Copied (new)
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt.pisaval3.partial: renamed to: filename_contains_ě_.txt
2024/04/14 02:15:40 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt.qovaras4.partial: renamed to: filename_contains_ࢺ_.txt
2024/04/14 02:15:40 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file.mosilok7.partial: renamed to: 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt.feduqaz0.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt.rezuhex5.partial: renamed to: 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:40 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:40 DEBUG : Русский.txt.vujucir4.partial: renamed to: Русский.txt
2024/04/14 02:15:40 INFO  : Русский.txt: Copied (new)
2024/04/14 02:15:40 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:15:40 checking Path1 Local file system at /tmp/bs-021536-nu/ext_paths/path1
2024/04/14 02:15:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : file_enconde_mañana_funcionará.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : Русский.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:40 DEBUG : 測試_Русский_ _ _ě_áñ: Making directory
2024/04/14 02:15:41 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:41 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Need to transfer - File not found at Destination
2024/04/14 02:15:41 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: Need to transfer - File not found at Destination
2024/04/14 02:15:41 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2': Waiting for checks to finish
2024/04/14 02:15:41 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2': Waiting for transfers to finish
2024/04/14 02:15:43 DEBUG : filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:15:43 DEBUG : filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:15:43 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:43 INFO  : file1.txt: Copied (new)
2024/04/14 02:15:44 DEBUG : filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:44 INFO  : filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:44 DEBUG : file_enconde_mañana_funcionará.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:44 INFO  : file_enconde_mañana_funcionará.txt: Copied (new)
2024/04/14 02:15:44 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:15:45 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:15:45 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:15:45 DEBUG : filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:45 INFO  : filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:45 DEBUG : Русский.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:45 INFO  : Русский.txt: Copied (new)
2024/04/14 02:15:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:15:46 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Uploading chunk 0, size=272, remain=0
2024/04/14 02:15:46 DEBUG : 測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:46 INFO  : 測試_check file: Copied (new)
2024/04/14 02:15:47 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:47 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt: Copied (new)
2024/04/14 02:15:47 DEBUG : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: md5 = 0033328434f9662a7dae0d1aee7768b6 OK
2024/04/14 02:15:47 INFO  : 測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt: Copied (new)
2024/04/14 02:15:47 DEBUG : 測試_Русский_ _ _ě_áñ/測試_check file: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:15:47 INFO  : 測試_Русский_ _ _ě_áñ/測試_check file: Copied (new)
2024/04/14 02:15:47 checking path2 OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2'
2024/04/14 02:15:48 (01)  : test extended-char-paths
2024/04/14 02:15:48 (02)  : fix-names /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/
2024/04/14 02:15:48 ERROR : Local file system at /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-qihotey8: Server side directory move failed: rename /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-qihotey8: no such file or directory
2024/04/14 02:15:48 INFO  : Local file system at /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/04/14 02:15:48 ERROR : Local file system at /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ-rclone-move-qihotey8: error reading source root directory: directory not found
2024/04/14 02:15:48 (03)  : fix-names TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/
2024/04/14 02:15:53 ERROR : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-beficih0': Server side directory move failed: directory not found
2024/04/14 02:15:54 INFO  : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists
2024/04/14 02:15:55 ERROR : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ-rclone-move-beficih0': error reading source root directory: directory not found
2024/04/14 02:15:55 (04)  : fix-names /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:15:55 (05)  : fix-names TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:15:57 (06)  : fix-names /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt
2024/04/14 02:15:57 (07)  : fix-names TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/filename_contains_ě_.txt
2024/04/14 02:15:58 (08)  : fix-names /tmp/bs-021536-nu/ext_paths/path1/測試_check{spc}file
2024/04/14 02:15:58 (09)  : fix-names TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_check{spc}file
2024/04/14 02:15:59 (10)  : fix-names /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:15:59 (11)  : fix-names TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:16:02 (12)  : test resync subdirs with extended chars
2024/04/14 02:16:02 (13)  : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ resync
2024/04/14 02:16:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:04 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/14 02:16:04 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:16:04 INFO  : Copying Path2 files to Path1
2024/04/14 02:16:04 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:16:05 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:16:05 INFO  : Resync updating listings
2024/04/14 02:16:05 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:16:05 INFO  : Bisync successful
2024/04/14 02:16:05 (14)  : copy-listings resync
2024/04/14 02:16:05 (15)  : test place new files with extended chars on each side
2024/04/14 02:16:05 (16)  : touch-glob 2001-01-02 /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5/ file1.txt
2024/04/14 02:16:05 (17)  : copy-as /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5/file1.txt /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p1
2024/04/14 02:16:05 INFO  : file1.txt: Copied (new) to: 測試_file1p1
2024/04/14 02:16:05 (18)  : copy-as /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5/file1.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_{spc}_{spc}_ě_áñ 測試_file1p2
2024/04/14 02:16:08 INFO  : file1.txt: Copied (new) to: 測試_file1p2
2024/04/14 02:16:08 (19)  : test normal sync of subdirs with extended chars
2024/04/14 02:16:08 (20)  : bisync subdir=測試_Русский_{spc}_{spc}_ě_áñ
2024/04/14 02:16:10 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:10 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/14 02:16:10 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:16:10 INFO  : Building Path1 and Path2 listings
2024/04/14 02:16:10 INFO  : Path1 checking for diffs
2024/04/14 02:16:10 INFO  : - Path1    File is new               - 測試_file1p1
2024/04/14 02:16:10 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2024/04/14 02:16:10 INFO  : Path2 checking for diffs
2024/04/14 02:16:10 INFO  : - Path2    File is new               - 測試_file1p2
2024/04/14 02:16:10 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2024/04/14 02:16:10 INFO  : Applying changes
2024/04/14 02:16:10 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ/測試_file1p1
2024/04/14 02:16:10 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ/測試_file1p2
2024/04/14 02:16:10 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:16:11 INFO  : 測試_file1p2: Copied (new)
2024/04/14 02:16:11 INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:16:13 INFO  : 測試_file1p1: Copied (new)
2024/04/14 02:16:13 INFO  : Updating listings
2024/04/14 02:16:13 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/ext_paths/path1/測試_Русский_ _ _ě_áñ/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/測試_Русский_ _ _ě_áñ/"
2024/04/14 02:16:13 INFO  : Bisync successful
2024/04/14 02:16:13 (21)  : move-listings normal-sync
2024/04/14 02:16:13 (22)  : test check-filename with extended chars. check should fail.
2024/04/14 02:16:13 (23)  : bisync resync
2024/04/14 02:16:15 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:15 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:15 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/14 02:16:15 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:15 INFO  : Copying Path2 files to Path1
2024/04/14 02:16:15 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:16:15 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:16:16 INFO  : Resync updating listings
2024/04/14 02:16:16 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/ext_paths/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:16 INFO  : Bisync successful
2024/04/14 02:16:16 (24)  : delete-file /tmp/bs-021536-nu/ext_paths/path1/測試_Русский_{spc}_{spc}_ě_áñ/測試_check{spc}file
2024/04/14 02:16:16 INFO  : 測試_check file: Deleted
2024/04/14 02:16:16 (25)  : bisync check-access check-filename=測試_check{spc}file
2024/04/14 02:16:18 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:18 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/14 02:16:18 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:18 INFO  : Building Path1 and Path2 listings
2024/04/14 02:16:18 INFO  : Path1 checking for diffs
2024/04/14 02:16:18 INFO  : - Path1    File was deleted          - 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:16:18 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2024/04/14 02:16:18 INFO  : Path2 checking for diffs
2024/04/14 02:16:18 INFO  : Checking access health
2024/04/14 02:16:18 ERROR : Access test failed: Path1 count 1, Path2 count 2 - 測試_check file
2024/04/14 02:16:18 ERROR : -          Access test failed: Path2 file not found in Path1 - 測試_Русский_ _ _ě_áñ/測試_check file
2024/04/14 02:16:18 ERROR : Bisync critical error: check file check failed
2024/04/14 02:16:18 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:18 Bisync error: bisync aborted
2024/04/14 02:16:18 (26)  : copy-listings check-access-fail
2024/04/14 02:16:18 (27)  : test check-filename with extended chars. check should pass.
2024/04/14 02:16:18 (28)  : bisync resync
2024/04/14 02:16:20 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:20 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16: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/14 02:16:20 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:20 INFO  : Copying Path2 files to Path1
2024/04/14 02:16:20 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:16:23 ERROR : 測試_Русский_ _ _ě_áñ/測試_check file: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:23 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:23 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:23 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:23 Bisync error: bisync aborted
2024/04/14 02:16:23 (29)  : bisync check-access check-filename=測試_check{spc}file
2024/04/14 02:16:25 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:25 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/14 02:16:25 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:25 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-021536-nu/workdir/tmp_bs-021536-nu_ext_paths_path1..TestOpenDrive_rclone-test-fafilay7tizirod9nicijuz3_bs-021536-nu_ext_paths_path2.path1.lst
Path2: /tmp/bs-021536-nu/workdir/tmp_bs-021536-nu_ext_paths_path1..TestOpenDrive_rclone-test-fafilay7tizirod9nicijuz3_bs-021536-nu_ext_paths_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/bs-021536-nu/workdir"
2024/04/14 02:16:25 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:25 Bisync error: bisync aborted
2024/04/14 02:16:25 (30)  : move-listings check-access-pass
2024/04/14 02:16:25 (31)  : test filters-file path with extended chars - masks /fileZ.txt
2024/04/14 02:16:25 (32)  : copy-file /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5/測試_filtersfile.txt /tmp/bs-021536-nu/workdir/
2024/04/14 02:16:25 INFO  : 測試_filtersfile.txt: Copied (new)
2024/04/14 02:16:25 (33)  : bisync filters-file=/tmp/bs-021536-nu/workdir/測試_filtersfile.txt resync
2024/04/14 02:16:27 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:27 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16: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/14 02:16:27 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:27 INFO  : Using filters file /tmp/bs-021536-nu/workdir/測試_filtersfile.txt
2024/04/14 02:16:27 INFO  : Storing filters file hash to /tmp/bs-021536-nu/workdir/測試_filtersfile.txt.md5
2024/04/14 02:16:27 INFO  : Copying Path2 files to Path1
2024/04/14 02:16:27 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:16:27 ERROR : 測試_Русский_ _ _ě_áñ/測試_check file: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:27 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:27 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:27 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:27 Bisync error: bisync aborted
2024/04/14 02:16:27 (34)  : copy-as /tmp/bs-021536-nu/datadir/test_ext_paths-buqahoj5/file1.txt /tmp/bs-021536-nu/ext_paths/path1/ fileZ.txt
2024/04/14 02:16:27 INFO  : file1.txt: Copied (new) to: fileZ.txt
2024/04/14 02:16:27 (35)  : bisync filters-file=/tmp/bs-021536-nu/workdir/測試_filtersfile.txt
2024/04/14 02:16:29 INFO  : 測試_Русский_ _ _ě_áñ: Set directory modification time (using SetModTime)
2024/04/14 02:16:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:16:29 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/14 02:16:29 INFO  : Synching Path1 "/tmp/bs-021536-nu/ext_paths/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/ext_paths/path2/"
2024/04/14 02:16:29 INFO  : Using filters file /tmp/bs-021536-nu/workdir/測試_filtersfile.txt
2024/04/14 02:16:29 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-021536-nu/workdir/tmp_bs-021536-nu_ext_paths_path1..TestOpenDrive_rclone-test-fafilay7tizirod9nicijuz3_bs-021536-nu_ext_paths_path2.path1.lst
Path2: /tmp/bs-021536-nu/workdir/tmp_bs-021536-nu_ext_paths_path1..TestOpenDrive_rclone-test-fafilay7tizirod9nicijuz3_bs-021536-nu_ext_paths_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/bs-021536-nu/workdir"
2024/04/14 02:16:29 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:29 Bisync error: bisync aborted
2024/04/14 02:16:29 ----------------------------------------------------------
2024/04/14 02:16:29 MISCOMPARE - Number of Golden and Results files do not match:
2024/04/14 02:16:29   Golden count: 33
2024/04/14 02:16:29   Result count: 25
2024/04/14 02:16:29   Golden files: _testdir_path1.._testdir_path2.path1.lst, _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path1.lst-old, _testdir_path1.._testdir_path2.path2.lst, _testdir_path1.._testdir_path2.path2.lst-new, _testdir_path1.._testdir_path2.path2.lst-old, check-access-fail._testdir_path1.._testdir_path2.path1.lst-err, check-access-fail._testdir_path1.._testdir_path2.path1.lst-new, check-access-fail._testdir_path1.._testdir_path2.path2.lst-err, check-access-fail._testdir_path1.._testdir_path2.path2.lst-new, check-access-pass._testdir_path1.._testdir_path2.path1.lst, check-access-pass._testdir_path1.._testdir_path2.path1.lst-err, check-access-pass._testdir_path1.._testdir_path2.path1.lst-new, check-access-pass._testdir_path1.._testdir_path2.path1.lst-old, check-access-pass._testdir_path1.._testdir_path2.path2.lst, check-access-pass._testdir_path1.._testdir_path2.path2.lst-err, check-access-pass._testdir_path1.._testdir_path2.path2.lst-new, check-access-pass._testdir_path1.._testdir_path2.path2.lst-old, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.copy1to2.que, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.copy2to1.que, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-new, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-old, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-new, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-old, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-new, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-new, test.log, 測試_filtersfile.txt, 測試_filtersfile.txt.md5
2024/04/14 02:16:29   Result files: _testdir_path1.._testdir_path2.path1.lst-new, _testdir_path1.._testdir_path2.path2.lst-new, check-access-fail._testdir_path1.._testdir_path2.path1.lst-err, check-access-fail._testdir_path1.._testdir_path2.path1.lst-new, check-access-fail._testdir_path1.._testdir_path2.path2.lst-err, check-access-fail._testdir_path1.._testdir_path2.path2.lst-new, check-access-pass._testdir_path1.._testdir_path2.path1.lst-err, check-access-pass._testdir_path1.._testdir_path2.path1.lst-new, check-access-pass._testdir_path1.._testdir_path2.path2.lst-err, check-access-pass._testdir_path1.._testdir_path2.path2.lst-new, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.copy1to2.que, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.copy2to1.que, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-new, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-old, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-new, normal-sync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-old, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path1.lst-new, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst, resync._testdir_path1_測試_Русский_____ě_áñ.._testdir_path2_測試_Русский_____ě_áñ.path2.lst-new, test.log, 測試_filtersfile.txt, 測試_filtersfile.txt.md5
2024/04/14 02:16:29   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path1.lst
2024/04/14 02:16:29   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path1.lst-old
2024/04/14 02:16:29   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path2.lst
2024/04/14 02:16:29   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path2.lst-old
2024/04/14 02:16:29   File found in Golden but not in Results:  check-access-pass._testdir_path1.._testdir_path2.path1.lst
2024/04/14 02:16:29   File found in Golden but not in Results:  check-access-pass._testdir_path1.._testdir_path2.path1.lst-old
2024/04/14 02:16:29   File found in Golden but not in Results:  check-access-pass._testdir_path1.._testdir_path2.path2.lst
2024/04/14 02:16:29   File found in Golden but not in Results:  check-access-pass._testdir_path1.._testdir_path2.path2.lst-old
2024/04/14 02:16:29 ----------------------------------------------------------
2024/04/14 02:16:29 | MISCOMPARE  -Golden vs +Results for  _testdir_path1.._testdir_path2.path1.lst-new
2024/04/14 02:16:29 | @@ -1 +0,0 @@
2024/04/14 02:16:29 | --      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:16:29 | @@ -3,22 +1,0 @@
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file_enconde_mañana_funcionará.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"Русский.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p1\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p2\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 ----------------------------------------------------------
2024/04/14 02:16:29 | MISCOMPARE  -Golden vs +Results for  _testdir_path1.._testdir_path2.path2.lst-new
2024/04/14 02:16:29 | @@ -1 +0,0 @@
2024/04/14 02:16:29 | --      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:16:29 | @@ -3,22 +1,0 @@
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file_enconde_mañana_funcionará.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"Русский.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p1\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p2\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 ----------------------------------------------------------
2024/04/14 02:16:29 | MISCOMPARE  -Golden vs +Results for  check-access-pass._testdir_path1.._testdir_path2.path1.lst-new
2024/04/14 02:16:29 | @@ -1 +0,0 @@
2024/04/14 02:16:29 | --      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:16:29 | @@ -3,22 +1,0 @@
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file_enconde_mañana_funcionará.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"Русский.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_check file\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p1\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p2\""
2024/04/14 02:16:29 | -
2024/04/14 02:16:29 ----------------------------------------------------------
2024/04/14 02:16:30 | MISCOMPARE  -Golden vs +Results for  check-access-pass._testdir_path1.._testdir_path2.path2.lst-new
2024/04/14 02:16:30 | @@ -1 +0,0 @@
2024/04/14 02:16:30 | --      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:16:30 | @@ -3,22 +1,0 @@
2024/04/14 02:16:30 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"file_enconde_mañana_funcionará.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ě_.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"filename_contains_ࢺ_.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"Русский.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_check file\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ě_.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --      272 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/filename_contains_ࢺ_.txt\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --        0 - - 2000-01-01 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_check file\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p1\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 | --       19 - - 2001-01-02 00:00:00 +0000 UTC "\"測試_Русский_ _ _ě_áñ/測試_file1p2\""
2024/04/14 02:16:30 | -
2024/04/14 02:16:30 ----------------------------------------------------------
2024/04/14 02:16:30 | MISCOMPARE  -Golden vs +Results for  test.log
2024/04/14 02:16:30 | @@ -124,4 +124,4 @@
2024/04/14 02:16:30 | -INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:16:30 | -INFO  : Resync updating listings
2024/04/14 02:16:30 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:16:30 | -INFO  : Bisync successful
2024/04/14 02:16:30 | +ERROR : 測試_Русский_ _ _ě_áñ/測試_check file: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:30 | +ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:30 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:30 | +Bisync error: bisync aborted
2024/04/14 02:16:30 | @@ -140,9 +140,8 @@
2024/04/14 02:16:30 | -INFO  : Building Path1 and Path2 listings
2024/04/14 02:16:30 | -INFO  : Path1 checking for diffs
2024/04/14 02:16:30 | -INFO  : Path2 checking for diffs
2024/04/14 02:16:30 | -INFO  : Checking access health
2024/04/14 02:16:30 | -INFO  : Found 2 matching "測試_check file" files on both paths
2024/04/14 02:16:30 | -INFO  : No changes found
2024/04/14 02:16:30 | -INFO  : Updating listings
2024/04/14 02:16:30 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:16:30 | -INFO  : Bisync successful
2024/04/14 02:16:30 | +ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run
2024/04/14 02:16:30 | +Tip: here are the filenames we were looking for. Do they exist?
2024/04/14 02:16:30 | +Path1: {workdir/}{session}.path1.lst
2024/04/14 02:16:30 | +Path2: {workdir/}{session}.path2.lst
2024/04/14 02:16:30 | +Try running this command to inspect the work dir:
2024/04/14 02:16:30 | +rclone lsl "{workdir}"
2024/04/14 02:16:30 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:30 | +Bisync error: bisync aborted
2024/04/14 02:16:30 | @@ -168,4 +167,4 @@
2024/04/14 02:16:30 | -INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:16:30 | -INFO  : Resync updating listings
2024/04/14 02:16:30 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:16:30 | -INFO  : Bisync successful
2024/04/14 02:16:30 | +ERROR : 測試_Русский_ _ _ě_áñ/測試_check file: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:30 | +ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:16:30 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:30 | +Bisync error: bisync aborted
2024/04/14 02:16:30 | @@ -186,7 +185,8 @@
2024/04/14 02:16:30 | -INFO  : Building Path1 and Path2 listings
2024/04/14 02:16:30 | -INFO  : Path1 checking for diffs
2024/04/14 02:16:30 | -INFO  : Path2 checking for diffs
2024/04/14 02:16:30 | -INFO  : No changes found
2024/04/14 02:16:30 | -INFO  : Updating listings
2024/04/14 02:16:30 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:16:30 | -INFO  : Bisync successful
2024/04/14 02:16:30 | +ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run
2024/04/14 02:16:30 | +Tip: here are the filenames we were looking for. Do they exist?
2024/04/14 02:16:30 | +Path1: {workdir/}{session}.path1.lst
2024/04/14 02:16:30 | +Path2: {workdir/}{session}.path2.lst
2024/04/14 02:16:30 | +Try running this command to inspect the work dir:
2024/04/14 02:16:30 | +rclone lsl "{workdir}"
2024/04/14 02:16:30 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:16:30 | +Bisync error: bisync aborted
2024/04/14 02:16:30 ----------------------------------------------------------
    bisync_test.go:539: TEST ext_paths FAILED - 13 MISCOMPARED FILES
=== RUN   TestBisyncLocalRemote/rclone_args
2024/04/14 02:16:30 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/rclone_args/path1"
2024/04/14 02:16:30 DEBUG : path2: Making directory
2024/04/14 02:16:30 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2"
2024/04/14 02:16:30 DEBUG : Starting OpenDrive session with ID: 3ccd041b4759fcaf441f3c8c1e464002269f43b065e434f191bc0e2f98e4792a
2024/04/14 02:16:31 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/initdir/test_rclone_args-radoror8"
2024/04/14 02:16:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : subdir: Making directory with metadata
2024/04/14 02:16:31 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/04/14 02:16:31 DEBUG : Added delayed dir = "subdir", newDst=subdir
2024/04/14 02:16:31 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:16:31 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_rclone_args-radoror8: Waiting for checks to finish
2024/04/14 02:16:31 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_rclone_args-radoror8: Waiting for transfers to finish
2024/04/14 02:16:31 DEBUG : RCLONE_TEST.dahoqiz9.partial: renamed to: RCLONE_TEST
2024/04/14 02:16:31 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:16:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:31 DEBUG : file2.txt.tafubap7.partial: renamed to: file2.txt
2024/04/14 02:16:31 INFO  : file2.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:31 DEBUG : subdir/file20.txt.cufaxus9.partial: renamed to: subdir/file20.txt
2024/04/14 02:16:31 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:16:31 DEBUG : file1.txt.hiyeloq4.partial: renamed to: file1.txt
2024/04/14 02:16:31 INFO  : file1.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:31 DEBUG : subdir/file21.txt.vazided1.partial: renamed to: subdir/file21.txt
2024/04/14 02:16:31 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:16:31 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:16:31 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8"
2024/04/14 02:16:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:31 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:31 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:31 DEBUG : file20.txt.xajifev1.partial: renamed to: file20.txt
2024/04/14 02:16:31 INFO  : file20.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8: Waiting for checks to finish
2024/04/14 02:16:31 DEBUG : file21.txt.poyaxig8.partial: renamed to: file21.txt
2024/04/14 02:16:31 INFO  : file21.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8: Waiting for transfers to finish
2024/04/14 02:16:31 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:16:31 DEBUG : file2.txt.woxanal5.partial: renamed to: file2.txt
2024/04/14 02:16:31 INFO  : file2.txt: Copied (new)
2024/04/14 02:16:31 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:16:31 DEBUG : file1.txt.petaqum3.partial: renamed to: file1.txt
2024/04/14 02:16:31 INFO  : file1.txt: Copied (new)
2024/04/14 02:16:32 ERROR : : error listing: directory not found
2024/04/14 02:16:32 ERROR : : error listing: directory not found
2024/04/14 02:16:32 checking initFs Local file system at /tmp/bs-021536-nu/initdir/test_rclone_args-radoror8
2024/04/14 02:16:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : subdir: Making directory with metadata
2024/04/14 02:16:32 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2024/04/14 02:16:32 DEBUG : Added delayed dir = "subdir", newDst=subdir
2024/04/14 02:16:32 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:32 DEBUG : file2.txt.cahecer2.partial: renamed to: file2.txt
2024/04/14 02:16:32 INFO  : file2.txt: Copied (new)
2024/04/14 02:16:32 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:32 DEBUG : subdir/file20.txt.xeliqad7.partial: renamed to: subdir/file20.txt
2024/04/14 02:16:32 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:16:32 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:32 DEBUG : subdir/file21.txt.covokat8.partial: renamed to: subdir/file21.txt
2024/04/14 02:16:32 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:16:32 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:16:32 DEBUG : file1.txt.zekiqib0.partial: renamed to: file1.txt
2024/04/14 02:16:32 INFO  : file1.txt: Copied (new)
2024/04/14 02:16:32 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:16:32 DEBUG : RCLONE_TEST.gibujex3.partial: renamed to: RCLONE_TEST
2024/04/14 02:16:32 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:16:32 DEBUG : Local file system at /tmp/bs-021536-nu/rclone_args/path1: Waiting for checks to finish
2024/04/14 02:16:32 DEBUG : Local file system at /tmp/bs-021536-nu/rclone_args/path1: Waiting for transfers to finish
2024/04/14 02:16:32 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:16:32 checking Path1 Local file system at /tmp/bs-021536-nu/rclone_args/path1
2024/04/14 02:16:32 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:16:32 DEBUG : subdir: Making directory
2024/04/14 02:16:33 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:33 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2024/04/14 02:16:33 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2': Waiting for checks to finish
2024/04/14 02:16:33 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2': Waiting for transfers to finish
2024/04/14 02:16:34 DEBUG : file1.txt: Uploading chunk 0, size=19, remain=0
2024/04/14 02:16:34 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:16:35 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:35 INFO  : file2.txt: Copied (new)
2024/04/14 02:16:35 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2024/04/14 02:16:35 INFO  : file1.txt: Copied (new)
2024/04/14 02:16:35 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:16:35 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:16:36 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:36 INFO  : subdir/file20.txt: Copied (new)
2024/04/14 02:16:36 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:16:36 INFO  : subdir/file21.txt: Copied (new)
2024/04/14 02:16:36 checking path2 OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2'
2024/04/14 02:16:37 (01)  : test rclone-args
2024/04/14 02:16:37 (02)  : touch-glob 2001-01-02 /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/ *
2024/04/14 02:16:37 (03)  : test initial bisync
2024/04/14 02:16:37 (04)  : bisync resync checksum
2024/04/14 02:16:39 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:16:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:16:39 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:16:39 INFO  : Copying Path2 files to Path1
2024/04/14 02:16:39 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:16:39 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:16:41 INFO  : Resync updating listings
2024/04/14 02:16:41 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:16:41 INFO  : Bisync successful
2024/04/14 02:16:41 (05)  : test place newer files on both paths
2024/04/14 02:16:41 (06)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file1.txt /tmp/bs-021536-nu/rclone_args/path1/
2024/04/14 02:16:41 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:16:41 (07)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file2.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/
2024/04/14 02:16:43 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:16:43 (08)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file20.txt /tmp/bs-021536-nu/rclone_args/path1/subdir
2024/04/14 02:16:43 INFO  : file20.txt: Updated modification time in destination
2024/04/14 02:16:43 (09)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file21.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir
2024/04/14 02:16:46 INFO  : file21.txt: Copied (replaced existing)
2024/04/14 02:16:46 (10)  : touch-glob 2001-01-02 /tmp/bs-021536-nu/rclone_args/path1/ file1.txt
2024/04/14 02:16:46 (11)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file2.txt
2024/04/14 02:16:47 (12)  : touch-glob 2001-01-02 /tmp/bs-021536-nu/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:16:47 (13)  : touch-glob 2001-01-02 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:16:48 (14)  : test run bisync with custom options
2024/04/14 02:16:48 (15)  : bisync checksum
2024/04/14 02:16:51 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:16:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:16:51 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:16:51 INFO  : Building Path1 and Path2 listings
2024/04/14 02:16:51 INFO  : Path1 checking for diffs
2024/04/14 02:16:51 INFO  : - Path1    File changed: hash - file1.txt
2024/04/14 02:16:51 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:16:51 INFO  : (Modified:    1 hash differs)
2024/04/14 02:16:51 INFO  : Path2 checking for diffs
2024/04/14 02:16:51 INFO  : - Path2    File changed: size (larger), hash - file2.txt
2024/04/14 02:16:51 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:16:51 INFO  : (Modified:    1 larger,    0 smaller,    1 hash differs)
2024/04/14 02:16:51 INFO  : Applying changes
2024/04/14 02:16:51 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/file1.txt
2024/04/14 02:16:51 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/file2.txt
2024/04/14 02:16:51 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:16:51 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:16:51 INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:16:53 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:16:53 INFO  : Updating listings
2024/04/14 02:16:53 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:16:53 INFO  : Bisync successful
2024/04/14 02:16:53 (16)  : touch-glob 2007-07-23 /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/ *
2024/04/14 02:16:53 (17)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file1.txt /tmp/bs-021536-nu/rclone_args/path1/
2024/04/14 02:16:53 INFO  : file1.txt: Updated modification time in destination
2024/04/14 02:16:53 (18)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file2.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/
2024/04/14 02:16:55 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:16:55 (19)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file20.txt /tmp/bs-021536-nu/rclone_args/path1/subdir
2024/04/14 02:16:55 INFO  : file20.txt: Updated modification time in destination
2024/04/14 02:16:55 (20)  : copy-as /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file21.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file1.txt
2024/04/14 02:16:57 INFO  : file21.txt: Copied (replaced existing) to: file1.txt
2024/04/14 02:16:57 (21)  : touch-glob 2007-07-23 /tmp/bs-021536-nu/rclone_args/path1/ file1.txt
2024/04/14 02:16:57 (22)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file2.txt
2024/04/14 02:16:57 (23)  : touch-glob 2007-07-23 /tmp/bs-021536-nu/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:16:57 (24)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:16:58 (25)  : bisync size-only
2024/04/14 02:17:00 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:17:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/14 02:17:00 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:00 INFO  : Building Path1 and Path2 listings
2024/04/14 02:17:00 INFO  : Path1 checking for diffs
2024/04/14 02:17:00 INFO  : Path2 checking for diffs
2024/04/14 02:17:00 INFO  : - Path2    File changed: size (smaller) - file1.txt
2024/04/14 02:17:00 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2024/04/14 02:17:00 INFO  : (Modified:    0 larger,    1 smaller)
2024/04/14 02:17:00 INFO  : Applying changes
2024/04/14 02:17:00 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/file1.txt
2024/04/14 02:17:00 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:17:01 ERROR : file1.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:01 ERROR : Local file system at /tmp/bs-021536-nu/rclone_args/path1: not deleting files as there were IO errors
2024/04/14 02:17:01 ERROR : Local file system at /tmp/bs-021536-nu/rclone_args/path1: not deleting directories as there were IO errors
2024/04/14 02:17:01 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:01 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:17:01 Bisync error: bisync aborted
2024/04/14 02:17:01 (26)  : bisync resync
2024/04/14 02:17:03 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:17:03 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/14 02:17:03 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:03 INFO  : Copying Path2 files to Path1
2024/04/14 02:17:03 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:17:03 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:17:03 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:17:04 INFO  : subdir/file20.txt: Updated modification time in destination
2024/04/14 02:17:04 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:17:05 INFO  : file1.txt: Copied (replaced existing)
2024/04/14 02:17:05 INFO  : Resync updating listings
2024/04/14 02:17:05 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:05 INFO  : Bisync successful
2024/04/14 02:17:05 (27)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file1.txt /tmp/bs-021536-nu/rclone_args/path1/
2024/04/14 02:17:05 (28)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file2.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/
2024/04/14 02:17:07 INFO  : file2.txt: Copied (replaced existing)
2024/04/14 02:17:07 (29)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file20.txt /tmp/bs-021536-nu/rclone_args/path1/subdir
2024/04/14 02:17:07 (30)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file21.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir
2024/04/14 02:17:10 INFO  : file21.txt: Copied (replaced existing)
2024/04/14 02:17:10 (31)  : touch-glob 2007-07-23 /tmp/bs-021536-nu/rclone_args/path1/ file1.txt
2024/04/14 02:17:10 (32)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file2.txt
2024/04/14 02:17:10 (33)  : touch-glob 2007-07-23 /tmp/bs-021536-nu/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:17:10 (34)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:17:11 (35)  : bisync ignore-size
2024/04/14 02:17:14 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:17:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": false,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/14 02:17:14 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:14 INFO  : Building Path1 and Path2 listings
2024/04/14 02:17:15 INFO  : Path1 checking for diffs
2024/04/14 02:17:15 INFO  : Path2 checking for diffs
2024/04/14 02:17:15 INFO  : - Path2    File changed: time (newer) - file2.txt
2024/04/14 02:17:15 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2024/04/14 02:17:15 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:17:15 INFO  : (Modified:    2 newer,    0 older)
2024/04/14 02:17:15 INFO  : Applying changes
2024/04/14 02:17:15 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/file2.txt
2024/04/14 02:17:15 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/subdir/file21.txt
2024/04/14 02:17:15 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:17:15 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:15 INFO  : file2.txt: Updated modification time in destination
2024/04/14 02:17:15 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:17:15 INFO  : Updating listings
2024/04/14 02:17:15 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:15 INFO  : Bisync successful
2024/04/14 02:17:15 (36)  : bisync resync compare-all
2024/04/14 02:17:17 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:17:17 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:17 INFO  : Copying Path2 files to Path1
2024/04/14 02:17:17 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:17:17 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:17:18 INFO  : Resync updating listings
2024/04/14 02:17:18 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/rclone_args/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:18 INFO  : Bisync successful
2024/04/14 02:17:18 (37)  : copy-as /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file21.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file2.txt
2024/04/14 02:17:20 INFO  : file21.txt: Copied (replaced existing) to: file2.txt
2024/04/14 02:17:20 (38)  : touch-glob 2023-08-26 /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/ *
2024/04/14 02:17:20 (39)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file1.txt /tmp/bs-021536-nu/rclone_args/path1/
2024/04/14 02:17:20 INFO  : file1.txt: Updated modification time in destination
2024/04/14 02:17:20 (40)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file20.txt /tmp/bs-021536-nu/rclone_args/path1/subdir
2024/04/14 02:17:20 INFO  : file20.txt: Updated modification time in destination
2024/04/14 02:17:20 (41)  : copy-file /tmp/bs-021536-nu/datadir/test_rclone_args-cecewuy8/file21.txt TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir
2024/04/14 02:17:23 INFO  : file21.txt: Copied (replaced existing)
2024/04/14 02:17:23 (42)  : touch-glob 2007-07-23 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/ file2.txt
2024/04/14 02:17:24 (43)  : touch-glob 2023-08-26 /tmp/bs-021536-nu/rclone_args/path1/ file1.txt
2024/04/14 02:17:24 (44)  : touch-glob 2023-08-26 /tmp/bs-021536-nu/rclone_args/path1/subdir/ file20.txt
2024/04/14 02:17:24 (45)  : touch-glob 2023-08-26 TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir/ file21.txt
2024/04/14 02:17:24 (46)  : bisync compare-all
2024/04/14 02:17:26 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:26 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 1,
	"HashType2": 1,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": true
}
2024/04/14 02:17:26 INFO  : Synching Path1 "/tmp/bs-021536-nu/rclone_args/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/"
2024/04/14 02:17:26 INFO  : Building Path1 and Path2 listings
2024/04/14 02:17:27 INFO  : Path1 checking for diffs
2024/04/14 02:17:27 INFO  : - Path1    File changed: time (newer) - file1.txt
2024/04/14 02:17:27 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2024/04/14 02:17:27 INFO  : Path1:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:17:27 INFO  : (Modified:    2 newer,    0 older)
2024/04/14 02:17:27 INFO  : Path2 checking for diffs
2024/04/14 02:17:27 INFO  : - Path2    File changed: size (smaller), hash - file2.txt
2024/04/14 02:17:27 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2024/04/14 02:17:27 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2024/04/14 02:17:27 INFO  : (Modified:    1 newer,    0 older,    0 larger,    1 smaller,    1 hash differs)
2024/04/14 02:17:27 INFO  : Applying changes
2024/04/14 02:17:27 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/file1.txt
2024/04/14 02:17:27 INFO  : - Path1    Queue copy to Path2       - TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/rclone_args/path2/subdir/file20.txt
2024/04/14 02:17:27 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/file2.txt
2024/04/14 02:17:27 INFO  : - Path2    Queue copy to Path1       - /tmp/bs-021536-nu/rclone_args/path1/subdir/file21.txt
2024/04/14 02:17:27 INFO  : - Path2    Do queued copies to                - Path1
2024/04/14 02:17:27 INFO  : subdir: Set directory modification time (using SetModTime)
2024/04/14 02:17:28 ERROR : file2.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 INFO  : subdir/file21.txt: Updated modification time in destination
2024/04/14 02:17:28 ERROR : Local file system at /tmp/bs-021536-nu/rclone_args/path1: not deleting files as there were IO errors
2024/04/14 02:17:28 ERROR : Local file system at /tmp/bs-021536-nu/rclone_args/path1: not deleting directories as there were IO errors
2024/04/14 02:17:28 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:17:28 Bisync error: bisync aborted
2024/04/14 02:17:28   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path1.lst
2024/04/14 02:17:28   File found in Golden but not in Results:  _testdir_path1.._testdir_path2.path2.lst
2024/04/14 02:17:28   File found in Results but not in Golden:  _testdir_path1.._testdir_path2.path1.lst-err
2024/04/14 02:17:28   File found in Results but not in Golden:  _testdir_path1.._testdir_path2.path2.lst-err
2024/04/14 02:17:28 ----------------------------------------------------------
2024/04/14 02:17:28 | MISCOMPARE  -Golden vs +Results for  _testdir_path1.._testdir_path2.copy1to2.que
2024/04/14 02:17:28 | @@ -2 +1,0 @@
2024/04/14 02:17:28 | -"subdir/file20.txt"
2024/04/14 02:17:28 ----------------------------------------------------------
2024/04/14 02:17:28 | MISCOMPARE  -Golden vs +Results for  _testdir_path1.._testdir_path2.path1.lst-old
2024/04/14 02:17:28 | @@ -1 +1 @@
2024/04/14 02:17:28 | --      109 md5:294d25b294ff26a5243dba914ac3fbf7 - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:17:28 | +-      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:17:28 | @@ -3 +3 @@
2024/04/14 02:17:28 | --       19 md5:7fe98ed88552b828777d8630900346b8 - 2007-07-23 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:17:28 | +-       19 - - 2007-07-23 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:17:28 | @@ -5 +5 @@
2024/04/14 02:17:28 | --       19 md5:7fe98ed88552b828777d8630900346b8 - 2007-07-23 00:00:00 +0000 UTC "\"file2.txt\""
2024/04/14 02:17:28 | +-       19 - - 2007-07-23 00:00:00 +0000 UTC "\"file2.txt\""
2024/04/14 02:17:28 | @@ -7 +7 @@
2024/04/14 02:17:28 | --        0 md5:d41d8cd98f00b204e9800998ecf8427e - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file20.txt\""
2024/04/14 02:17:28 | +-        0 - - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file20.txt\""
2024/04/14 02:17:28 | @@ -9 +9 @@
2024/04/14 02:17:28 | --        0 md5:d41d8cd98f00b204e9800998ecf8427e - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file21.txt\""
2024/04/14 02:17:28 | +-        0 - - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file21.txt\""
2024/04/14 02:17:28 ----------------------------------------------------------
2024/04/14 02:17:28 | MISCOMPARE  -Golden vs +Results for  _testdir_path1.._testdir_path2.path2.lst-old
2024/04/14 02:17:28 | @@ -1 +1 @@
2024/04/14 02:17:28 | --      109 md5:294d25b294ff26a5243dba914ac3fbf7 - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:17:28 | +-      109 - - 2000-01-01 00:00:00 +0000 UTC "\"RCLONE_TEST\""
2024/04/14 02:17:28 | @@ -3 +3 @@
2024/04/14 02:17:28 | --       19 md5:7fe98ed88552b828777d8630900346b8 - 2007-07-23 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:17:28 | +-       19 - - 2007-07-23 00:00:00 +0000 UTC "\"file1.txt\""
2024/04/14 02:17:28 | @@ -5 +5 @@
2024/04/14 02:17:28 | --       19 md5:7fe98ed88552b828777d8630900346b8 - 2007-07-23 00:00:00 +0000 UTC "\"file2.txt\""
2024/04/14 02:17:28 | +-       19 - - 2007-07-23 00:00:00 +0000 UTC "\"file2.txt\""
2024/04/14 02:17:28 | @@ -7 +7 @@
2024/04/14 02:17:28 | --        0 md5:d41d8cd98f00b204e9800998ecf8427e - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file20.txt\""
2024/04/14 02:17:28 | +-        0 - - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file20.txt\""
2024/04/14 02:17:28 | @@ -9 +9 @@
2024/04/14 02:17:28 | --        0 md5:d41d8cd98f00b204e9800998ecf8427e - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file21.txt\""
2024/04/14 02:17:28 | +-        0 - - 2007-07-23 00:00:00 +0000 UTC "\"subdir/file21.txt\""
2024/04/14 02:17:28 ----------------------------------------------------------
2024/04/14 02:17:28 | MISCOMPARE  -Golden vs +Results for  test.log
2024/04/14 02:17:28 | @@ -89,3 +89,6 @@
2024/04/14 02:17:28 | -INFO  : Updating listings
2024/04/14 02:17:28 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:17:28 | -INFO  : Bisync successful
2024/04/14 02:17:28 | +ERROR : file1.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 | +ERROR : {path1String}: not deleting files as there were IO errors
2024/04/14 02:17:28 | +ERROR : {path1String}: not deleting directories as there were IO errors
2024/04/14 02:17:28 | +ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:17:28 | +Bisync error: bisync aborted
2024/04/14 02:17:28 | @@ -198,4 +201,6 @@
2024/04/14 02:17:28 | -INFO  : - Path1    Do queued copies to                - Path2
2024/04/14 02:17:28 | -INFO  : Updating listings
2024/04/14 02:17:28 | -INFO  : Validating listings for Path1 "{path1/}" vs Path2 "{path2/}"
2024/04/14 02:17:28 | -INFO  : Bisync successful
2024/04/14 02:17:28 | +ERROR : file2.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 | +ERROR : {path1String}: not deleting files as there were IO errors
2024/04/14 02:17:28 | +ERROR : {path1String}: not deleting directories as there were IO errors
2024/04/14 02:17:28 | +ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:28 | +ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:17:28 | +Bisync error: bisync aborted
2024/04/14 02:17:28 ----------------------------------------------------------
    bisync_test.go:539: TEST rclone_args FAILED - 8 MISCOMPARED FILES
=== RUN   TestBisyncLocalRemote/resync
2024/04/14 02:17:29 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/resync/path1"
2024/04/14 02:17:29 DEBUG : path2: Making directory
2024/04/14 02:17:29 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2"
2024/04/14 02:17:29 DEBUG : Starting OpenDrive session with ID: e3f3ea8a5984b72ef338013a336cdbe5cd348e67a379e9d2ccc7646536da7804
2024/04/14 02:17:31 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/initdir/test_resync-maseped7"
2024/04/14 02:17:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file2.txt.qoxohoc4.partial: renamed to: file2.txt
2024/04/14 02:17:31 INFO  : file2.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file3.txt.feqireq4.partial: renamed to: file3.txt
2024/04/14 02:17:31 INFO  : file3.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_resync-maseped7: Waiting for checks to finish
2024/04/14 02:17:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:17:31 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file4.txt.ponoqov0.partial: renamed to: file4.txt
2024/04/14 02:17:31 INFO  : file4.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : RCLONE_TEST.vaqilif8.partial: renamed to: RCLONE_TEST
2024/04/14 02:17:31 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:17:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file1.txt.cotojon4.partial: renamed to: file1.txt
2024/04/14 02:17:31 INFO  : file1.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/initdir/test_resync-maseped7: Waiting for transfers to finish
2024/04/14 02:17:31 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file6.txt.gozumob9.partial: renamed to: file6.txt
2024/04/14 02:17:31 INFO  : file6.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file7.txt.gavorat3.partial: renamed to: file7.txt
2024/04/14 02:17:31 INFO  : file7.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file5.txt.mihipit0.partial: renamed to: file5.txt
2024/04/14 02:17:31 INFO  : file5.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : Creating backend with remote "/tmp/bs-021536-nu/datadir/test_resync-punigug7"
2024/04/14 02:17:31 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_resync-punigug7: Waiting for checks to finish
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/datadir/test_resync-punigug7: Waiting for transfers to finish
2024/04/14 02:17:31 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:17:31 DEBUG : fileA.txt.pomeqab2.partial: renamed to: fileA.txt
2024/04/14 02:17:31 INFO  : fileA.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/14 02:17:31 DEBUG : fileB.txt.qocisic4.partial: renamed to: fileB.txt
2024/04/14 02:17:31 INFO  : fileB.txt: Copied (new)
2024/04/14 02:17:31 ERROR : : error listing: directory not found
2024/04/14 02:17:31 ERROR : : error listing: directory not found
2024/04/14 02:17:31 checking initFs Local file system at /tmp/bs-021536-nu/initdir/test_resync-maseped7
2024/04/14 02:17:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:17:31 DEBUG : RCLONE_TEST.ditadey5.partial: renamed to: RCLONE_TEST
2024/04/14 02:17:31 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:17:31 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file3.txt.sikehuz3.partial: renamed to: file3.txt
2024/04/14 02:17:31 INFO  : file3.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file2.txt.zixigot0.partial: renamed to: file2.txt
2024/04/14 02:17:31 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file5.txt.duyaquj1.partial: renamed to: file5.txt
2024/04/14 02:17:31 INFO  : file5.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file4.txt.tuhojik1.partial: renamed to: file4.txt
2024/04/14 02:17:31 INFO  : file4.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 INFO  : file2.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file7.txt.ciwicot3.partial: renamed to: file7.txt
2024/04/14 02:17:31 INFO  : file7.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/resync/path1: Waiting for checks to finish
2024/04/14 02:17:31 DEBUG : Local file system at /tmp/bs-021536-nu/resync/path1: Waiting for transfers to finish
2024/04/14 02:17:31 DEBUG : file6.txt.qofomib6.partial: renamed to: file6.txt
2024/04/14 02:17:31 INFO  : file6.txt: Copied (new)
2024/04/14 02:17:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:31 DEBUG : file1.txt.kuraroh0.partial: renamed to: file1.txt
2024/04/14 02:17:31 INFO  : file1.txt: Copied (new)
2024/04/14 02:17:31 checking Path1 Local file system at /tmp/bs-021536-nu/resync/path1
2024/04/14 02:17:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/14 02:17:31 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2': Waiting for checks to finish
2024/04/14 02:17:31 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2': Waiting for transfers to finish
2024/04/14 02:17:35 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:35 INFO  : file1.txt: Copied (new)
2024/04/14 02:17:35 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:35 INFO  : file4.txt: Copied (new)
2024/04/14 02:17:35 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:35 INFO  : file3.txt: Copied (new)
2024/04/14 02:17:36 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:36 INFO  : file2.txt: Copied (new)
2024/04/14 02:17:36 DEBUG : RCLONE_TEST: Uploading chunk 0, size=109, remain=0
2024/04/14 02:17:38 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:38 INFO  : file5.txt: Copied (new)
2024/04/14 02:17:38 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:38 INFO  : file7.txt: Copied (new)
2024/04/14 02:17:38 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/14 02:17:38 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:17:39 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/14 02:17:39 INFO  : file6.txt: Copied (new)
2024/04/14 02:17:39 checking path2 OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2'
2024/04/14 02:17:39 (01)  : test resync
2024/04/14 02:17:39 (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2024/04/14 02:17:39 (03)  : purge-children /tmp/bs-021536-nu/resync/path1/
2024/04/14 02:17:39 (04)  : bisync resync
2024/04/14 02:17:41 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:17: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/14 02:17:41 INFO  : Synching Path1 "/tmp/bs-021536-nu/resync/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2/"
2024/04/14 02:17:41 INFO  : Copying Path2 files to Path1
2024/04/14 02:17:41 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:17:41 ERROR : file1.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:41 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:17:42 ERROR : file2.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : file3.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : file4.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : file6.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : file7.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : file5.txt: Failed to copy: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : Bisync critical error: failed to open source object: failed to open file): Unknown 416 Requested Range Not Satisfiable (Error 416)
2024/04/14 02:17:42 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/14 02:17:42 Bisync error: bisync aborted
2024/04/14 02:17:42 (05)  : move-listings empty-path1
2024/04/14 02:17:42 (06)  : test 2. resync with empty path2, resulting in synching all content to path2.
2024/04/14 02:17:42 (07)  : purge-children TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2/
2024/04/14 02:17:43 (08)  : bisync resync
2024/04/14 02:17:46 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/14 02:17: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/14 02:17:46 INFO  : Synching Path1 "/tmp/bs-021536-nu/resync/path1/" with Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2/"
2024/04/14 02:17:46 INFO  : Copying Path2 files to Path1
2024/04/14 02:17:46 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/14 02:17:46 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/14 02:17:48 INFO  : RCLONE_TEST: Copied (new)
2024/04/14 02:17:48 INFO  : Resync updating listings
2024/04/14 02:17:48 INFO  : Validating listings for Path1 "/tmp/bs-021536-nu/resync/path1/" vs Path2 "TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2/"
2024/04/14 02:17:48 INFO  : Bisync successful
2024/04/14 02:17:48 (09)  : move-listings empty-path2
2024/04/14 02:17:48 (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2024/04/14 02:17:48 (11)  : touch-glob 2002-02-02 /tmp/bs-021536-nu/datadir/test_resync-punigug7/ fileA.txt
2024/04/14 02:17:48 (12)  : touch-glob 1999-09-09 /tmp/bs-021536-nu/datadir/test_resync-punigug7/ fileB.txt
2024/04/14 02:17:48 (13)  : test = file      - path1      - path2      - expected action    - who wins
2024/04/14 02:17:48 (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2024/04/14 02:17:48 (15)  : delete-file TestOpenDrive:rclone-test-fafilay7tizirod9nicijuz3/bs-021536-nu/resync/path2/file1.txt
    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:       	TestBisyncLocalRemote/resync
        	Messages:   	step 15 failed: object not found
2024/04/14 02:17:49 DEBUG : OpenDrive root 'rclone-test-fafilay7tizirod9nicijuz3': Purge remote
--- FAIL: TestBisyncLocalRemote (134.01s)
    --- FAIL: TestBisyncLocalRemote/ext_paths (53.52s)
    --- FAIL: TestBisyncLocalRemote/rclone_args (59.18s)
    --- FAIL: TestBisyncLocalRemote/resync (20.06s)
FAIL
2024/04/14 02:17:50 DEBUG : OpenDrive root 'rclone-test-kofific9xerocaq4qosigix3': Purge remote
2024/04/14 02:17:50 purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose -test.run '^(TestBisyncLocalRemote|TestBisyncRemoteLocal)$/^(ext_paths|rclone_args|resync)$'" - Finished ERROR in 4m51.681299138s (try 3/5): exit status 1: Failed [TestBisyncRemoteLocal/resync TestBisyncLocalRemote/ext_paths TestBisyncLocalRemote/rclone_args TestBisyncLocalRemote/resync]