"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteLocal$/^check_access_filters$|^TestBisyncRemoteRemote$/^resolve$'" - Starting (try 3/5)
2026/07/27 04:46:26 DEBUG : Creating backend with remote "TestB2:rclone-test-nirifel8jomi"
2026/07/27 04:46:26 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/07/27 04:46:27 DEBUG : Creating backend with remote "/tmp/rclone2636319683"
=== RUN   TestBisyncRemoteLocal
2026/07/27 04:46:27 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru"
2026/07/27 04:46:28 NOTICE: remote: TestB2:rclone-test-biqusob6jiru
=== RUN   TestBisyncRemoteLocal/check_access_filters
2026/07/27 04:46:28 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5"
2026/07/27 04:46:29 INFO  : path1: Making directory
2026/07/27 04:46:30 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1"
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/tmp/044628latasoy5"
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/tmp/044628latasoy5/check_access_filters/path2"
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/tmp/044628latasoy5/initdir/test_check_access_filters-waqasag5"
2026/07/27 04:46:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not: Making directory with metadata
2026/07/27 04:46:31 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2026/07/27 04:46:31 DEBUG : subdir: Making directory with metadata
2026/07/27 04:46:31 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdir", newDst=subdir
2026/07/27 04:46:31 DEBUG : subdirX: Making directory with metadata
2026/07/27 04:46:31 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/07/27 04:46:31 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2026/07/27 04:46:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/07/27 04:46:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 INFO  : file2.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/07/27 04:46:31 INFO  : file1.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2026/07/27 04:46:31 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2026/07/27 04:46:31 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1: Making directory with metadata
2026/07/27 04:46:31 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir/subdirA: Making directory with metadata
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2026/07/27 04:46:31 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : subdir/subdirB: Making directory with metadata
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2026/07/27 04:46:31 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:31 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2026/07/27 04:46:31 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2026/07/27 04:46:31 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/07/27 04:46:31 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 INFO  : file4.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : Local file system at /tmp/044628latasoy5/initdir/test_check_access_filters-waqasag5: Waiting for checks to finish
2026/07/27 04:46:31 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2026/07/27 04:46:31 DEBUG : Local file system at /tmp/044628latasoy5/initdir/test_check_access_filters-waqasag5: Waiting for transfers to finish
2026/07/27 04:46:31 INFO  : subdir-not/file20.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/07/27 04:46:31 INFO  : file3.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2026/07/27 04:46:31 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 INFO  : subdirX/file20.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2026/07/27 04:46:31 INFO  : subdirX/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2026/07/27 04:46:31 INFO  : subdir/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2026/07/27 04:46:31 INFO  : subdir/file20.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2026/07/27 04:46:31 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/07/27 04:46:31 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2026/07/27 04:46:31 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2026/07/27 04:46:31 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2026/07/27 04:46:31 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2026/07/27 04:46:31 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2026/07/27 04:46:31 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2026/07/27 04:46:31 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2026/07/27 04:46:31 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2026/07/27 04:46:31 INFO  : subdir/subdirB/file30.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2026/07/27 04:46:31 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:31 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2026/07/27 04:46:31 INFO  : subdir/subdirA/file30.txt: Copied (new)
2026/07/27 04:46:31 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2026/07/27 04:46:31 DEBUG : Creating backend with remote "/tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2"
2026/07/27 04:46:31 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:31 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2026/07/27 04:46:31 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK
2026/07/27 04:46:31 DEBUG : Local file system at /tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2: Waiting for checks to finish
2026/07/27 04:46:31 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2026/07/27 04:46:31 DEBUG : Local file system at /tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2: Waiting for transfers to finish
2026/07/27 04:46:31 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2026/07/27 04:46:31 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2026/07/27 04:46:31 INFO  : include-other-filtersfile.txt: Copied (new)
2026/07/27 04:46:31 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2026/07/27 04:46:31 INFO  : exclude-other-filtersfile.txt: Copied (new)
2026/07/27 04:46:31 INFO  : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: cleaning bucket "rclone-test-biqusob6jiru" of all files
2026/07/27 04:46:31 DEBUG : Waiting for deletions to finish
2026/07/27 04:46:31 ERROR : error listing: directory not found
2026/07/27 04:46:31 ERROR : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Failed to list "": directory not found
2026/07/27 04:46:31 DEBUG : removing 1 level 0 directories
2026/07/27 04:46:31 INFO  : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Removing directory
2026/07/27 04:46:31 ERROR : Failed to rmdir: stat /tmp/044628latasoy5/check_access_filters/path2: no such file or directory
2026/07/27 04:46:32 ERROR : error listing: directory not found
2026/07/27 04:46:32 NOTICE: checking initFs Local file system at /tmp/044628latasoy5/initdir/test_check_access_filters-waqasag5
2026/07/27 04:46:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 INFO  : subdir-not: Making directory
2026/07/27 04:46:32 INFO  : subdir: Making directory
2026/07/27 04:46:32 INFO  : subdirX: Making directory
2026/07/27 04:46:32 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 INFO  : subdirX/subdirX1: Making directory
2026/07/27 04:46:32 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 INFO  : subdir-not/subdir-not2: Making directory
2026/07/27 04:46:32 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 INFO  : subdir/subdirA: Making directory
2026/07/27 04:46:32 INFO  : subdir/subdirB: Making directory
2026/07/27 04:46:32 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:32 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Waiting for checks to finish
2026/07/27 04:46:32 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Waiting for transfers to finish
2026/07/27 04:46:33 DEBUG : RCLONE_TEST: size = 109 OK
2026/07/27 04:46:33 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:33 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:46:33 DEBUG : file1.txt: size = 0 OK
2026/07/27 04:46:33 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:33 INFO  : file1.txt: Copied (new)
2026/07/27 04:46:33 DEBUG : file2.txt: size = 0 OK
2026/07/27 04:46:33 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:33 INFO  : file2.txt: Copied (new)
2026/07/27 04:46:33 DEBUG : file4.txt: size = 0 OK
2026/07/27 04:46:33 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:33 INFO  : file4.txt: Copied (new)
2026/07/27 04:46:33 DEBUG : file3.txt: size = 0 OK
2026/07/27 04:46:33 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:33 INFO  : file3.txt: Copied (new)
2026/07/27 04:46:33 DEBUG : subdirX/file20.txt: size = 0 OK
2026/07/27 04:46:33 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:33 INFO  : subdirX/file20.txt: Copied (new)
2026/07/27 04:46:33 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:33 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:33 INFO  : subdirX/RCLONE_TEST: Copied (new)
2026/07/27 04:46:33 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:33 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:33 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2026/07/27 04:46:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:34 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2026/07/27 04:46:34 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2026/07/27 04:46:34 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:34 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:34 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:34 INFO  : subdir/RCLONE_TEST: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir-not/file20.txt: size = 0 OK
2026/07/27 04:46:34 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:34 INFO  : subdir-not/file20.txt: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:34 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:34 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir/file20.txt: size = 0 OK
2026/07/27 04:46:34 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:34 INFO  : subdir/file20.txt: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2026/07/27 04:46:34 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:34 INFO  : subdir/subdirB/file30.txt: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:34 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2026/07/27 04:46:34 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2026/07/27 04:46:34 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:34 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:46:35 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:46:35 INFO  : subdir/subdirA/file30.txt: Copied (new)
2026/07/27 04:46:35 NOTICE: checking Path1 B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1
2026/07/27 04:46:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file3.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file4.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir-not: Making directory with metadata
2026/07/27 04:46:35 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2026/07/27 04:46:35 DEBUG : subdir: Making directory with metadata
2026/07/27 04:46:35 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdir", newDst=subdir
2026/07/27 04:46:35 DEBUG : subdirX: Making directory with metadata
2026/07/27 04:46:35 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/07/27 04:46:35 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2026/07/27 04:46:35 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1: Making directory with metadata
2026/07/27 04:46:35 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/07/27 04:46:35 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2026/07/27 04:46:35 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/07/27 04:46:35 INFO  : file1.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2026/07/27 04:46:35 INFO  : file3.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2026/07/27 04:46:35 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2026/07/27 04:46:35 INFO  : file4.txt: Copied (new)
2026/07/27 04:46:35 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2026/07/27 04:46:35 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2026/07/27 04:46:35 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2026/07/27 04:46:35 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2026/07/27 04:46:35 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 INFO  : subdirX/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2026/07/27 04:46:35 INFO  : subdirX/file20.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/07/27 04:46:35 INFO  : file2.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir/subdirA: Making directory with metadata
2026/07/27 04:46:35 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2026/07/27 04:46:35 DEBUG : subdir/subdirB: Making directory with metadata
2026/07/27 04:46:35 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2026/07/27 04:46:35 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2026/07/27 04:46:35 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2026/07/27 04:46:35 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2026/07/27 04:46:35 INFO  : subdir-not/file20.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2026/07/27 04:46:35 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2026/07/27 04:46:35 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2026/07/27 04:46:35 INFO  : subdir/file20.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2026/07/27 04:46:35 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2026/07/27 04:46:35 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2026/07/27 04:46:35 INFO  : subdir/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 INFO  : subdir/subdirB/file30.txt: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2026/07/27 04:46:35 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Waiting for checks to finish
2026/07/27 04:46:35 DEBUG : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Waiting for transfers to finish
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2026/07/27 04:46:35 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:46:35 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2026/07/27 04:46:35 INFO  : subdir/subdirA/file30.txt: Copied (new)
2026/07/27 04:46:35 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:35 NOTICE: checking path2 Local file system at /tmp/044628latasoy5/check_access_filters/path2
2026/07/27 04:46:35 NOTICE: (01)  : test check-access-filters
2026/07/27 04:46:35 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2026/07/27 04:46:35 NOTICE: (03)  : copy-file /tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/exclude-other-filtersfile.txt /tmp/044628latasoy5/workdir/
2026/07/27 04:46:35 DEBUG : copyFile "/tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/exclude-other-filtersfile.txt" to "/tmp/044628latasoy5/workdir/" as ""
2026/07/27 04:46:35 DEBUG : Creating backend with remote "/tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/exclude-other-filtersfile.txt"
2026/07/27 04:46:35 DEBUG : Creating backend with remote "/tmp/044628latasoy5/workdir/"
2026/07/27 04:46:35 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/044628latasoy5/workdir" as "exclude-other-filtersfile.txt"
2026/07/27 04:46:35 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2026/07/27 04:46:35 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2026/07/27 04:46:35 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2026/07/27 04:46:35 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2026/07/27 04:46:35 INFO  : exclude-other-filtersfile.txt: Copied (new)
2026/07/27 04:46:35 NOTICE: (04)  : test resync to get the filters file md5 built.
2026/07/27 04:46:35 NOTICE: (05)  : bisync resync filters-file=/tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:39 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:46:39 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:46:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:46:39 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:39 INFO  : Using filters file /tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:39 INFO  : Storing filters file hash to /tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt.md5
2026/07/27 04:46:39 INFO  : Copying Path2 files to Path1
2026/07/27 04:46:39 INFO  : - Path2    Resync is copying files to         - Path1
2026/07/27 04:46:39 INFO  : There was nothing to transfer
2026/07/27 04:46:39 INFO  : - Path1    Resync is copying files to         - Path2
2026/07/27 04:46:40 INFO  : There was nothing to transfer
2026/07/27 04:46:40 INFO  : Resync updating listings
2026/07/27 04:46:40 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:40 INFO  : Bisync successful
2026/07/27 04:46:40 NOTICE: (06)  : test EXCLUDE - test filters for check access
2026/07/27 04:46:40 NOTICE: (07)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:43 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:46:43 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:46:43 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:46:43 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:43 INFO  : Using filters file /tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:43 INFO  : Building Path1 and Path2 listings
2026/07/27 04:46:44 INFO  : Path1 checking for diffs
2026/07/27 04:46:44 INFO  : Path2 checking for diffs
2026/07/27 04:46:44 INFO  : Checking access health
2026/07/27 04:46:44 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2026/07/27 04:46:44 INFO  : No changes found
2026/07/27 04:46:44 INFO  : Updating listings
2026/07/27 04:46:44 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:44 INFO  : Bisync successful
2026/07/27 04:46:44 NOTICE: (08)  : copy-listings exclude-initial
2026/07/27 04:46:44 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2026/07/27 04:46:44 NOTICE: (10)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2026/07/27 04:46:44 DEBUG : Creating backend with remote "/tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA/"
2026/07/27 04:46:44 DEBUG : fs cache: renaming cache item "/tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA/" to be canonical "/tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA"
2026/07/27 04:46:44 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:44 NOTICE: (11)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not/RCLONE_TEST
2026/07/27 04:46:44 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not/"
2026/07/27 04:46:45 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not/" to be canonical "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not"
2026/07/27 04:46:46 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:46 NOTICE: (12)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2026/07/27 04:46:46 DEBUG : Creating backend with remote "/tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2/"
2026/07/27 04:46:46 DEBUG : fs cache: renaming cache item "/tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "/tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2"
2026/07/27 04:46:46 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:46 NOTICE: (13)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/RCLONE_TEST
2026/07/27 04:46:46 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/"
2026/07/27 04:46:47 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/" to be canonical "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX"
2026/07/27 04:46:48 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:48 NOTICE: (14)  : test EXCLUDE - test should PASS
2026/07/27 04:46:48 NOTICE: (15)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:52 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:46:52 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:52 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:46:52 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:46:52 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:52 INFO  : Using filters file /tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:52 INFO  : Building Path1 and Path2 listings
2026/07/27 04:46:53 INFO  : Path1 checking for diffs
2026/07/27 04:46:53 INFO  : Path2 checking for diffs
2026/07/27 04:46:53 INFO  : Checking access health
2026/07/27 04:46:53 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2026/07/27 04:46:53 INFO  : No changes found
2026/07/27 04:46:53 INFO  : Updating listings
2026/07/27 04:46:53 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:53 INFO  : Bisync successful
2026/07/27 04:46:53 NOTICE: (16)  : copy-listings exclude-pass-run
2026/07/27 04:46:53 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2026/07/27 04:46:53 NOTICE: (18)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/RCLONE_TEST
2026/07/27 04:46:53 DEBUG : Creating backend with remote "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:53 DEBUG : fs cache: renaming cache item "/tmp/044628latasoy5/check_access_filters/path2/" to be canonical "/tmp/044628latasoy5/check_access_filters/path2"
2026/07/27 04:46:53 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:53 NOTICE: (19)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir/RCLONE_TEST
2026/07/27 04:46:53 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir/"
2026/07/27 04:46:54 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir/" to be canonical "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir"
2026/07/27 04:46:54 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:46:54 NOTICE: (20)  : test EXCLUDE - test should ABORT
2026/07/27 04:46:54 NOTICE: (21)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:58 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:46:58 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:46:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:46:58 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:46:58 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:46:58 INFO  : Using filters file /tmp/044628latasoy5/workdir/exclude-other-filtersfile.txt
2026/07/27 04:46:58 INFO  : Building Path1 and Path2 listings
2026/07/27 04:46:59 INFO  : Path1 checking for diffs
2026/07/27 04:46:59 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2026/07/27 04:46:59 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2026/07/27 04:46:59 INFO  : Path2 checking for diffs
2026/07/27 04:46:59 INFO  : - Path2    File was deleted          - RCLONE_TEST
2026/07/27 04:46:59 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2026/07/27 04:46:59 INFO  : Checking access health
2026/07/27 04:46:59 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2026/07/27 04:46:59 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2026/07/27 04:46:59 ERROR : Bisync critical error: check file check failed
2026/07/27 04:46:59 ERROR : Bisync aborted. Must run --resync to recover.
2026/07/27 04:46:59 NOTICE: Bisync error: bisync aborted
2026/07/27 04:46:59 NOTICE: (22)  : move-listings exclude-error-run
2026/07/27 04:46:59 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2026/07/27 04:46:59 NOTICE: (24)  : test reset to the initial state
2026/07/27 04:46:59 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/
2026/07/27 04:46:59 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/"
2026/07/27 04:47:00 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" to be canonical "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1"
2026/07/27 04:47:00 DEBUG : RCLONE_TEST: size = 109 OK
2026/07/27 04:47:00 DEBUG : file2.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:00 DEBUG : file2.txt: Unchanged skipping
2026/07/27 04:47:00 DEBUG : file1.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : file1.txt: Unchanged skipping
2026/07/27 04:47:00 DEBUG : file3.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : file3.txt: Unchanged skipping
2026/07/27 04:47:00 DEBUG : file4.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : file4.txt: Unchanged skipping
2026/07/27 04:47:00 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:00 DEBUG : subdirX/file20.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : subdirX/file20.txt: Unchanged skipping
2026/07/27 04:47:00 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:00 DEBUG : subdir/file20.txt: size = 0 OK
2026/07/27 04:47:00 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:00 DEBUG : subdir/file20.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:01 DEBUG : subdir-not/file20.txt: size = 0 OK
2026/07/27 04:47:01 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir-not/file20.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2026/07/27 04:47:01 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2026/07/27 04:47:01 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2026/07/27 04:47:01 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Waiting for checks to finish
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:01 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:01 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Waiting for transfers to finish
2026/07/27 04:47:01 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:01 INFO  : subdirX/RCLONE_TEST: Copied (new)
2026/07/27 04:47:01 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:01 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:01 INFO  : subdir/RCLONE_TEST: Copied (new)
2026/07/27 04:47:02 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:02 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2026/07/27 04:47:02 NOTICE: (26)  : sync-dir TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/ /tmp/044628latasoy5/check_access_filters/path2/
2026/07/27 04:47:02 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" for canonical name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1"
2026/07/27 04:47:02 DEBUG : fs cache: switching user supplied name "/tmp/044628latasoy5/check_access_filters/path2/" for canonical name "/tmp/044628latasoy5/check_access_filters/path2"
2026/07/27 04:47:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:02 DEBUG : file1.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : file1.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : file2.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : file2.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : file3.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : file3.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : file4.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : file4.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdirX/file20.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdirX/file20.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir-not/file20.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir-not/file20.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir/file20.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir/file20.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:02 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2026/07/27 04:47:02 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:02 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2026/07/27 04:47:02 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2026/07/27 04:47:02 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2026/07/27 04:47:02 DEBUG : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Waiting for checks to finish
2026/07/27 04:47:02 DEBUG : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Waiting for transfers to finish
2026/07/27 04:47:02 DEBUG : RCLONE_TEST.243c2add.partial: size = 109 OK
2026/07/27 04:47:02 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:02 DEBUG : RCLONE_TEST.243c2add.partial: renamed to: RCLONE_TEST
2026/07/27 04:47:02 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:47:03 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.5c6ff6b2.partial: size = 109 OK
2026/07/27 04:47:03 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:03 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.5c6ff6b2.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2026/07/27 04:47:03 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2026/07/27 04:47:03 DEBUG : subdir/subdirA/RCLONE_TEST.d4eee2c4.partial: size = 109 OK
2026/07/27 04:47:03 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:03 DEBUG : subdir/subdirA/RCLONE_TEST.d4eee2c4.partial: renamed to: subdir/subdirA/RCLONE_TEST
2026/07/27 04:47:03 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2026/07/27 04:47:03 DEBUG : Waiting for deletions to finish
2026/07/27 04:47:03 NOTICE: (27)  : copy-file /tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/include-other-filtersfile.txt /tmp/044628latasoy5/workdir/
2026/07/27 04:47:03 DEBUG : copyFile "/tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/include-other-filtersfile.txt" to "/tmp/044628latasoy5/workdir/" as ""
2026/07/27 04:47:03 DEBUG : Creating backend with remote "/tmp/044628latasoy5/datadir/test_check_access_filters-cahobux2/include-other-filtersfile.txt"
2026/07/27 04:47:03 DEBUG : Creating backend with remote "/tmp/044628latasoy5/workdir/"
2026/07/27 04:47:03 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/044628latasoy5/workdir" as "include-other-filtersfile.txt"
2026/07/27 04:47:03 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:03 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK
2026/07/27 04:47:03 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2026/07/27 04:47:03 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2026/07/27 04:47:03 INFO  : include-other-filtersfile.txt: Copied (new)
2026/07/27 04:47:03 NOTICE: (28)  : bisync resync filters-file=/tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:07 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:47:07 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:47:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:47:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:47:07 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:07 INFO  : Using filters file /tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:07 INFO  : Storing filters file hash to /tmp/044628latasoy5/workdir/include-other-filtersfile.txt.md5
2026/07/27 04:47:07 INFO  : Copying Path2 files to Path1
2026/07/27 04:47:07 INFO  : - Path2    Resync is copying files to         - Path1
2026/07/27 04:47:07 INFO  : There was nothing to transfer
2026/07/27 04:47:07 INFO  : - Path1    Resync is copying files to         - Path2
2026/07/27 04:47:08 INFO  : There was nothing to transfer
2026/07/27 04:47:08 INFO  : Resync updating listings
2026/07/27 04:47:08 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:08 INFO  : Bisync successful
2026/07/27 04:47:08 NOTICE: (29)  : test INCLUDE - test include/exclude filters for check access
2026/07/27 04:47:08 NOTICE: (30)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:11 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:47:11 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:47:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:47:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:47:11 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:11 INFO  : Using filters file /tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:11 INFO  : Building Path1 and Path2 listings
2026/07/27 04:47:12 INFO  : Path1 checking for diffs
2026/07/27 04:47:12 INFO  : Path2 checking for diffs
2026/07/27 04:47:12 INFO  : Checking access health
2026/07/27 04:47:12 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2026/07/27 04:47:12 INFO  : No changes found
2026/07/27 04:47:12 INFO  : Updating listings
2026/07/27 04:47:12 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:12 INFO  : Bisync successful
2026/07/27 04:47:12 NOTICE: (31)  : copy-listings include-initial
2026/07/27 04:47:12 NOTICE: (32)  : test INCLUDE - delete RCLONE_TEST files in excluded directories
2026/07/27 04:47:12 NOTICE: (33)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2026/07/27 04:47:12 DEBUG : fs cache: switching user supplied name "/tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA/" for canonical name "/tmp/044628latasoy5/check_access_filters/path2/subdir/subdirA"
2026/07/27 04:47:12 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:12 NOTICE: (34)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not/RCLONE_TEST
2026/07/27 04:47:12 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not/" for canonical name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir-not"
2026/07/27 04:47:13 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:13 NOTICE: (35)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2026/07/27 04:47:13 DEBUG : fs cache: switching user supplied name "/tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2/" for canonical name "/tmp/044628latasoy5/check_access_filters/path2/subdir-not/subdir-not2"
2026/07/27 04:47:13 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:13 NOTICE: (36)  : test INCLUDE - test should PASS
2026/07/27 04:47:13 NOTICE: (37)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:17 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:47:17 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:47:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:47:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:47:17 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:17 INFO  : Using filters file /tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:17 INFO  : Building Path1 and Path2 listings
2026/07/27 04:47:17 INFO  : Path1 checking for diffs
2026/07/27 04:47:17 INFO  : Path2 checking for diffs
2026/07/27 04:47:17 INFO  : Checking access health
2026/07/27 04:47:17 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2026/07/27 04:47:17 INFO  : No changes found
2026/07/27 04:47:17 INFO  : Updating listings
2026/07/27 04:47:17 INFO  : Validating listings for Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" vs Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:17 INFO  : Bisync successful
2026/07/27 04:47:17 NOTICE: (38)  : copy-listings include-pass-run
2026/07/27 04:47:17 NOTICE: (39)  : test INCLUDE - delete RCLONE_TEST files in included directories
2026/07/27 04:47:17 NOTICE: (40)  : delete-file /tmp/044628latasoy5/check_access_filters/path2/RCLONE_TEST
2026/07/27 04:47:17 DEBUG : fs cache: switching user supplied name "/tmp/044628latasoy5/check_access_filters/path2/" for canonical name "/tmp/044628latasoy5/check_access_filters/path2"
2026/07/27 04:47:17 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:17 NOTICE: (41)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir/RCLONE_TEST
2026/07/27 04:47:17 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir/" for canonical name "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdir"
2026/07/27 04:47:18 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:18 NOTICE: (42)  : delete-file TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/subdirX1/RCLONE_TEST
2026/07/27 04:47:18 DEBUG : Creating backend with remote "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/subdirX1/"
2026/07/27 04:47:19 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/subdirX1/" to be canonical "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/subdirX/subdirX1"
2026/07/27 04:47:20 INFO  : RCLONE_TEST: Deleted
2026/07/27 04:47:20 NOTICE: (43)  : test INCLUDE - test should ABORT
2026/07/27 04:47:20 NOTICE: (44)  : bisync check-access filters-file=/tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:24 DEBUG : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:47:24 INFO  : subdir: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdir-not: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdirX: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2026/07/27 04:47:24 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:47:24 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2026/07/27 04:47:24 INFO  : Synching Path1 "TestB2:rclone-test-biqusob6jiru/044628latasoy5/check_access_filters/path1/" with Path2 "/tmp/044628latasoy5/check_access_filters/path2/"
2026/07/27 04:47:24 INFO  : Using filters file /tmp/044628latasoy5/workdir/include-other-filtersfile.txt
2026/07/27 04:47:24 INFO  : Building Path1 and Path2 listings
2026/07/27 04:47:25 INFO  : Path1 checking for diffs
2026/07/27 04:47:25 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2026/07/27 04:47:25 INFO  : - Path1    File was deleted          - subdirX/subdirX1/RCLONE_TEST
2026/07/27 04:47:25 INFO  : Path1:    2 changes:    0 new,    0 modified,    2 deleted
2026/07/27 04:47:25 INFO  : Path2 checking for diffs
2026/07/27 04:47:25 INFO  : - Path2    File was deleted          - RCLONE_TEST
2026/07/27 04:47:25 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2026/07/27 04:47:25 INFO  : Checking access health
2026/07/27 04:47:25 ERROR : Access test failed: Path1 count 3, Path2 count 4 - RCLONE_TEST
2026/07/27 04:47:25 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2026/07/27 04:47:25 ERROR : -          Access test failed: Path2 file not found in Path1 - subdirX/subdirX1/RCLONE_TEST
2026/07/27 04:47:25 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2026/07/27 04:47:25 ERROR : Bisync critical error: check file check failed
2026/07/27 04:47:25 ERROR : Bisync aborted. Must run --resync to recover.
2026/07/27 04:47:25 NOTICE: Bisync error: bisync aborted
2026/07/27 04:47:25 NOTICE: (45)  : move-listings include-error-run
2026/07/27 04:47:25 INFO  : B2 bucket rclone-test-biqusob6jiru path 044628latasoy5/check_access_filters/path1: cleaning bucket "rclone-test-biqusob6jiru" of all files
2026/07/27 04:47:25 DEBUG : RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c2a_d20260727_m044632_c001_v0001136_t0020_u01785127592859")
2026/07/27 04:47:25 DEBUG : file1.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fab6_d20260727_m044633_c001_v0001130_t0027_u01785127593024")
2026/07/27 04:47:25 DEBUG : file2.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc487_d20260727_m044633_c001_v0001100_t0056_u01785127593206")
2026/07/27 04:47:25 DEBUG : file3.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b19_d20260727_m044633_c001_v0001161_t0034_u01785127593397")
2026/07/27 04:47:25 DEBUG : file4.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c2c_d20260727_m044633_c001_v0001136_t0055_u01785127593329")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f418881b45372ccf6_d20260727_m044724_c001_v7007000_t0000_u01785127644395")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f118cee5411dc79fd_d20260727_m044723_c001_v0001174_t0041_u01785127643079")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c96_d20260727_m044721_c001_v0001136_t0026_u01785127641056")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f42009d4baa5785ab_d20260727_m044716_c001_v7007000_t0000_u01785127636964")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1176d5d06a26e245_d20260727_m044715_c001_v0001173_t0025_u01785127635557")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc4d7_d20260727_m044713_c001_v0001100_t0040_u01785127633994")
2026/07/27 04:47:25 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f4188738dafb9a823_d20260727_m044711_c001_v7007000_t0000_u01785127631826")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f117c1e4c825255e1_d20260727_m044710_c001_v0001039_t0004_u01785127630252")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fb27_d20260727_m044708_c001_v0001130_t0043_u01785127628641")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f4188738dafb9a804_d20260727_m044707_c001_v7007000_t0000_u01785127627010")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f11397aa11c22ef4d_d20260727_m044705_c001_v0001117_t0012_u01785127625399")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b8d_d20260727_m044703_c001_v0001161_t0023_u01785127623777")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f425cfe14b2e0ca05_d20260727_m044658_c001_v7007000_t0000_u01785127618708")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1183b9d91b8a243f_d20260727_m044657_c001_v0001100_t0001_u01785127617406")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b6b_d20260727_m044655_c001_v0001161_t0059_u01785127615427")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f425cfe14b2e0ca04_d20260727_m044652_c001_v7007000_t0000_u01785127612387")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f10849c384fd33417_d20260727_m044651_c001_v0001043_t0055_u01785127611035")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c4e_d20260727_m044648_c001_v0001136_t0051_u01785127608866")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f419fba772940421f_d20260727_m044643_c001_v7007000_t0000_u01785127603687")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066bce5422289dc_d20260727_m044642_c001_v0001183_t0035_u01785127602427")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc49b_d20260727_m044640_c001_v0001100_t0033_u01785127600726")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f419fba772940421c_d20260727_m044639_c001_v7007000_t0000_u01785127599134")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f109f75a22238ce57_d20260727_m044637_c001_v0001106_t0040_u01785127597670")
2026/07/27 04:47:26 DEBUG : modtime_write_test: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fac2_d20260727_m044635_c001_v0001130_t0022_u01785127595646")
2026/07/27 04:47:27 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f4277822aa441271d_d20260727_m044713_c001_v7007000_t0000_u01785127633430")
2026/07/27 04:47:27 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c68_d20260727_m044701_c001_v0001136_t0010_u01785127621601")
2026/07/27 04:47:27 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f41910b2c82f185e6_d20260727_m044646_c001_v7007000_t0000_u01785127606204")
2026/07/27 04:47:27 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b1b_d20260727_m044633_c001_v0001161_t0058_u01785127593766")
2026/07/27 04:47:27 DEBUG : subdir-not/file20.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365faba_d20260727_m044633_c001_v0001130_t0023_u01785127593866")
2026/07/27 04:47:27 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fabc_d20260727_m044634_c001_v0001130_t0013_u01785127594390")
2026/07/27 04:47:27 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc48f_d20260727_m044634_c001_v0001100_t0059_u01785127594446")
2026/07/27 04:47:27 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f425cfe14b2e0ca09_d20260727_m044718_c001_v7007000_t0000_u01785127638548")
2026/07/27 04:47:27 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc4b3_d20260727_m044701_c001_v0001100_t0056_u01785127621424")
2026/07/27 04:47:27 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f400cc35119df51f3_d20260727_m044654_c001_v7007000_t0000_u01785127614904")
2026/07/27 04:47:27 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b1d_d20260727_m044634_c001_v0001161_t0050_u01785127594045")
2026/07/27 04:47:27 DEBUG : subdir/file20.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c32_d20260727_m044634_c001_v0001136_t0000_u01785127594124")
2026/07/27 04:47:27 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c34_d20260727_m044634_c001_v0001136_t0057_u01785127594619")
2026/07/27 04:47:27 DEBUG : subdir/subdirA/file30.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b25_d20260727_m044634_c001_v0001161_t0024_u01785127594681")
2026/07/27 04:47:27 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc48d_d20260727_m044634_c001_v0001100_t0055_u01785127594143")
2026/07/27 04:47:27 DEBUG : subdir/subdirB/file30.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1110c8b82d825b21_d20260727_m044634_c001_v0001161_t0042_u01785127594337")
2026/07/27 04:47:27 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fb1b_d20260727_m044701_c001_v0001130_t0052_u01785127621224")
2026/07/27 04:47:27 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f404729e3fe2c2a8c_d20260727_m044648_c001_v7007000_t0000_u01785127608327")
2026/07/27 04:47:27 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f112689318365fab8_d20260727_m044633_c001_v0001130_t0059_u01785127593508")
2026/07/27 04:47:27 DEBUG : subdirX/file20.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc489_d20260727_m044633_c001_v0001100_t0008_u01785127593535")
2026/07/27 04:47:27 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f407f393e6e4157a4_d20260727_m044720_c001_v7007000_t0000_u01785127640511")
2026/07/27 04:47:28 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1066f41c829e6c2e_d20260727_m044633_c001_v0001136_t0056_u01785127593657")
2026/07/27 04:47:28 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "4_z1f925d73e05dab1b9ff80e1b_f1009affdb39fc48b_d20260727_m044633_c001_v0001100_t0008_u01785127593853")
2026/07/27 04:47:28 DEBUG : Waiting for deletions to finish
2026/07/27 04:47:28 INFO  : file4.txt: Deleted
2026/07/27 04:47:28 INFO  : subdirX/subdirX1/file30.txt: Deleted
2026/07/27 04:47:28 INFO  : file2.txt: Deleted
2026/07/27 04:47:28 INFO  : file3.txt: Deleted
2026/07/27 04:47:28 INFO  : file1.txt: Deleted
2026/07/27 04:47:28 INFO  : subdirX/subdirX1/RCLONE_TEST: Deleted
2026/07/27 04:47:28 INFO  : subdir/RCLONE_TEST: Deleted
2026/07/27 04:47:28 INFO  : subdir/file20.txt: Deleted
2026/07/27 04:47:28 INFO  : subdir-not/RCLONE_TEST: Deleted
2026/07/27 04:47:28 INFO  : subdir-not/subdir-not2/file30.txt: Deleted
2026/07/27 04:47:28 INFO  : subdir/subdirB/file30.txt: Deleted
2026/07/27 04:47:28 INFO  : subdir-not/file20.txt: Deleted
2026/07/27 04:47:28 INFO  : subdirX/RCLONE_TEST: Deleted
2026/07/27 04:47:28 INFO  : subdirX/file20.txt: Deleted
2026/07/27 04:47:28 INFO  : subdir/subdirA/file30.txt: Deleted
2026/07/27 04:47:28 INFO  : subdir/subdirB/RCLONE_TEST: Deleted
2026/07/27 04:47:28 DEBUG : removing 4 level 2 directories
2026/07/27 04:47:28 INFO  : subdirX/subdirX1: Removing directory
2026/07/27 04:47:28 INFO  : subdir-not/subdir-not2: Removing directory
2026/07/27 04:47:28 INFO  : subdir/subdirA: Removing directory
2026/07/27 04:47:28 INFO  : subdir/subdirB: Removing directory
2026/07/27 04:47:28 DEBUG : removing 3 level 1 directories
2026/07/27 04:47:28 INFO  : subdirX: Removing directory
2026/07/27 04:47:28 INFO  : subdir: Removing directory
2026/07/27 04:47:28 INFO  : subdir-not: Removing directory
2026/07/27 04:47:28 DEBUG : removing 1 level 0 directories
2026/07/27 04:47:28 INFO  : Local file system at /tmp/044628latasoy5/check_access_filters/path2: Removing directory
    bisync_test.go:610: TEST check_access_filters PASSED
2026/07/27 04:47:28 DEBUG : B2 bucket rclone-test-biqusob6jiru: Purge remote
2026/07/27 04:47:28 INFO  : B2 bucket rclone-test-biqusob6jiru: cleaning bucket "rclone-test-biqusob6jiru" of all files
--- PASS: TestBisyncRemoteLocal (62.12s)
    --- PASS: TestBisyncRemoteLocal/check_access_filters (60.43s)
=== RUN   TestBisyncRemoteRemote
2026/07/27 04:47:29 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu"
2026/07/27 04:47:30 NOTICE: remote: TestB2:rclone-test-vavemab6bahu
=== RUN   TestBisyncRemoteRemote/resolve
2026/07/27 04:47:30 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6"
2026/07/27 04:47:31 INFO  : path1: Making directory
2026/07/27 04:47:32 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1"
2026/07/27 04:47:33 INFO  : path2: Making directory
2026/07/27 04:47:33 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2"
2026/07/27 04:47:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial"
2026/07/27 04:47:34 DEBUG : Creating backend with remote "/tmp/044730lohajov6/initdir/test_resolve-taluziq5"
2026/07/27 04:47:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : Local file system at /tmp/044730lohajov6/initdir/test_resolve-taluziq5: Waiting for checks to finish
2026/07/27 04:47:34 DEBUG : Local file system at /tmp/044730lohajov6/initdir/test_resolve-taluziq5: Waiting for transfers to finish
2026/07/27 04:47:34 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2026/07/27 04:47:34 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2026/07/27 04:47:34 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2026/07/27 04:47:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2026/07/27 04:47:34 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:47:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2026/07/27 04:47:34 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2026/07/27 04:47:34 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:47:34 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2026/07/27 04:47:34 INFO  : file2.txt: Copied (new)
2026/07/27 04:47:34 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2026/07/27 04:47:34 INFO  : file1.txt: Copied (new)
2026/07/27 04:47:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles"
2026/07/27 04:47:34 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:47:34 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:34 DEBUG : Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0: Waiting for checks to finish
2026/07/27 04:47:34 DEBUG : Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0: Waiting for transfers to finish
2026/07/27 04:47:34 DEBUG : file1L.txt.6455455d.partial: size = 33 OK
2026/07/27 04:47:34 DEBUG : file1R.txt.f5ef699a.partial: size = 33 OK
2026/07/27 04:47:34 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2026/07/27 04:47:34 DEBUG : file1L.txt.6455455d.partial: renamed to: file1L.txt
2026/07/27 04:47:34 INFO  : file1L.txt: Copied (new)
2026/07/27 04:47:34 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK
2026/07/27 04:47:34 DEBUG : file2.txt.67fcf9d0.partial: size = 37 OK
2026/07/27 04:47:34 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2026/07/27 04:47:34 DEBUG : file1R.txt.f5ef699a.partial: renamed to: file1R.txt
2026/07/27 04:47:34 INFO  : file1R.txt: Copied (new)
2026/07/27 04:47:34 DEBUG : file2.txt.67fcf9d0.partial: renamed to: file2.txt
2026/07/27 04:47:34 INFO  : file2.txt: Copied (new)
2026/07/27 04:47:34 INFO  : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: cleaning bucket "rclone-test-vavemab6bahu" of all files
2026/07/27 04:47:35 INFO  : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: cleaning bucket "rclone-test-vavemab6bahu" of all files
2026/07/27 04:47:36 NOTICE: checking initFs Local file system at /tmp/044730lohajov6/initdir/test_resolve-taluziq5
2026/07/27 04:47:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:36 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:36 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Waiting for checks to finish
2026/07/27 04:47:36 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Waiting for transfers to finish
2026/07/27 04:47:37 DEBUG : file1.txt: size = 0 OK
2026/07/27 04:47:37 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:47:37 INFO  : file1.txt: Copied (new)
2026/07/27 04:47:38 DEBUG : file2.txt: size = 0 OK
2026/07/27 04:47:38 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:47:38 INFO  : file2.txt: Copied (new)
2026/07/27 04:47:38 DEBUG : RCLONE_TEST: size = 109 OK
2026/07/27 04:47:38 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:38 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:47:38 NOTICE: checking Path1 B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1
2026/07/27 04:47:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2026/07/27 04:47:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2026/07/27 04:47:38 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Waiting for checks to finish
2026/07/27 04:47:38 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Waiting for transfers to finish
2026/07/27 04:47:39 DEBUG : RCLONE_TEST: size = 109 OK
2026/07/27 04:47:39 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2026/07/27 04:47:39 INFO  : RCLONE_TEST: Copied (new)
2026/07/27 04:47:39 DEBUG : file1.txt: size = 0 OK
2026/07/27 04:47:39 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:47:39 INFO  : file1.txt: Copied (new)
2026/07/27 04:47:40 DEBUG : file2.txt: size = 0 OK
2026/07/27 04:47:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2026/07/27 04:47:40 INFO  : file2.txt: Copied (new)
2026/07/27 04:47:40 NOTICE: checking path2 B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2
2026/07/27 04:47:40 NOTICE: (01)  : test resolve
2026/07/27 04:47:40 NOTICE: (02)  : test initial bisync
2026/07/27 04:47:40 NOTICE: (03)  : bisync resync
2026/07/27 04:48:41 DEBUG : pacer: low level retry 1/10 (error Head "https://f001.backblazeb2.com/b2api/v1/b2_download_file_by_id?fileId=4_z5f324da3e06dab1b9ff80e1b_f10217ac78e3a22eb_d20260727_m044740_c001_v0001176_t0021_u01785127660455": net/http: TLS handshake timeout)
2026/07/27 04:48:41 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/07/27 04:48:42 DEBUG : pacer: Reducing sleep to 10ms
2026/07/27 04:48:48 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:48:48 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:48:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:48:48 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:48:48 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:48:48 INFO  : Copying Path2 files to Path1
2026/07/27 04:48:48 INFO  : - Path2    Resync is copying files to         - Path1
2026/07/27 04:48:48 INFO  : There was nothing to transfer
2026/07/27 04:48:48 INFO  : - Path1    Resync is copying files to         - Path2
2026/07/27 04:48:48 INFO  : There was nothing to transfer
2026/07/27 04:48:48 INFO  : Resync updating listings
2026/07/27 04:48:48 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:48:48 INFO  : Bisync successful
2026/07/27 04:48:48 NOTICE: (04)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2026/07/27 04:48:48 NOTICE: (05)  : touch-glob 2001-01-02 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:48:48 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/"
2026/07/27 04:48:48 DEBUG : fs cache: renaming cache item "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" to be canonical "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:48:48 DEBUG : file1R.txt: Set modification time 2001-01-02
2026/07/27 04:48:48 NOTICE: (06)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:48:48 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:48:48 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:48:48 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:48:49 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:48:50 DEBUG : file1R.txt: size = 33 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:48:50 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2)
2026/07/27 04:48:50 DEBUG : file1R.txt: Sizes differ
2026/07/27 04:48:51 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:48:51 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:48:51 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2026/07/27 04:48:51 NOTICE: (07)  : touch-glob 2001-03-04 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:48:51 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:48:51 DEBUG : file1L.txt: Set modification time 2001-03-04
2026/07/27 04:48:51 NOTICE: (08)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:48:51 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:48:51 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:48:51 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:48:52 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:48:53 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:48:53 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1)
2026/07/27 04:48:53 DEBUG : file1L.txt: Sizes differ
2026/07/27 04:48:54 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:48:54 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:48:54 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2026/07/27 04:48:54 NOTICE: (09)  : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato
2026/07/27 04:48:54 NOTICE: (10)  : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato
2026/07/27 04:49:03 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:03 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:49:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:49:03 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:03 INFO  : Building Path1 and Path2 listings
2026/07/27 04:49:03 INFO  : Path1 checking for diffs
2026/07/27 04:49:03 INFO  : - Path1    File changed: size (larger), time (newer) - file1.txt
2026/07/27 04:49:03 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2026/07/27 04:49:03 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2026/07/27 04:49:03 INFO  : Path2 checking for diffs
2026/07/27 04:49:03 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2026/07/27 04:49:03 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2026/07/27 04:49:03 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2026/07/27 04:49:03 INFO  : Applying changes
2026/07/27 04:49:03 INFO  : Checking potential conflicts...
2026/07/27 04:49:03 ERROR : file1.txt: sha1 differ
2026/07/27 04:49:03 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:49:03 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:49:03 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:49:03 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:49:03 INFO  : file1.txt: Path1 is newer. Path1: 2001-03-04 00:00:00 +0000 UTC, Path2: 2001-01-02 00:00:00 +0000 UTC, Difference: 1464h0m0s
2026/07/27 04:49:03 INFO  : file1.txt: The winner is: Path1
2026/07/27 04:49:03 NOTICE: - Path2    Deleting Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt
2026/07/27 04:49:04 INFO  : file1.txt: Deleted
2026/07/27 04:49:04 INFO  : - Path1    Queue copy to Path2       - file1.txt
2026/07/27 04:49:04 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:49:04 INFO  : file1.txt: Copied (server-side copy)
2026/07/27 04:49:04 INFO  : Updating listings
2026/07/27 04:49:04 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:04 INFO  : Bisync successful
2026/07/27 04:49:04 NOTICE: (11)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2026/07/27 04:49:04 NOTICE: (12)  : touch-glob 2001-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:49:04 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:04 DEBUG : file1R.txt: Set modification time 2001-07-23
2026/07/27 04:49:04 NOTICE: (13)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:49:04 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:49:04 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:49:04 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:05 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:49:06 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:06 DEBUG : file1R.txt: Modification times differ by -3384h0m0s: 2001-07-23 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2026/07/27 04:49:06 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:49:06 DEBUG : file1.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2)
2026/07/27 04:49:06 DEBUG : file1R.txt: sha1 differ
2026/07/27 04:49:07 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:07 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:49:07 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2026/07/27 04:49:07 NOTICE: (14)  : touch-glob 2001-08-26 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:49:07 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:07 DEBUG : file1L.txt: Set modification time 2001-08-26
2026/07/27 04:49:07 NOTICE: (15)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:49:07 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:49:07 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:49:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:49:08 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:49:08 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:08 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2026/07/27 04:49:08 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:49:10 INFO  : file1L.txt: Updated modification time in destination
2026/07/27 04:49:10 DEBUG : file1L.txt: Unchanged skipping
2026/07/27 04:49:10 NOTICE: (16)  : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur
2026/07/27 04:49:10 NOTICE: (17)  : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur
2026/07/27 04:49:18 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:18 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:49:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:49:18 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:18 INFO  : Building Path1 and Path2 listings
2026/07/27 04:49:19 INFO  : Path1 checking for diffs
2026/07/27 04:49:19 INFO  : - Path1    File changed: time (newer) - file1.txt
2026/07/27 04:49:19 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2026/07/27 04:49:19 INFO  : (Modified:    1 newer,    0 older)
2026/07/27 04:49:19 INFO  : Path2 checking for diffs
2026/07/27 04:49:19 INFO  : - Path2    File changed: time (newer) - file1.txt
2026/07/27 04:49:19 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2026/07/27 04:49:19 INFO  : (Modified:    1 newer,    0 older)
2026/07/27 04:49:19 INFO  : Applying changes
2026/07/27 04:49:19 INFO  : Checking potential conflicts...
2026/07/27 04:49:19 ERROR : file1.txt: sha1 differ
2026/07/27 04:49:19 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:49:19 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:49:19 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:49:19 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:49:19 INFO  : file1.txt: The winner is: Path2
2026/07/27 04:49:19 NOTICE: - Path1    Renaming Path1 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.dinosaur1
2026/07/27 04:49:20 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.dinosaur1
2026/07/27 04:49:20 INFO  : file1.txt: Deleted
2026/07/27 04:49:20 NOTICE: - Path1    Queue copy to Path2       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.dinosaur1
2026/07/27 04:49:20 NOTICE: - Path2    Not renaming Path2 copy, as it was determined the winner - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt
2026/07/27 04:49:20 NOTICE: - Path2    Queue copy to Path1       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt
2026/07/27 04:49:20 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:49:21 INFO  : file1.txt: Copied (server-side copy)
2026/07/27 04:49:21 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:49:22 INFO  : file1.txt.dinosaur1: Copied (server-side copy)
2026/07/27 04:49:22 INFO  : Updating listings
2026/07/27 04:49:22 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:22 INFO  : Bisync successful
2026/07/27 04:49:22 NOTICE: (18)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2026/07/27 04:49:22 NOTICE: (19)  : touch-glob 2002-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:49:22 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:22 DEBUG : file1R.txt: Set modification time 2002-07-23
2026/07/27 04:49:22 NOTICE: (20)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:49:22 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:49:22 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:49:22 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:23 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:49:23 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:23 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2026/07/27 04:49:23 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:49:25 INFO  : file1R.txt: Updated modification time in destination
2026/07/27 04:49:25 DEBUG : file1R.txt: Unchanged skipping
2026/07/27 04:49:25 NOTICE: (21)  : touch-glob 2002-08-26 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:49:25 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:25 DEBUG : file1L.txt: Set modification time 2002-08-26
2026/07/27 04:49:25 NOTICE: (22)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:49:25 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:49:25 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:49:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:49:26 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:49:26 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:26 DEBUG : file1L.txt: Modification times differ by -9576h0m0s: 2002-08-26 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2026/07/27 04:49:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:49:26 DEBUG : file1.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1)
2026/07/27 04:49:26 DEBUG : file1L.txt: sha1 differ
2026/07/27 04:49:27 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:27 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:49:27 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2026/07/27 04:49:27 NOTICE: (23)  : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple
2026/07/27 04:49:27 NOTICE: (24)  : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple
2026/07/27 04:49:36 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:36 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:49:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:49:36 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:36 INFO  : Building Path1 and Path2 listings
2026/07/27 04:49:36 INFO  : Path1 checking for diffs
2026/07/27 04:49:36 INFO  : - Path1    File is new               - file1.txt
2026/07/27 04:49:36 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:49:36 INFO  : Path2 checking for diffs
2026/07/27 04:49:36 INFO  : - Path2    File is new               - file1.txt
2026/07/27 04:49:36 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:49:36 INFO  : Applying changes
2026/07/27 04:49:36 INFO  : Checking potential conflicts...
2026/07/27 04:49:36 ERROR : file1.txt: sha1 differ
2026/07/27 04:49:36 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:49:36 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:49:36 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:49:36 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:49:36 INFO  : file1.txt: Winner cannot be determined as sizes are equal. Path1: 33, Path2: 33, Difference: 0
2026/07/27 04:49:36 INFO  : file1.txt: A winner could not be determined.
2026/07/27 04:49:36 NOTICE: - Path1    Renaming Path1 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.apple1
2026/07/27 04:49:38 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.apple1
2026/07/27 04:49:38 INFO  : file1.txt: Deleted
2026/07/27 04:49:38 NOTICE: - Path1    Queue copy to Path2       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.apple1
2026/07/27 04:49:38 NOTICE: - Path2    Renaming Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.apple2
2026/07/27 04:49:38 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.apple2
2026/07/27 04:49:39 INFO  : file1.txt: Deleted
2026/07/27 04:49:39 NOTICE: - Path2    Queue copy to Path1       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.apple2
2026/07/27 04:49:39 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:49:39 INFO  : file1.txt.apple2: Copied (server-side copy)
2026/07/27 04:49:39 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:49:40 INFO  : file1.txt.apple1: Copied (server-side copy)
2026/07/27 04:49:40 INFO  : Updating listings
2026/07/27 04:49:40 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:40 INFO  : Bisync successful
2026/07/27 04:49:40 NOTICE: (25)  : test different suffixes
2026/07/27 04:49:40 NOTICE: (26)  : touch-glob 2003-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:49:40 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:40 DEBUG : file1R.txt: Set modification time 2003-07-23
2026/07/27 04:49:40 NOTICE: (27)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:49:40 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:49:40 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:49:40 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:41 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:49:41 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/07/27 04:49:43 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:43 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:49:43 INFO  : file1R.txt: Copied (new) to: file1.txt
2026/07/27 04:49:43 NOTICE: (28)  : touch-glob 2003-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:49:43 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:43 DEBUG : file1L.txt: Set modification time 2003-07-23
2026/07/27 04:49:43 NOTICE: (29)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:49:43 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:49:43 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:49:43 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:49:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:49:44 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/07/27 04:49:46 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:49:46 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:49:46 INFO  : file1L.txt: Copied (new) to: file1.txt
2026/07/27 04:49:46 NOTICE: (30)  : test bisync run with --conflict-resolve=older --conflict-loser=num --conflict-suffix=cloud,local
2026/07/27 04:49:46 NOTICE: (31)  : bisync conflict-resolve=older conflict-loser=num conflict-suffix=cloud,local
2026/07/27 04:49:54 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:54 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:49:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:49:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:49:54 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:54 INFO  : Building Path1 and Path2 listings
2026/07/27 04:49:54 INFO  : Path1 checking for diffs
2026/07/27 04:49:54 INFO  : - Path1    File is new               - file1.txt
2026/07/27 04:49:54 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:49:54 INFO  : Path2 checking for diffs
2026/07/27 04:49:54 INFO  : - Path2    File is new               - file1.txt
2026/07/27 04:49:54 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:49:54 INFO  : Applying changes
2026/07/27 04:49:54 INFO  : Checking potential conflicts...
2026/07/27 04:49:55 ERROR : file1.txt: sha1 differ
2026/07/27 04:49:55 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:49:55 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:49:55 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:49:55 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:49:55 INFO  : file1.txt: Winner cannot be determined as times are equal. Path1: 2003-07-23 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 0s
2026/07/27 04:49:55 INFO  : file1.txt: A winner could not be determined.
2026/07/27 04:49:55 NOTICE: - Path1    Renaming Path1 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.cloud1
2026/07/27 04:49:56 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.cloud1
2026/07/27 04:49:56 INFO  : file1.txt: Deleted
2026/07/27 04:49:56 NOTICE: - Path1    Queue copy to Path2       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.cloud1
2026/07/27 04:49:56 NOTICE: - Path2    Renaming Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.local1
2026/07/27 04:49:57 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.local1
2026/07/27 04:49:57 INFO  : file1.txt: Deleted
2026/07/27 04:49:57 NOTICE: - Path2    Queue copy to Path1       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.local1
2026/07/27 04:49:57 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:49:58 INFO  : file1.txt.local1: Copied (server-side copy)
2026/07/27 04:49:58 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:49:59 INFO  : file1.txt.cloud1: Copied (server-side copy)
2026/07/27 04:49:59 INFO  : Updating listings
2026/07/27 04:49:59 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:49:59 INFO  : Bisync successful
2026/07/27 04:49:59 NOTICE: (32)  : test legacy
2026/07/27 04:49:59 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:49:59 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:49:59 DEBUG : file1R.txt: Set modification time 2004-07-23
2026/07/27 04:49:59 NOTICE: (34)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:49:59 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:49:59 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:49:59 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:00 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:50:00 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/07/27 04:50:02 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:02 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:50:02 INFO  : file1R.txt: Copied (new) to: file1.txt
2026/07/27 04:50:02 NOTICE: (35)  : touch-glob 2004-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:50:02 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:50:02 DEBUG : file1L.txt: Set modification time 2004-07-23
2026/07/27 04:50:02 NOTICE: (36)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:50:02 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:50:02 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:50:02 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:50:03 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:50:03 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/07/27 04:50:04 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:04 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:50:04 INFO  : file1L.txt: Copied (new) to: file1.txt
2026/07/27 04:50:04 NOTICE: (37)  : test bisync run with --conflict-resolve=none --conflict-loser=pathname --conflict-suffix=.path
2026/07/27 04:50:04 NOTICE: (38)  : bisync conflict-resolve=none conflict-loser=pathname conflict-suffix=.path
2026/07/27 04:50:13 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:13 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:13 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:50:13 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:50:13 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:13 INFO  : Building Path1 and Path2 listings
2026/07/27 04:50:13 INFO  : Path1 checking for diffs
2026/07/27 04:50:13 INFO  : - Path1    File is new               - file1.txt
2026/07/27 04:50:13 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:50:13 INFO  : Path2 checking for diffs
2026/07/27 04:50:13 INFO  : - Path2    File is new               - file1.txt
2026/07/27 04:50:13 INFO  : Path2:    1 changes:    1 new,    0 modified,    0 deleted
2026/07/27 04:50:13 INFO  : Applying changes
2026/07/27 04:50:13 INFO  : Checking potential conflicts...
2026/07/27 04:50:13 ERROR : file1.txt: sha1 differ
2026/07/27 04:50:13 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:50:13 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:50:13 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:50:13 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:50:13 NOTICE: - Path1    Renaming Path1 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt..path1
2026/07/27 04:50:14 INFO  : file1.txt: Copied (server-side copy) to: file1.txt..path1
2026/07/27 04:50:15 INFO  : file1.txt: Deleted
2026/07/27 04:50:15 NOTICE: - Path1    Queue copy to Path2       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt..path1
2026/07/27 04:50:15 NOTICE: - Path2    Renaming Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt..path2
2026/07/27 04:50:15 INFO  : file1.txt: Copied (server-side copy) to: file1.txt..path2
2026/07/27 04:50:15 INFO  : file1.txt: Deleted
2026/07/27 04:50:15 NOTICE: - Path2    Queue copy to Path1       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt..path2
2026/07/27 04:50:15 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:50:16 INFO  : file1.txt..path2: Copied (server-side copy)
2026/07/27 04:50:16 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:50:17 INFO  : file1.txt..path1: Copied (server-side copy)
2026/07/27 04:50:17 INFO  : Updating listings
2026/07/27 04:50:17 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:17 INFO  : Bisync successful
2026/07/27 04:50:17 NOTICE: (39)  : test deletes on both sides with default suffix
2026/07/27 04:50:17 NOTICE: (40)  : touch-glob 2005-01-02 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:50:17 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:50:17 DEBUG : file1R.txt: Set modification time 2005-01-02
2026/07/27 04:50:17 NOTICE: (41)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:50:17 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:50:17 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:50:17 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:18 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:50:18 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2026/07/27 04:50:20 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:20 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:50:20 INFO  : file1R.txt: Copied (new) to: file1.txt
2026/07/27 04:50:20 NOTICE: (42)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file2.txt
2026/07/27 04:50:20 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file2.txt"
2026/07/27 04:50:20 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:50:20 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:50:21 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file2.txt"
2026/07/27 04:50:21 DEBUG : file1R.txt: size = 33 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:50:21 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1)
2026/07/27 04:50:21 DEBUG : file1R.txt: Sizes differ
2026/07/27 04:50:23 DEBUG : file2.txt: size = 33 OK
2026/07/27 04:50:23 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:50:23 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2026/07/27 04:50:23 NOTICE: (43)  : touch-glob 2006-03-04 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:50:23 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:50:23 DEBUG : file1L.txt: Set modification time 2006-03-04
2026/07/27 04:50:23 NOTICE: (44)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:50:23 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:50:23 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:50:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:50:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:50:24 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2026/07/27 04:50:26 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:50:26 INFO  : file1L.txt: Copied (new) to: file1.txt
2026/07/27 04:50:26 NOTICE: (45)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file2.txt
2026/07/27 04:50:26 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file2.txt"
2026/07/27 04:50:26 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:50:26 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:27 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file2.txt"
2026/07/27 04:50:27 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:50:27 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2)
2026/07/27 04:50:27 DEBUG : file1L.txt: Sizes differ
2026/07/27 04:50:29 DEBUG : file2.txt: size = 33 OK
2026/07/27 04:50:29 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:50:29 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2026/07/27 04:50:29 NOTICE: (46)  : test bisync run with --conflict-resolve=newer --conflict-loser=delete
2026/07/27 04:50:29 NOTICE: (47)  : bisync conflict-resolve=newer conflict-loser=delete
2026/07/27 04:50:38 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:38 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:50:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:50:38 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:38 INFO  : Building Path1 and Path2 listings
2026/07/27 04:50:39 INFO  : Path1 checking for diffs
2026/07/27 04:50:39 INFO  : - Path1    File changed: size (larger), time (newer) - file2.txt
2026/07/27 04:50:39 INFO  : - Path1    File is new               - file1.txt
2026/07/27 04:50:39 INFO  : Path1:    2 changes:    1 new,    1 modified,    0 deleted
2026/07/27 04:50:39 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2026/07/27 04:50:39 INFO  : Path2 checking for diffs
2026/07/27 04:50:39 INFO  : - Path2    File changed: size (larger), time (newer) - file2.txt
2026/07/27 04:50:39 INFO  : - Path2    File is new               - file1.txt
2026/07/27 04:50:39 INFO  : Path2:    2 changes:    1 new,    1 modified,    0 deleted
2026/07/27 04:50:39 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2026/07/27 04:50:39 INFO  : Applying changes
2026/07/27 04:50:39 INFO  : Checking potential conflicts...
2026/07/27 04:50:39 ERROR : file1.txt: sha1 differ
2026/07/27 04:50:39 ERROR : file2.txt: sha1 differ
2026/07/27 04:50:39 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 2 differences found
2026/07/27 04:50:39 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 2 errors while checking
2026/07/27 04:50:39 INFO  : Finished checking the potential conflicts. 2 differences found
2026/07/27 04:50:39 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:50:39 INFO  : file1.txt: Path1 is newer. Path1: 2006-03-04 00:00:00 +0000 UTC, Path2: 2005-01-02 00:00:00 +0000 UTC, Difference: 10224h0m0s
2026/07/27 04:50:39 INFO  : file1.txt: The winner is: Path1
2026/07/27 04:50:39 NOTICE: - Path2    Deleting Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt
2026/07/27 04:50:39 INFO  : file1.txt: Deleted
2026/07/27 04:50:39 INFO  : - Path1    Queue copy to Path2       - file1.txt
2026/07/27 04:50:39 NOTICE: - WARNING  New or changed in both paths       - file2.txt
2026/07/27 04:50:39 INFO  : file2.txt: Path2 is newer. Path1: 2005-01-02 00:00:00 +0000 UTC, Path2: 2006-03-04 00:00:00 +0000 UTC, Difference: 10224h0m0s
2026/07/27 04:50:39 INFO  : file2.txt: The winner is: Path2
2026/07/27 04:50:39 NOTICE: - Path1    Deleting Path1 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file2.txt
2026/07/27 04:50:40 INFO  : file2.txt: Deleted
2026/07/27 04:50:40 INFO  : - Path2    Queue copy to Path1       - file2.txt
2026/07/27 04:50:40 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:50:41 INFO  : file2.txt: Copied (server-side copy)
2026/07/27 04:50:41 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:50:41 INFO  : file1.txt: Copied (server-side copy)
2026/07/27 04:50:41 INFO  : Updating listings
2026/07/27 04:50:42 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:42 INFO  : Bisync successful
2026/07/27 04:50:42 NOTICE: (48)  : test --conflict-resolve newer --conflict-loser pathname --conflict-suffix path
2026/07/27 04:50:42 NOTICE: (49)  : touch-glob 2004-07-23 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1R.txt
2026/07/27 04:50:42 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:50:42 DEBUG : file1R.txt: Set modification time 2004-07-23
2026/07/27 04:50:42 NOTICE: (50)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/ file1.txt
2026/07/27 04:50:42 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/" as "file1.txt"
2026/07/27 04:50:42 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1R.txt"
2026/07/27 04:50:42 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:43 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2" as "file1.txt"
2026/07/27 04:50:43 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:43 DEBUG : file1R.txt: Modification times differ by 14136h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2026/07/27 04:50:43 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 (Local file system at /tmp/044730lohajov6/datadir/test_resolve-kugotip0)
2026/07/27 04:50:43 DEBUG : file1.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd (B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2)
2026/07/27 04:50:43 DEBUG : file1R.txt: sha1 differ
2026/07/27 04:50:45 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:45 DEBUG : file1R.txt: sha1 = 65c38b0ea2471fffaeb286d75ed7a07ae09fded8 OK
2026/07/27 04:50:45 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2026/07/27 04:50:45 NOTICE: (51)  : touch-glob 2004-07-25 /tmp/044730lohajov6/datadir/test_resolve-kugotip0/ file1L.txt
2026/07/27 04:50:45 DEBUG : fs cache: switching user supplied name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/" for canonical name "/tmp/044730lohajov6/datadir/test_resolve-kugotip0"
2026/07/27 04:50:45 DEBUG : file1L.txt: Set modification time 2004-07-25
2026/07/27 04:50:45 NOTICE: (52)  : copy-as /tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/ file1.txt
2026/07/27 04:50:45 DEBUG : copyFile "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt" to "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" as "file1.txt"
2026/07/27 04:50:45 DEBUG : Creating backend with remote "/tmp/044730lohajov6/datadir/test_resolve-kugotip0/file1L.txt"
2026/07/27 04:50:45 DEBUG : Creating backend with remote "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/"
2026/07/27 04:50:46 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1" as "file1.txt"
2026/07/27 04:50:46 DEBUG : file1.txt: size = 33 OK
2026/07/27 04:50:46 DEBUG : file1L.txt: Modification times differ by 14088h0m0s: 2004-07-25 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2026/07/27 04:50:46 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2026/07/27 04:50:47 INFO  : file1L.txt: Updated modification time in destination
2026/07/27 04:50:47 DEBUG : file1L.txt: Unchanged skipping
2026/07/27 04:50:47 NOTICE: (53)  : test bisync run with --conflict-resolve=newer --conflict-loser=pathname --conflict-suffix=path
2026/07/27 04:50:47 NOTICE: (54)  : bisync conflict-resolve=newer conflict-loser=pathname conflict-suffix=path
2026/07/27 04:50:56 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:56 DEBUG : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2026/07/27 04:50:56 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2026/07/27 04:50:56 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2026/07/27 04:50:56 INFO  : Synching Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" with Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:56 INFO  : Building Path1 and Path2 listings
2026/07/27 04:50:56 INFO  : Path1 checking for diffs
2026/07/27 04:50:56 INFO  : - Path1    File changed: time (older) - file1.txt
2026/07/27 04:50:56 INFO  : - Path1    File is new               - file2.txt
2026/07/27 04:50:56 INFO  : Path1:    2 changes:    1 new,    1 modified,    0 deleted
2026/07/27 04:50:56 INFO  : (Modified:    0 newer,    1 older)
2026/07/27 04:50:56 INFO  : Path2 checking for diffs
2026/07/27 04:50:56 INFO  : - Path2    File changed: time (older) - file1.txt
2026/07/27 04:50:56 INFO  : - Path2    File is new               - file2.txt
2026/07/27 04:50:56 INFO  : Path2:    2 changes:    1 new,    1 modified,    0 deleted
2026/07/27 04:50:56 INFO  : (Modified:    0 newer,    1 older)
2026/07/27 04:50:56 INFO  : Applying changes
2026/07/27 04:50:56 INFO  : Checking potential conflicts...
2026/07/27 04:50:56 ERROR : file1.txt: sha1 differ
2026/07/27 04:50:56 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 differences found
2026/07/27 04:50:56 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 errors while checking
2026/07/27 04:50:56 NOTICE: B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: 1 matching files
2026/07/27 04:50:56 INFO  : Finished checking the potential conflicts. 1 differences found
2026/07/27 04:50:56 NOTICE: - WARNING  New or changed in both paths       - file1.txt
2026/07/27 04:50:56 INFO  : file1.txt: Path1 is newer. Path1: 2004-07-25 00:00:00 +0000 UTC, Path2: 2004-07-23 00:00:00 +0000 UTC, Difference: 48h0m0s
2026/07/27 04:50:56 INFO  : file1.txt: The winner is: Path1
2026/07/27 04:50:56 NOTICE: - Path1    Not renaming Path1 copy, as it was determined the winner - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt
2026/07/27 04:50:56 NOTICE: - Path1    Queue copy to Path2       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt
2026/07/27 04:50:56 NOTICE: - Path2    Renaming Path2 copy                - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/file1.txt.path2
2026/07/27 04:50:57 INFO  : file1.txt: Copied (server-side copy) to: file1.txt.path2
2026/07/27 04:50:57 INFO  : file1.txt: Deleted
2026/07/27 04:50:57 NOTICE: - Path2    Queue copy to Path1       - TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/file1.txt.path2
2026/07/27 04:50:57 NOTICE: - WARNING  New or changed in both paths       - file2.txt
2026/07/27 04:50:57 INFO  : Files are equal! Skipping: file2.txt
2026/07/27 04:50:57 INFO  : - Path2    Do queued copies to                - Path1
2026/07/27 04:50:58 INFO  : file1.txt.path2: Copied (server-side copy)
2026/07/27 04:50:58 INFO  : - Path1    Do queued copies to                - Path2
2026/07/27 04:50:58 INFO  : file1.txt: Copied (server-side copy)
2026/07/27 04:50:58 INFO  : Updating listings
2026/07/27 04:50:59 INFO  : Validating listings for Path1 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path1/" vs Path2 "TestB2:rclone-test-vavemab6bahu/044730lohajov6/resolve/path2/"
2026/07/27 04:50:59 INFO  : Bisync successful
2026/07/27 04:50:59 INFO  : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path1: cleaning bucket "rclone-test-vavemab6bahu" of all files
2026/07/27 04:50:59 DEBUG : RCLONE_TEST: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f113b4b556e6ccbfd_d20260727_m044737_c001_v0001044_t0031_u01785127657460")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10794e9a8009f31f_d20260727_m045047_c001_v0001044_t0033_u01785127847295")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1058011698aeaf67_d20260727_m045025_c001_v0001106_t0003_u01785127825877")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f422046c13a72cd74_d20260727_m045014_c001_v7007000_t0000_u01785127814920")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1047d11765682dbe_d20260727_m045004_c001_v0001153_t0009_u01785127804633")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4020872c6012c69d_d20260727_m044956_c001_v7007000_t0000_u01785127796354")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10264ade289d6098_d20260727_m044945_c001_v0001033_t0003_u01785127785801")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41609b788ffdf9e9_d20260727_m044938_c001_v7007000_t0000_u01785127778090")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f103b0715942c7b3a_d20260727_m044927_c001_v0001184_t0040_u01785127767680")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f104f119992889e12_d20260727_m044920_c001_v0001109_t0016_u01785127760974")
2026/07/27 04:50:59 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f42009d4baa578606_d20260727_m044920_c001_v7007000_t0000_u01785127760572")
2026/07/27 04:51:00 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f109fe80a7c36e4a5_d20260727_m044909_c001_v0001179_t0000_u01785127749890")
2026/07/27 04:51:00 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10650f435a6fe077_d20260727_m044854_c001_v0001032_t0056_u01785127734478")
2026/07/27 04:51:00 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10217ac78e3a22e3_d20260727_m044737_c001_v0001176_t0007_u01785127657184")
2026/07/27 04:51:00 DEBUG : file1.txt..path1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f108b7df749222d9c_d20260727_m045014_c001_v0001090_t0051_u01785127814558")
2026/07/27 04:51:00 DEBUG : file1.txt..path2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f100114767fd18630_d20260727_m045016_c001_v0001150_t0016_u01785127816214")
2026/07/27 04:51:00 DEBUG : file1.txt.apple1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1021b26bbc6b9e2e_d20260727_m044937_c001_v0001104_t0042_u01785127777462")
2026/07/27 04:51:00 DEBUG : file1.txt.apple2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f102f8d1d34477665_d20260727_m044939_c001_v0001165_t0020_u01785127779390")
2026/07/27 04:51:00 DEBUG : file1.txt.cloud1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f103fe6b586ed5fc1_d20260727_m044956_c001_v0001157_t0058_u01785127796021")
2026/07/27 04:51:00 DEBUG : file1.txt.dinosaur1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1012718a8ba3caf2_d20260727_m044920_c001_v0001145_t0033_u01785127760148")
2026/07/27 04:51:00 DEBUG : file1.txt.local1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10032a11796d30b9_d20260727_m044958_c001_v0001105_t0039_u01785127798128")
2026/07/27 04:51:00 DEBUG : file1.txt.path2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11421e831ee31c3a_d20260727_m045057_c001_v0001165_t0013_u01785127857817")
2026/07/27 04:51:00 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1109e7e67b7e3751_d20260727_m045040_c001_v0001037_t0058_u01785127840889")
2026/07/27 04:51:00 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f417f9d998c80ee71_d20260727_m045040_c001_v7007000_t0000_u01785127840495")
2026/07/27 04:51:00 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f102df23befd1c7f8_d20260727_m045023_c001_v0001179_t0035_u01785127823103")
2026/07/27 04:51:00 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106e2aab36efa1ac_d20260727_m044737_c001_v0001106_t0049_u01785127657562")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4080a4a999e36a1f_d20260727_m045051_c001_v7007000_t0000_u01785127851969")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f107269f0625591a0_d20260727_m045050_c001_v0001117_t0054_u01785127850510")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106e2aab36efa563_d20260727_m045048_c001_v0001106_t0048_u01785127848066")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f413394997cf7f3d4_d20260727_m045033_c001_v7007000_t0000_u01785127833620")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10668a055bff9f3a_d20260727_m045032_c001_v0001181_t0015_u01785127832414")
2026/07/27 04:51:00 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10217ac78e3a2470_d20260727_m045030_c001_v0001176_t0042_u01785127830279")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f422046c13a72cd49_d20260727_m045009_c001_v7007000_t0000_u01785127809053")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10126a40b7f8c854_d20260727_m045007_c001_v0001033_t0048_u01785127807729")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f113b4b556e6ccd50_d20260727_m045005_c001_v0001044_t0015_u01785127805295")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4020872c6012c69b_d20260727_m044950_c001_v7007000_t0000_u01785127790500")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f117f98e16bfd33c9_d20260727_m044949_c001_v0001183_t0018_u01785127789298")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106e2aab36efa441_d20260727_m044946_c001_v0001106_t0019_u01785127786605")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41609b788ffdf9c4_d20260727_m044931_c001_v7007000_t0000_u01785127771846")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f113a6289fd53a0c7_d20260727_m044930_c001_v0001137_t0023_u01785127770378")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10217ac78e3a23c5_d20260727_m044928_c001_v0001176_t0044_u01785127768271")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f42009d4baa578603_d20260727_m044914_c001_v7007000_t0000_u01785127754202")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f101ccde828946a81_d20260727_m044912_c001_v0001100_t0038_u01785127752768")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f113b4b556e6cccb2_d20260727_m044910_c001_v0001044_t0046_u01785127750453")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f417f9d998c7f7e6a_d20260727_m044859_c001_v7007000_t0000_u01785127739022")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10304aae8ac1ddea_d20260727_m044857_c001_v0001174_t0047_u01785127737690")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106e2aab36efa320_d20260727_m044855_c001_v0001106_t0036_u01785127735168")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41455061a18378a4_d20260727_m044844_c001_v7007000_t0000_u01785127724089")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1088a265168c4922_d20260727_m044842_c001_v0001041_t0013_u01785127722806")
2026/07/27 04:51:01 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10217ac78e3a22eb_d20260727_m044740_c001_v0001176_t0021_u01785127660455")
2026/07/27 04:51:02 INFO  : B2 bucket rclone-test-vavemab6bahu path 044730lohajov6/resolve/path2: cleaning bucket "rclone-test-vavemab6bahu" of all files
2026/07/27 04:51:02 DEBUG : RCLONE_TEST: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f117f8c565b03bfe1_d20260727_m044739_c001_v0001172_t0052_u01785127659509")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f116aae36cdfc6eba_d20260727_m045058_c001_v0001181_t0029_u01785127858329")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41455061a1837a22_d20260727_m045057_c001_v7007000_t0000_u01785127857423")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1148b20e0eae3c12_d20260727_m045044_c001_v0001131_t0032_u01785127844668")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1059bca3c22743c9_d20260727_m045041_c001_v0001181_t0004_u01785127841467")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41713ed0816b94a4_d20260727_m045039_c001_v7007000_t0000_u01785127839778")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f111906f1d8db72b7_d20260727_m045020_c001_v0001130_t0021_u01785127820131")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4080a4a999e369fa_d20260727_m045015_c001_v7007000_t0000_u01785127815803")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1129861eae8df2ce_d20260727_m045001_c001_v0001161_t0036_u01785127801808")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f413394997cf7f3aa_d20260727_m044957_c001_v7007000_t0000_u01785127797733")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f10032a11796d3095_d20260727_m044942_c001_v0001105_t0055_u01785127782980")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f405e578fa4aa8162_d20260727_m044939_c001_v7007000_t0000_u01785127779014")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11576b4994dfd4ed_d20260727_m044924_c001_v0001165_t0034_u01785127764775")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f108b7df749222cf6_d20260727_m044907_c001_v0001090_t0038_u01785127747341")
2026/07/27 04:51:02 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1067ab8364addf71_d20260727_m044904_c001_v0001150_t0000_u01785127744528")
2026/07/27 04:51:03 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f42009d4baa5785f9_d20260727_m044904_c001_v7007000_t0000_u01785127744134")
2026/07/27 04:51:03 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f108ad50089d7a24b_d20260727_m044851_c001_v0001161_t0007_u01785127731552")
2026/07/27 04:51:03 DEBUG : file1.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106ac2d6f08a0ab6_d20260727_m044739_c001_v0001102_t0008_u01785127659707")
2026/07/27 04:51:03 DEBUG : file1.txt..path1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f110a917e6ea49dec_d20260727_m045016_c001_v0001146_t0024_u01785127816898")
2026/07/27 04:51:03 DEBUG : file1.txt..path2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f103ff59a4b097296_d20260727_m045015_c001_v0001136_t0046_u01785127815496")
2026/07/27 04:51:03 DEBUG : file1.txt.apple1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1111f443e6012f90_d20260727_m044939_c001_v0001137_t0018_u01785127779898")
2026/07/27 04:51:03 DEBUG : file1.txt.apple2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f105add724567854b_d20260727_m044938_c001_v0001182_t0048_u01785127778799")
2026/07/27 04:51:03 DEBUG : file1.txt.cloud1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f101a9418c6b916b2_d20260727_m044958_c001_v0001161_t0052_u01785127798978")
2026/07/27 04:51:03 DEBUG : file1.txt.dinosaur1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1154b6147f91af0b_d20260727_m044922_c001_v0001137_t0034_u01785127762148")
2026/07/27 04:51:03 DEBUG : file1.txt.local1: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1151395d9d066732_d20260727_m044956_c001_v0001039_t0022_u01785127796957")
2026/07/27 04:51:03 DEBUG : file1.txt.path2: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f107875e0349bf7c3_d20260727_m045057_c001_v0001184_t0003_u01785127857223")
2026/07/27 04:51:03 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11333dd9131239fb_d20260727_m045028_c001_v0001104_t0045_u01785127828948")
2026/07/27 04:51:03 DEBUG : file2.txt: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f118fa5d5f32ccd31_d20260727_m044739_c001_v0001101_t0054_u01785127659895")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41455061a1837a21_d20260727_m045056_c001_v7007000_t0000_u01785127856268")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1120bf6a16651643_d20260727_m045054_c001_v0001097_t0010_u01785127854785")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106ac2d6f08a0c50_d20260727_m045052_c001_v0001102_t0021_u01785127852508")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41713ed0816b94a3_d20260727_m045038_c001_v7007000_t0000_u01785127838675")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11371dc9fc995599_d20260727_m045036_c001_v0001144_t0004_u01785127836564")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f117f8c565b03c585_d20260727_m045034_c001_v0001172_t0028_u01785127834149")
2026/07/27 04:51:03 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4080a4a999e369f8_d20260727_m045013_c001_v7007000_t0000_u01785127813248")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f1095e6db692018ee_d20260727_m045011_c001_v0001120_t0030_u01785127811859")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f118fa5d5f32ccf29_d20260727_m045009_c001_v0001101_t0037_u01785127809574")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f413394997cf7f3a8_d20260727_m044954_c001_v7007000_t0000_u01785127794620")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f104688a1950774b5_d20260727_m044953_c001_v0001137_t0056_u01785127793262")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106ac2d6f08a0bd6_d20260727_m044951_c001_v0001102_t0059_u01785127791057")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f405e578fa4aa8161_d20260727_m044936_c001_v7007000_t0000_u01785127776189")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11741356bd4736ee_d20260727_m044934_c001_v0001098_t0055_u01785127774705")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f117f8c565b03c34b_d20260727_m044932_c001_v0001172_t0049_u01785127772417")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f4188738dafb9a8f7_d20260727_m044918_c001_v7007000_t0000_u01785127758726")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f11902f16a8dbbba4_d20260727_m044917_c001_v0001146_t0028_u01785127757191")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f118fa5d5f32cce7d_d20260727_m044914_c001_v0001101_t0016_u01785127754756")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f42009d4baa5785f8_d20260727_m044903_c001_v7007000_t0000_u01785127743411")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f104b1089f8c85b80_d20260727_m044901_c001_v0001117_t0056_u01785127741823")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f106ac2d6f08a0b5e_d20260727_m044859_c001_v0001102_t0027_u01785127739548")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f41713ed0816b945a_d20260727_m044848_c001_v7007000_t0000_u01785127728414")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f112555d3bc5383ca_d20260727_m044846_c001_v0001137_t0019_u01785127726879")
2026/07/27 04:51:04 DEBUG : modtime_write_test: Deleting (id "4_z5f324da3e06dab1b9ff80e1b_f117f8c565b03c1b7_d20260727_m044844_c001_v0001172_t0054_u01785127724620")
    bisync_test.go:610: TEST resolve PASSED
2026/07/27 04:51:05 DEBUG : B2 bucket rclone-test-vavemab6bahu: Purge remote
2026/07/27 04:51:05 INFO  : B2 bucket rclone-test-vavemab6bahu: cleaning bucket "rclone-test-vavemab6bahu" of all files
--- PASS: TestBisyncRemoteRemote (216.86s)
    --- PASS: TestBisyncRemoteRemote/resolve (214.78s)
PASS
2026/07/27 04:51:06 DEBUG : B2 bucket rclone-test-nirifel8jomi: Purge remote
2026/07/27 04:51:06 INFO  : B2 bucket rclone-test-nirifel8jomi: cleaning bucket "rclone-test-nirifel8jomi" of all files
2026/07/27 04:51:07 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteLocal$/^check_access_filters$|^TestBisyncRemoteRemote$/^resolve$'" - Finished OK in 4m40.307902678s (try 3/5)