"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteRemote$/^filtersfile_checks$'" - Starting (try 3/5)
2025/11/11 05:52:51 DEBUG : Creating backend with remote "TestB2:rclone-test-riyoqev1tega"
2025/11/11 05:52:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/11 05:52:52 DEBUG : Creating backend with remote "/tmp/rclone649952929"
=== RUN   TestBisyncLocalRemote
2025/11/11 05:52:52 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi"
2025/11/11 05:52:52 NOTICE: remote: TestB2:rclone-test-foyejos5kivi
=== RUN   TestBisyncLocalRemote/check_access_filters
2025/11/11 05:52:52 DEBUG : Creating backend with remote "/tmp/055252co"
2025/11/11 05:52:52 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1"
2025/11/11 05:52:52 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi/055252co"
2025/11/11 05:52:53 INFO  : path2: Making directory
2025/11/11 05:52:54 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2"
2025/11/11 05:52:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/11/11 05:52:55 DEBUG : Creating backend with remote "/tmp/055252co/initdir/test_check_access_filters-kuzomit1"
2025/11/11 05:52:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir-not: Making directory with metadata
2025/11/11 05:52:55 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/11/11 05:52:55 DEBUG : subdir: Making directory with metadata
2025/11/11 05:52:55 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/11 05:52:55 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/11 05:52:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdirX: Making directory with metadata
2025/11/11 05:52:55 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/11 05:52:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 INFO  : file1.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/11 05:52:55 INFO  : file2.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/11/11 05:52:55 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/11 05:52:55 INFO  : file3.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/11 05:52:55 INFO  : file4.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/11/11 05:52:55 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/11/11 05:52:55 DEBUG : subdir/subdirA: Making directory with metadata
2025/11/11 05:52:55 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir/subdirB: Making directory with metadata
2025/11/11 05:52:55 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/11/11 05:52:55 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/11/11 05:52:55 INFO  : subdir-not/file20.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/11/11 05:52:55 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2025/11/11 05:52:55 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/11/11 05:52:55 INFO  : subdirX/file20.txt: Copied (new)
2025/11/11 05:52:55 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:55 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/11/11 05:52:55 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/11 05:52:55 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : Local file system at /tmp/055252co/initdir/test_check_access_filters-kuzomit1: Waiting for checks to finish
2025/11/11 05:52:55 DEBUG : Local file system at /tmp/055252co/initdir/test_check_access_filters-kuzomit1: Waiting for transfers to finish
2025/11/11 05:52:55 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/11/11 05:52:55 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/11/11 05:52:55 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/11/11 05:52:55 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/11/11 05:52:55 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2025/11/11 05:52:55 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/11/11 05:52:55 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/11/11 05:52:55 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/11/11 05:52:55 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:52:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/11/11 05:52:55 DEBUG : Creating backend with remote "/tmp/055252co/datadir/test_check_access_filters-lurehut5"
2025/11/11 05:52:55 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:55 DEBUG : Local file system at /tmp/055252co/datadir/test_check_access_filters-lurehut5: Waiting for checks to finish
2025/11/11 05:52:55 DEBUG : Local file system at /tmp/055252co/datadir/test_check_access_filters-lurehut5: Waiting for transfers to finish
2025/11/11 05:52:55 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK
2025/11/11 05:52:55 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2025/11/11 05:52:55 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/11/11 05:52:55 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/11/11 05:52:55 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/11/11 05:52:55 INFO  : include-other-filtersfile.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/11/11 05:52:55 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/11/11 05:52:55 DEBUG : Waiting for deletions to finish
2025/11/11 05:52:55 ERROR : error listing: directory not found
2025/11/11 05:52:55 ERROR : Local file system at /tmp/055252co/check_access_filters/path1: Failed to list "": directory not found
2025/11/11 05:52:55 DEBUG : removing 1 level 0 directories
2025/11/11 05:52:55 INFO  : Local file system at /tmp/055252co/check_access_filters/path1: Removing directory
2025/11/11 05:52:55 ERROR : Failed to rmdir: stat /tmp/055252co/check_access_filters/path1: no such file or directory
2025/11/11 05:52:55 INFO  : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: cleaning bucket "rclone-test-foyejos5kivi" of all files
2025/11/11 05:52:56 ERROR : error listing: directory not found
2025/11/11 05:52:56 NOTICE: checking initFs Local file system at /tmp/055252co/initdir/test_check_access_filters-kuzomit1
2025/11/11 05:52:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not: Making directory with metadata
2025/11/11 05:52:56 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/11/11 05:52:56 DEBUG : subdir: Making directory with metadata
2025/11/11 05:52:56 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/11 05:52:56 DEBUG : subdirX: Making directory with metadata
2025/11/11 05:52:56 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/11 05:52:56 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/11 05:52:56 INFO  : file1.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/11/11 05:52:56 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/11 05:52:56 INFO  : file2.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/11 05:52:56 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 INFO  : file3.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/11/11 05:52:56 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/11/11 05:52:56 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/11/11 05:52:56 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/11 05:52:56 INFO  : file4.txt: Copied (new)
2025/11/11 05:52:56 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/11/11 05:52:56 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/subdirA: Making directory with metadata
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/11/11 05:52:56 DEBUG : subdir/subdirB: Making directory with metadata
2025/11/11 05:52:56 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:52:56 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/11/11 05:52:56 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/11/11 05:52:56 INFO  : subdirX/file20.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/11/11 05:52:56 INFO  : subdir-not/file20.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/11/11 05:52:56 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : Local file system at /tmp/055252co/check_access_filters/path1: Waiting for checks to finish
2025/11/11 05:52:56 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/11 05:52:56 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : Local file system at /tmp/055252co/check_access_filters/path1: Waiting for transfers to finish
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/11 05:52:56 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2025/11/11 05:52:56 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/11/11 05:52:56 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/11/11 05:52:56 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/11/11 05:52:56 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/11/11 05:52:56 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:52:56 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/11/11 05:52:56 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/11/11 05:52:56 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:52:56 NOTICE: checking Path1 Local file system at /tmp/055252co/check_access_filters/path1
2025/11/11 05:52:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 INFO  : subdir-not: Making directory
2025/11/11 05:52:56 INFO  : subdir: Making directory
2025/11/11 05:52:56 INFO  : subdirX: Making directory
2025/11/11 05:52:56 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 INFO  : subdir-not/subdir-not2: Making directory
2025/11/11 05:52:56 INFO  : subdir/subdirA: Making directory
2025/11/11 05:52:56 INFO  : subdir/subdirB: Making directory
2025/11/11 05:52:56 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 INFO  : subdirX/subdirX1: Making directory
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/11/11 05:52:56 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Waiting for checks to finish
2025/11/11 05:52:56 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Waiting for transfers to finish
2025/11/11 05:52:57 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/11 05:52:57 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:57 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:52:58 DEBUG : file2.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : file2.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : file3.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : file3.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : file1.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : file1.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:58 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:58 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/11/11 05:52:58 DEBUG : file4.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : file4.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:58 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:58 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:58 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:58 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:58 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:58 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir-not/file20.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : subdir-not/file20.txt: Copied (new)
2025/11/11 05:52:58 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2025/11/11 05:52:58 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:58 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/11/11 05:52:59 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2025/11/11 05:52:59 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:59 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/11/11 05:52:59 DEBUG : subdirX/file20.txt: size = 0 OK
2025/11/11 05:52:59 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:59 INFO  : subdirX/file20.txt: Copied (new)
2025/11/11 05:52:59 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:59 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:59 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/11/11 05:52:59 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:59 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:59 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/11/11 05:52:59 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2025/11/11 05:52:59 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:59 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/11/11 05:52:59 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2025/11/11 05:52:59 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:52:59 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/11/11 05:52:59 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2025/11/11 05:52:59 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:52:59 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/11/11 05:52:59 NOTICE: checking path2 B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2
2025/11/11 05:53:00 NOTICE: (01)  : test check-access-filters
2025/11/11 05:53:00 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/11/11 05:53:00 NOTICE: (03)  : copy-file /tmp/055252co/datadir/test_check_access_filters-lurehut5/exclude-other-filtersfile.txt /tmp/055252co/workdir/
2025/11/11 05:53:00 DEBUG : copyFile "/tmp/055252co/datadir/test_check_access_filters-lurehut5/exclude-other-filtersfile.txt" to "/tmp/055252co/workdir/" as ""
2025/11/11 05:53:00 DEBUG : Creating backend with remote "/tmp/055252co/datadir/test_check_access_filters-lurehut5/exclude-other-filtersfile.txt"
2025/11/11 05:53:00 DEBUG : Creating backend with remote "/tmp/055252co/workdir/"
2025/11/11 05:53:00 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/055252co/workdir" as "exclude-other-filtersfile.txt"
2025/11/11 05:53:00 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:00 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2025/11/11 05:53:00 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/11/11 05:53:00 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/11/11 05:53:00 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/11/11 05:53:00 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/11/11 05:53:00 NOTICE: (05)  : bisync resync filters-file=/tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:03 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:03 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:03 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:03 INFO  : Using filters file /tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:03 INFO  : Storing filters file hash to /tmp/055252co/workdir/exclude-other-filtersfile.txt.md5
2025/11/11 05:53:03 INFO  : Copying Path2 files to Path1
2025/11/11 05:53:03 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/11 05:53:04 INFO  : There was nothing to transfer
2025/11/11 05:53:04 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/11 05:53:04 INFO  : There was nothing to transfer
2025/11/11 05:53:04 INFO  : Resync updating listings
2025/11/11 05:53:04 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:04 INFO  : Bisync successful
2025/11/11 05:53:04 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/11/11 05:53:04 NOTICE: (07)  : bisync check-access filters-file=/tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:08 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:08 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:08 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:08 INFO  : Using filters file /tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:08 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:09 INFO  : Path1 checking for diffs
2025/11/11 05:53:09 INFO  : Path2 checking for diffs
2025/11/11 05:53:09 INFO  : Checking access health
2025/11/11 05:53:09 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/11/11 05:53:09 INFO  : No changes found
2025/11/11 05:53:09 INFO  : Updating listings
2025/11/11 05:53:09 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:09 INFO  : Bisync successful
2025/11/11 05:53:09 NOTICE: (08)  : copy-listings exclude-initial
2025/11/11 05:53:09 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2025/11/11 05:53:09 NOTICE: (10)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/11/11 05:53:09 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA/"
2025/11/11 05:53:10 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA/" to be canonical "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA"
2025/11/11 05:53:11 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:11 NOTICE: (11)  : delete-file /tmp/055252co/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/11/11 05:53:11 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1/subdir-not/"
2025/11/11 05:53:11 DEBUG : fs cache: renaming cache item "/tmp/055252co/check_access_filters/path1/subdir-not/" to be canonical "/tmp/055252co/check_access_filters/path1/subdir-not"
2025/11/11 05:53:11 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:11 NOTICE: (12)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/11/11 05:53:11 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2/"
2025/11/11 05:53:12 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2"
2025/11/11 05:53:13 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:13 NOTICE: (13)  : delete-file /tmp/055252co/check_access_filters/path1/subdirX/RCLONE_TEST
2025/11/11 05:53:13 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1/subdirX/"
2025/11/11 05:53:13 DEBUG : fs cache: renaming cache item "/tmp/055252co/check_access_filters/path1/subdirX/" to be canonical "/tmp/055252co/check_access_filters/path1/subdirX"
2025/11/11 05:53:13 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:13 NOTICE: (14)  : test EXCLUDE - test should PASS
2025/11/11 05:53:13 NOTICE: (15)  : bisync check-access filters-file=/tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:17 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:17 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:17 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:17 INFO  : Using filters file /tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:17 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:17 INFO  : Path1 checking for diffs
2025/11/11 05:53:17 INFO  : Path2 checking for diffs
2025/11/11 05:53:17 INFO  : Checking access health
2025/11/11 05:53:17 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/11/11 05:53:17 INFO  : No changes found
2025/11/11 05:53:17 INFO  : Updating listings
2025/11/11 05:53:17 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:17 INFO  : Bisync successful
2025/11/11 05:53:17 NOTICE: (16)  : copy-listings exclude-pass-run
2025/11/11 05:53:17 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2025/11/11 05:53:17 NOTICE: (18)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/RCLONE_TEST
2025/11/11 05:53:17 DEBUG : Creating backend with remote "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:19 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/" to be canonical "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2"
2025/11/11 05:53:19 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:19 NOTICE: (19)  : delete-file /tmp/055252co/check_access_filters/path1/subdir/RCLONE_TEST
2025/11/11 05:53:19 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1/subdir/"
2025/11/11 05:53:19 DEBUG : fs cache: renaming cache item "/tmp/055252co/check_access_filters/path1/subdir/" to be canonical "/tmp/055252co/check_access_filters/path1/subdir"
2025/11/11 05:53:19 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:19 NOTICE: (20)  : test EXCLUDE - test should ABORT
2025/11/11 05:53:19 NOTICE: (21)  : bisync check-access filters-file=/tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:23 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:23 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:23 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:23 INFO  : Using filters file /tmp/055252co/workdir/exclude-other-filtersfile.txt
2025/11/11 05:53:23 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:23 INFO  : Path1 checking for diffs
2025/11/11 05:53:23 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/11/11 05:53:23 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/11/11 05:53:23 INFO  : Path2 checking for diffs
2025/11/11 05:53:23 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/11/11 05:53:23 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/11/11 05:53:23 INFO  : Checking access health
2025/11/11 05:53:23 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/11/11 05:53:23 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/11/11 05:53:23 ERROR : Bisync critical error: check file check failed
2025/11/11 05:53:23 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:53:23 NOTICE: Bisync error: bisync aborted
2025/11/11 05:53:23 NOTICE: (22)  : move-listings exclude-error-run
2025/11/11 05:53:23 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2025/11/11 05:53:23 NOTICE: (24)  : test reset to the initial state
2025/11/11 05:53:23 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial /tmp/055252co/check_access_filters/path1/
2025/11/11 05:53:23 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1/"
2025/11/11 05:53:23 DEBUG : fs cache: renaming cache item "/tmp/055252co/check_access_filters/path1/" to be canonical "/tmp/055252co/check_access_filters/path1"
2025/11/11 05:53:23 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/11 05:53:23 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/11/11 05:53:23 DEBUG : file1.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/11 05:53:23 DEBUG : file1.txt: Unchanged skipping
2025/11/11 05:53:23 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : file2.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file3.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : file4.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file2.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : file3.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/11/11 05:53:23 DEBUG : file4.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:23 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:23 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:23 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/11/11 05:53:23 DEBUG : subdir-not/file20.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/11/11 05:53:23 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/11/11 05:53:23 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/file20.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/11/11 05:53:23 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/11/11 05:53:23 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:53:23 DEBUG : subdirX/file20.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/11/11 05:53:23 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/11/11 05:53:23 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/11/11 05:53:23 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:53:23 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/11/11 05:53:23 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:53:23 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/11/11 05:53:23 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/11/11 05:53:23 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:23 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:23 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/11/11 05:53:23 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : Local file system at /tmp/055252co/check_access_filters/path1: Waiting for checks to finish
2025/11/11 05:53:23 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:23 DEBUG : Local file system at /tmp/055252co/check_access_filters/path1: Waiting for transfers to finish
2025/11/11 05:53:23 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:23 NOTICE: (26)  : sync-dir /tmp/055252co/check_access_filters/path1/ TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/
2025/11/11 05:53:23 DEBUG : fs cache: switching user supplied name "/tmp/055252co/check_access_filters/path1/" for canonical name "/tmp/055252co/check_access_filters/path1"
2025/11/11 05:53:23 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/" for canonical name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2"
2025/11/11 05:53:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:23 DEBUG : file2.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:23 DEBUG : file2.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : file1.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:23 DEBUG : file4.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file3.txt: size = 0 OK
2025/11/11 05:53:23 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:23 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:23 DEBUG : file4.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : file3.txt: Unchanged skipping
2025/11/11 05:53:23 DEBUG : file1.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdirX/file20.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : subdir-not/file20.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir/file20.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:24 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:24 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2025/11/11 05:53:24 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/11/11 05:53:24 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/11/11 05:53:24 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Waiting for checks to finish
2025/11/11 05:53:24 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Waiting for transfers to finish
2025/11/11 05:53:24 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/11 05:53:24 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:53:24 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:53:25 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:25 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:53:25 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/11/11 05:53:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2025/11/11 05:53:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:53:25 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/11/11 05:53:25 DEBUG : Waiting for deletions to finish
2025/11/11 05:53:25 NOTICE: (27)  : copy-file /tmp/055252co/datadir/test_check_access_filters-lurehut5/include-other-filtersfile.txt /tmp/055252co/workdir/
2025/11/11 05:53:25 DEBUG : copyFile "/tmp/055252co/datadir/test_check_access_filters-lurehut5/include-other-filtersfile.txt" to "/tmp/055252co/workdir/" as ""
2025/11/11 05:53:25 DEBUG : Creating backend with remote "/tmp/055252co/datadir/test_check_access_filters-lurehut5/include-other-filtersfile.txt"
2025/11/11 05:53:25 DEBUG : Creating backend with remote "/tmp/055252co/workdir/"
2025/11/11 05:53:25 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/055252co/workdir" as "include-other-filtersfile.txt"
2025/11/11 05:53:25 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:25 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK
2025/11/11 05:53:25 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/11/11 05:53:25 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/11/11 05:53:25 INFO  : include-other-filtersfile.txt: Copied (new)
2025/11/11 05:53:25 NOTICE: (28)  : bisync resync filters-file=/tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:29 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:29 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:29 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:29 INFO  : Using filters file /tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:29 INFO  : Storing filters file hash to /tmp/055252co/workdir/include-other-filtersfile.txt.md5
2025/11/11 05:53:29 INFO  : Copying Path2 files to Path1
2025/11/11 05:53:29 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/11 05:53:30 INFO  : There was nothing to transfer
2025/11/11 05:53:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/11 05:53:30 INFO  : There was nothing to transfer
2025/11/11 05:53:30 INFO  : Resync updating listings
2025/11/11 05:53:30 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:30 INFO  : Bisync successful
2025/11/11 05:53:30 NOTICE: (29)  : test INCLUDE - test include/exclude filters for check access
2025/11/11 05:53:30 NOTICE: (30)  : bisync check-access filters-file=/tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:35 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:35 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:35 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:35 INFO  : Using filters file /tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:35 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:35 INFO  : Path1 checking for diffs
2025/11/11 05:53:35 INFO  : Path2 checking for diffs
2025/11/11 05:53:35 INFO  : Checking access health
2025/11/11 05:53:35 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2025/11/11 05:53:35 INFO  : No changes found
2025/11/11 05:53:35 INFO  : Updating listings
2025/11/11 05:53:35 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:35 INFO  : Bisync successful
2025/11/11 05:53:35 NOTICE: (31)  : copy-listings include-initial
2025/11/11 05:53:35 NOTICE: (32)  : test INCLUDE - delete RCLONE_TEST files in excluded directories
2025/11/11 05:53:35 NOTICE: (33)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/11/11 05:53:35 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA/" for canonical name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir/subdirA"
2025/11/11 05:53:36 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:36 NOTICE: (34)  : delete-file /tmp/055252co/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/11/11 05:53:36 DEBUG : fs cache: switching user supplied name "/tmp/055252co/check_access_filters/path1/subdir-not/" for canonical name "/tmp/055252co/check_access_filters/path1/subdir-not"
2025/11/11 05:53:36 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:36 NOTICE: (35)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/11/11 05:53:36 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2/" for canonical name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/subdir-not/subdir-not2"
2025/11/11 05:53:37 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:37 NOTICE: (36)  : test INCLUDE - test should PASS
2025/11/11 05:53:37 NOTICE: (37)  : bisync check-access filters-file=/tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:41 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:41 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:41 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:41 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:41 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:41 INFO  : Using filters file /tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:41 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:42 INFO  : Path1 checking for diffs
2025/11/11 05:53:42 INFO  : Path2 checking for diffs
2025/11/11 05:53:42 INFO  : Checking access health
2025/11/11 05:53:42 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2025/11/11 05:53:42 INFO  : No changes found
2025/11/11 05:53:42 INFO  : Updating listings
2025/11/11 05:53:42 INFO  : Validating listings for Path1 "/tmp/055252co/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:42 INFO  : Bisync successful
2025/11/11 05:53:42 NOTICE: (38)  : copy-listings include-pass-run
2025/11/11 05:53:42 NOTICE: (39)  : test INCLUDE - delete RCLONE_TEST files in included directories
2025/11/11 05:53:42 NOTICE: (40)  : delete-file TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/RCLONE_TEST
2025/11/11 05:53:42 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/" for canonical name "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2"
2025/11/11 05:53:42 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:42 NOTICE: (41)  : delete-file /tmp/055252co/check_access_filters/path1/subdir/RCLONE_TEST
2025/11/11 05:53:42 DEBUG : fs cache: switching user supplied name "/tmp/055252co/check_access_filters/path1/subdir/" for canonical name "/tmp/055252co/check_access_filters/path1/subdir"
2025/11/11 05:53:42 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:42 NOTICE: (42)  : delete-file /tmp/055252co/check_access_filters/path1/subdirX/subdirX1/RCLONE_TEST
2025/11/11 05:53:42 DEBUG : Creating backend with remote "/tmp/055252co/check_access_filters/path1/subdirX/subdirX1/"
2025/11/11 05:53:42 DEBUG : fs cache: renaming cache item "/tmp/055252co/check_access_filters/path1/subdirX/subdirX1/" to be canonical "/tmp/055252co/check_access_filters/path1/subdirX/subdirX1"
2025/11/11 05:53:42 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:42 NOTICE: (43)  : test INCLUDE - test should ABORT
2025/11/11 05:53:42 NOTICE: (44)  : bisync check-access filters-file=/tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:46 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/11/11 05:53:46 DEBUG : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:53:46 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:53:46 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/11 05:53:46 INFO  : Synching Path1 "/tmp/055252co/check_access_filters/path1/" with Path2 "TestB2:rclone-test-foyejos5kivi/055252co/check_access_filters/path2/"
2025/11/11 05:53:46 INFO  : Using filters file /tmp/055252co/workdir/include-other-filtersfile.txt
2025/11/11 05:53:46 INFO  : Building Path1 and Path2 listings
2025/11/11 05:53:46 INFO  : Path1 checking for diffs
2025/11/11 05:53:46 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/11/11 05:53:46 INFO  : - Path1    File was deleted          - subdirX/subdirX1/RCLONE_TEST
2025/11/11 05:53:46 INFO  : Path1:    2 changes:    0 new,    0 modified,    2 deleted
2025/11/11 05:53:46 INFO  : Path2 checking for diffs
2025/11/11 05:53:46 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/11/11 05:53:46 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/11/11 05:53:46 INFO  : Checking access health
2025/11/11 05:53:46 ERROR : Access test failed: Path1 count 3, Path2 count 4 - RCLONE_TEST
2025/11/11 05:53:46 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/11/11 05:53:46 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/11/11 05:53:46 ERROR : -          Access test failed: Path2 file not found in Path1 - subdirX/subdirX1/RCLONE_TEST
2025/11/11 05:53:46 ERROR : Bisync critical error: check file check failed
2025/11/11 05:53:46 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:53:46 NOTICE: Bisync error: bisync aborted
2025/11/11 05:53:46 NOTICE: (45)  : move-listings include-error-run
2025/11/11 05:53:46 DEBUG : Waiting for deletions to finish
2025/11/11 05:53:46 INFO  : file2.txt: Deleted
2025/11/11 05:53:46 INFO  : subdirX/subdirX1/file30.txt: Deleted
2025/11/11 05:53:46 INFO  : file3.txt: Deleted
2025/11/11 05:53:46 INFO  : RCLONE_TEST: Deleted
2025/11/11 05:53:46 INFO  : file1.txt: Deleted
2025/11/11 05:53:46 INFO  : subdirX/file20.txt: Deleted
2025/11/11 05:53:46 INFO  : subdir-not/file20.txt: Deleted
2025/11/11 05:53:46 INFO  : subdir-not/subdir-not2/file30.txt: Deleted
2025/11/11 05:53:46 INFO  : subdirX/RCLONE_TEST: Deleted
2025/11/11 05:53:46 INFO  : subdir/file20.txt: Deleted
2025/11/11 05:53:46 INFO  : file4.txt: Deleted
2025/11/11 05:53:46 INFO  : subdir/subdirB/file30.txt: Deleted
2025/11/11 05:53:46 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Deleted
2025/11/11 05:53:46 INFO  : subdir/subdirB/RCLONE_TEST: Deleted
2025/11/11 05:53:46 INFO  : subdir/subdirA/file30.txt: Deleted
2025/11/11 05:53:46 INFO  : subdir/subdirA/RCLONE_TEST: Deleted
2025/11/11 05:53:46 DEBUG : removing 4 level 2 directories
2025/11/11 05:53:46 INFO  : subdirX/subdirX1: Removing directory
2025/11/11 05:53:46 INFO  : subdir/subdirA: Removing directory
2025/11/11 05:53:46 INFO  : subdir-not/subdir-not2: Removing directory
2025/11/11 05:53:46 INFO  : subdir/subdirB: Removing directory
2025/11/11 05:53:46 DEBUG : removing 3 level 1 directories
2025/11/11 05:53:46 INFO  : subdirX: Removing directory
2025/11/11 05:53:46 INFO  : subdir: Removing directory
2025/11/11 05:53:46 INFO  : subdir-not: Removing directory
2025/11/11 05:53:46 DEBUG : removing 1 level 0 directories
2025/11/11 05:53:46 INFO  : Local file system at /tmp/055252co/check_access_filters/path1: Removing directory
2025/11/11 05:53:46 INFO  : B2 bucket rclone-test-foyejos5kivi path 055252co/check_access_filters/path2: cleaning bucket "rclone-test-foyejos5kivi" of all files
2025/11/11 05:53:47 DEBUG : RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f413f2cbeee85b1bd_d20251111_m055342_c001_v7007000_t0000_u01762840422524")
2025/11/11 05:53:47 DEBUG : RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec7fc_d20251111_m055324_c001_v0001146_t0025_u01762840404378")
2025/11/11 05:53:47 DEBUG : RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f402687500da1b74f_d20251111_m055319_c001_v7007000_t0000_u01762840399283")
2025/11/11 05:53:47 DEBUG : RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec74b_d20251111_m055257_c001_v0001146_t0052_u01762840377709")
2025/11/11 05:53:47 DEBUG : file1.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff4f_d20251111_m055257_c001_v0001117_t0048_u01762840377521")
2025/11/11 05:53:47 DEBUG : file2.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e470114_d20251111_m055257_c001_v0001100_t0017_u01762840377903")
2025/11/11 05:53:47 DEBUG : file3.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df54d_d20251111_m055258_c001_v0001179_t0057_u01762840378082")
2025/11/11 05:53:47 DEBUG : file4.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec751_d20251111_m055258_c001_v0001146_t0028_u01762840378079")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f413f2cbeee85b1d9_d20251111_m055346_c001_v7007000_t0000_u01762840426185")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f1065670d941c6a1d_d20251111_m055344_c001_v0001182_t0031_u01762840424982")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff8b_d20251111_m055343_c001_v0001117_t0041_u01762840423072")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f404f8df6aad163bf_d20251111_m055341_c001_v7007000_t0000_u01762840421537")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f1058e5b6fe0783ca_d20251111_m055340_c001_v0001040_t0019_u01762840420023")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df620_d20251111_m055338_c001_v0001179_t0037_u01762840418243")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f402687500da1b7a3_d20251111_m055334_c001_v7007000_t0000_u01762840414961")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104dbc205f5c5946_d20251111_m055333_c001_v0001101_t0035_u01762840413547")
2025/11/11 05:53:47 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec82e_d20251111_m055331_c001_v0001146_t0028_u01762840411332")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f402687500da1b773_d20251111_m055329_c001_v7007000_t0000_u01762840409598")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f1061de0b4f3e8a4e_d20251111_m055327_c001_v0001144_t0050_u01762840407826")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e470147_d20251111_m055325_c001_v0001100_t0026_u01762840405803")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f402687500da1b75c_d20251111_m055323_c001_v7007000_t0000_u01762840403050")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f1082bd84b6516c4f_d20251111_m055321_c001_v0001109_t0053_u01762840401486")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e47013b_d20251111_m055319_c001_v0001100_t0005_u01762840399836")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f402687500da1b740_d20251111_m055317_c001_v7007000_t0000_u01762840397184")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f112aae042013d5e0_d20251111_m055315_c001_v0001153_t0001_u01762840395867")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df5a8_d20251111_m055313_c001_v0001179_t0050_u01762840393739")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f404f8df6aad16305_d20251111_m055308_c001_v7007000_t0000_u01762840388579")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f105eab669794494f_d20251111_m055306_c001_v0001117_t0049_u01762840386858")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff5f_d20251111_m055305_c001_v0001117_t0046_u01762840385074")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f404f8df6aad162cc_d20251111_m055303_c001_v7007000_t0000_u01762840383422")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117b435e3f4e2af9_d20251111_m055302_c001_v0001165_t0037_u01762840382171")
2025/11/11 05:53:48 DEBUG : modtime_write_test: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec769_d20251111_m055300_c001_v0001146_t0049_u01762840380232")
2025/11/11 05:53:48 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e470117_d20251111_m055258_c001_v0001100_t0032_u01762840378217")
2025/11/11 05:53:48 DEBUG : subdir-not/file20.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff51_d20251111_m055258_c001_v0001117_t0027_u01762840378370")
2025/11/11 05:53:48 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f4238fd51c201cb83_d20251111_m055337_c001_v7007000_t0000_u01762840417692")
2025/11/11 05:53:48 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff76_d20251111_m055324_c001_v0001117_t0039_u01762840404920")
2025/11/11 05:53:48 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f4216311f44b0d8b4_d20251111_m055313_c001_v7007000_t0000_u01762840393188")
2025/11/11 05:53:49 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df555_d20251111_m055258_c001_v0001179_t0027_u01762840378770")
2025/11/11 05:53:49 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df55e_d20251111_m055258_c001_v0001179_t0035_u01762840378961")
2025/11/11 05:53:49 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df54f_d20251111_m055258_c001_v0001179_t0002_u01762840378293")
2025/11/11 05:53:49 DEBUG : subdir/file20.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df553_d20251111_m055258_c001_v0001179_t0026_u01762840378557")
2025/11/11 05:53:49 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f4162881cf43ecdf9_d20251111_m055336_c001_v7007000_t0000_u01762840416610")
2025/11/11 05:53:49 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df5d9_d20251111_m055325_c001_v0001179_t0005_u01762840405094")
2025/11/11 05:53:49 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f40108f13c71ae333_d20251111_m055311_c001_v7007000_t0000_u01762840391146")
2025/11/11 05:53:49 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec75b_d20251111_m055259_c001_v0001146_t0035_u01762840379014")
2025/11/11 05:53:49 DEBUG : subdir/subdirA/file30.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f106bac574e6dff53_d20251111_m055259_c001_v0001117_t0024_u01762840379030")
2025/11/11 05:53:49 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f109f32681a0ec759_d20251111_m055258_c001_v0001146_t0039_u01762840378583")
2025/11/11 05:53:49 DEBUG : subdir/subdirB/file30.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e470119_d20251111_m055258_c001_v0001100_t0047_u01762840378662")
2025/11/11 05:53:49 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e47011b_d20251111_m055259_c001_v0001100_t0001_u01762840379073")
2025/11/11 05:53:49 DEBUG : subdirX/file20.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df560_d20251111_m055259_c001_v0001179_t0056_u01762840379200")
2025/11/11 05:53:49 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f104d7569ef0df562_d20251111_m055259_c001_v0001179_t0048_u01762840379431")
2025/11/11 05:53:49 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "4_zcf026db3709d7b1b9fa80e1b_f117589359e47011d_d20251111_m055259_c001_v0001100_t0032_u01762840379522")
    bisync_test.go:608: TEST check_access_filters PASSED
2025/11/11 05:53:50 DEBUG : B2 bucket rclone-test-foyejos5kivi: Purge remote
2025/11/11 05:53:50 INFO  : B2 bucket rclone-test-foyejos5kivi: cleaning bucket "rclone-test-foyejos5kivi" of all files
--- PASS: TestBisyncLocalRemote (59.30s)
    --- PASS: TestBisyncLocalRemote/check_access_filters (57.56s)
=== RUN   TestBisyncRemoteRemote
2025/11/11 05:53:51 DEBUG : Creating backend with remote "TestB2:rclone-test-ninaroj9medu"
2025/11/11 05:53:51 NOTICE: remote: TestB2:rclone-test-ninaroj9medu
=== RUN   TestBisyncRemoteRemote/filtersfile_checks
2025/11/11 05:53:51 DEBUG : Creating backend with remote "TestB2:rclone-test-ninaroj9medu/055351sa"
2025/11/11 05:53:53 INFO  : path1: Making directory
2025/11/11 05:53:53 DEBUG : Creating backend with remote "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1"
2025/11/11 05:53:54 INFO  : path2: Making directory
2025/11/11 05:53:54 DEBUG : Creating backend with remote "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2"
2025/11/11 05:53:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/initial"
2025/11/11 05:53:55 DEBUG : Creating backend with remote "/tmp/055351sa/initdir/test_filtersfile_checks-haxemaf2"
2025/11/11 05:53:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : subdir: Making directory with metadata
2025/11/11 05:53:56 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/11 05:53:56 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/11 05:53:56 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/11 05:53:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:53:56 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/11 05:53:56 INFO  : file1.txt: Copied (new)
2025/11/11 05:53:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : Local file system at /tmp/055351sa/initdir/test_filtersfile_checks-haxemaf2: Waiting for checks to finish
2025/11/11 05:53:56 DEBUG : Local file system at /tmp/055351sa/initdir/test_filtersfile_checks-haxemaf2: Waiting for transfers to finish
2025/11/11 05:53:56 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/11 05:53:56 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:53:56 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/11 05:53:56 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/11 05:53:56 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/11 05:53:56 INFO  : file2.txt: Copied (new)
2025/11/11 05:53:56 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/11 05:53:56 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:53:56 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/11 05:53:56 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/11 05:53:56 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/11 05:53:56 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:53:56 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/11 05:53:56 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filtersfile_checks/modfiles"
2025/11/11 05:53:56 DEBUG : Creating backend with remote "/tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3"
2025/11/11 05:53:56 DEBUG : filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : filtersfile2.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:56 DEBUG : Local file system at /tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3: Waiting for checks to finish
2025/11/11 05:53:56 DEBUG : Local file system at /tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3: Waiting for transfers to finish
2025/11/11 05:53:56 DEBUG : hold.txt.83686dab.partial: size = 59 OK
2025/11/11 05:53:56 DEBUG : filtersfile.txt.5df4ccf0.partial: size = 380 OK
2025/11/11 05:53:56 DEBUG : filtersfile2.txt.dc377757.partial: size = 254 OK
2025/11/11 05:53:56 DEBUG : filtersfile2.txt: md5 = 95acc1e3b5beda465d9b2cb32808588c OK
2025/11/11 05:53:56 DEBUG : filtersfile.txt: md5 = cbf4389cb34d9e160689d1578eb5707f OK
2025/11/11 05:53:56 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/11/11 05:53:56 DEBUG : filtersfile2.txt.dc377757.partial: renamed to: filtersfile2.txt
2025/11/11 05:53:56 INFO  : filtersfile2.txt: Copied (new)
2025/11/11 05:53:56 DEBUG : filtersfile.txt.5df4ccf0.partial: renamed to: filtersfile.txt
2025/11/11 05:53:56 INFO  : filtersfile.txt: Copied (new)
2025/11/11 05:53:56 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2025/11/11 05:53:56 INFO  : hold.txt: Copied (new)
2025/11/11 05:53:56 INFO  : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: cleaning bucket "rclone-test-ninaroj9medu" of all files
2025/11/11 05:53:56 INFO  : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: cleaning bucket "rclone-test-ninaroj9medu" of all files
2025/11/11 05:53:57 NOTICE: checking initFs Local file system at /tmp/055351sa/initdir/test_filtersfile_checks-haxemaf2
2025/11/11 05:53:58 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:58 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:58 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:58 INFO  : subdir: Making directory
2025/11/11 05:53:58 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:58 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Waiting for checks to finish
2025/11/11 05:53:58 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Waiting for transfers to finish
2025/11/11 05:53:59 DEBUG : file1.txt: size = 0 OK
2025/11/11 05:53:59 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:53:59 INFO  : file1.txt: Copied (new)
2025/11/11 05:53:59 DEBUG : file2.txt: size = 0 OK
2025/11/11 05:53:59 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:53:59 INFO  : file2.txt: Copied (new)
2025/11/11 05:53:59 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/11 05:53:59 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:53:59 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:53:59 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/11 05:53:59 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:53:59 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:53:59 NOTICE: checking Path1 B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1
2025/11/11 05:53:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/11 05:53:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:59 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:59 INFO  : subdir: Making directory
2025/11/11 05:53:59 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/11 05:53:59 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Waiting for checks to finish
2025/11/11 05:53:59 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Waiting for transfers to finish
2025/11/11 05:54:00 DEBUG : file1.txt: size = 0 OK
2025/11/11 05:54:00 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:54:00 INFO  : file1.txt: Copied (new)
2025/11/11 05:54:00 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/11 05:54:00 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:54:00 INFO  : subdir/file20.txt: Copied (new)
2025/11/11 05:54:01 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/11 05:54:01 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/11 05:54:01 INFO  : RCLONE_TEST: Copied (new)
2025/11/11 05:54:22 DEBUG : file2.txt: size = 0 OK
2025/11/11 05:54:22 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/11 05:54:22 INFO  : file2.txt: Copied (new)
2025/11/11 05:54:22 NOTICE: checking path2 B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2
2025/11/11 05:54:23 NOTICE: (01)  : test filtersfile-checks
2025/11/11 05:54:23 NOTICE: (02)  : test initial bisync
2025/11/11 05:54:23 NOTICE: (03)  : bisync resync
2025/11/11 05:54:32 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:32 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:54:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:54:32 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:32 INFO  : Copying Path2 files to Path1
2025/11/11 05:54:32 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/11 05:54:32 INFO  : There was nothing to transfer
2025/11/11 05:54:32 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/11 05:54:32 INFO  : There was nothing to transfer
2025/11/11 05:54:32 INFO  : Resync updating listings
2025/11/11 05:54:32 INFO  : Validating listings for Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:32 INFO  : Bisync successful
2025/11/11 05:54:32 NOTICE: (04)  : test 1. inject filters file in workdir.
2025/11/11 05:54:32 NOTICE: (05)  : copy-file /tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile.txt /tmp/055351sa/workdir/
2025/11/11 05:54:32 DEBUG : copyFile "/tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile.txt" to "/tmp/055351sa/workdir/" as ""
2025/11/11 05:54:32 DEBUG : Creating backend with remote "/tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile.txt"
2025/11/11 05:54:32 DEBUG : Creating backend with remote "/tmp/055351sa/workdir/"
2025/11/11 05:54:32 DEBUG : operations.CopyFile "filtersfile.txt" to "Local file system at /tmp/055351sa/workdir" as "filtersfile.txt"
2025/11/11 05:54:32 DEBUG : filtersfile.txt: Need to transfer - File not found at Destination
2025/11/11 05:54:32 DEBUG : filtersfile.txt.5df4ccf0.partial: size = 380 OK
2025/11/11 05:54:32 DEBUG : filtersfile.txt: md5 = cbf4389cb34d9e160689d1578eb5707f OK
2025/11/11 05:54:32 DEBUG : filtersfile.txt.5df4ccf0.partial: renamed to: filtersfile.txt
2025/11/11 05:54:32 INFO  : filtersfile.txt: Copied (new)
2025/11/11 05:54:32 NOTICE: (06)  : test 2. run with filters-file but without md5. should abort.
2025/11/11 05:54:32 NOTICE: (07)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:54:40 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:40 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:40 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:54:40 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:54:40 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:40 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:54:40 ERROR : Bisync critical error: filters file md5 hash not found (must run --resync): /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:54:40 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:54:40 NOTICE: Bisync error: bisync aborted
2025/11/11 05:54:40 NOTICE: (08)  : test 3. run without filters-file. should be blocked due to prior abort.
2025/11/11 05:54:40 NOTICE: (09)  : bisync
2025/11/11 05:54:49 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:49 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:54:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:54:49 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:49 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run 
Tip: here are the filenames we were looking for. Do they exist? 
Path1: /tmp/055351sa/workdir/TestB2_rclone-test-ninaroj9medu_055351sa_filtersfile_checks_path1..TestB2_rclone-test-ninaroj9medu_055351sa_filtersfile_checks_path2.path1.lst
Path2: /tmp/055351sa/workdir/TestB2_rclone-test-ninaroj9medu_055351sa_filtersfile_checks_path1..TestB2_rclone-test-ninaroj9medu_055351sa_filtersfile_checks_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/055351sa/workdir"
2025/11/11 05:54:49 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:54:49 NOTICE: Bisync error: bisync aborted
2025/11/11 05:54:49 NOTICE: (10)  : test 4. run with filters-file and resync.
2025/11/11 05:54:49 NOTICE: (11)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt resync
2025/11/11 05:54:57 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:57 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:54:57 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:54:57 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:54:57 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:57 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:54:57 INFO  : Storing filters file hash to /tmp/055351sa/workdir/filtersfile.txt.md5
2025/11/11 05:54:57 INFO  : Copying Path2 files to Path1
2025/11/11 05:54:57 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/11 05:54:58 INFO  : There was nothing to transfer
2025/11/11 05:54:58 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/11 05:54:58 INFO  : There was nothing to transfer
2025/11/11 05:54:58 INFO  : Resync updating listings
2025/11/11 05:54:58 INFO  : Validating listings for Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:54:58 INFO  : Bisync successful
2025/11/11 05:54:58 NOTICE: (12)  : test 5. run with filters-file alone. should run.
2025/11/11 05:54:58 NOTICE: (13)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:06 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:06 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:55:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:55:06 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:55:06 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:06 INFO  : Building Path1 and Path2 listings
2025/11/11 05:55:07 INFO  : Path1 checking for diffs
2025/11/11 05:55:07 INFO  : Path2 checking for diffs
2025/11/11 05:55:07 INFO  : No changes found
2025/11/11 05:55:07 INFO  : Updating listings
2025/11/11 05:55:07 INFO  : Validating listings for Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:55:07 INFO  : Bisync successful
2025/11/11 05:55:07 NOTICE: (14)  : test 6. push changed filters-file to workdir.
2025/11/11 05:55:07 NOTICE: (15)  : copy-as /tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile2.txt /tmp/055351sa/workdir/ filtersfile.txt
2025/11/11 05:55:07 DEBUG : copyFile "/tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile2.txt" to "/tmp/055351sa/workdir/" as "filtersfile.txt"
2025/11/11 05:55:07 DEBUG : Creating backend with remote "/tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3/filtersfile2.txt"
2025/11/11 05:55:07 DEBUG : Creating backend with remote "/tmp/055351sa/workdir/"
2025/11/11 05:55:07 DEBUG : operations.CopyFile "filtersfile2.txt" to "Local file system at /tmp/055351sa/workdir" as "filtersfile.txt"
2025/11/11 05:55:07 DEBUG : filtersfile2.txt: size = 254 (Local file system at /tmp/055351sa/datadir/test_filtersfile_checks-yawosiw3)
2025/11/11 05:55:07 DEBUG : filtersfile.txt: size = 380 (Local file system at /tmp/055351sa/workdir)
2025/11/11 05:55:07 DEBUG : filtersfile2.txt: Sizes differ
2025/11/11 05:55:07 DEBUG : filtersfile.txt.33ddd493.partial: size = 254 OK
2025/11/11 05:55:07 DEBUG : filtersfile2.txt: md5 = 95acc1e3b5beda465d9b2cb32808588c OK
2025/11/11 05:55:07 DEBUG : filtersfile.txt.33ddd493.partial: renamed to: filtersfile.txt
2025/11/11 05:55:07 INFO  : filtersfile2.txt: Copied (replaced existing) to: filtersfile.txt
2025/11/11 05:55:07 NOTICE: (16)  : test 7. run with filters-file alone. should abort.
2025/11/11 05:55:07 NOTICE: (17)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:14 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:14 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:55:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:55:14 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:55:14 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:14 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:14 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:55:14 NOTICE: Bisync error: bisync aborted
2025/11/11 05:55:14 NOTICE: (18)  : test 8. run with filters-file and resync and dry-run. should do the dry-run but still cause next non-resync run to abort.
2025/11/11 05:55:14 NOTICE: (19)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt resync dry-run
2025/11/11 05:55:23 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:23 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:23 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:55:23 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:55:23 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:55:23 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:23 INFO  : Skipped storing filters file hash to /tmp/055351sa/workdir/filtersfile.txt.md5 as --dry-run is set
2025/11/11 05:55:23 INFO  : Copying Path2 files to Path1
2025/11/11 05:55:23 NOTICE: - Path2    Resync is copying files to         - Path1
2025/11/11 05:55:24 INFO  : There was nothing to transfer
2025/11/11 05:55:24 NOTICE: - Path1    Resync is copying files to         - Path2
2025/11/11 05:55:24 INFO  : There was nothing to transfer
2025/11/11 05:55:24 INFO  : Resync updating listings
2025/11/11 05:55:24 INFO  : Bisync successful
2025/11/11 05:55:24 NOTICE: (20)  : test 9. run with filters-file alone. should abort.
2025/11/11 05:55:24 NOTICE: (21)  : bisync filters-file=/tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:32 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:32 DEBUG : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/11 05:55:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/11 05:55:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/11 05:55:32 INFO  : Synching Path1 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-ninaroj9medu/055351sa/filtersfile_checks/path2/"
2025/11/11 05:55:32 INFO  : Using filters file /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:32 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/055351sa/workdir/filtersfile.txt
2025/11/11 05:55:32 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/11 05:55:32 NOTICE: Bisync error: bisync aborted
2025/11/11 05:55:32 INFO  : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path1: cleaning bucket "rclone-test-ninaroj9medu" of all files
2025/11/11 05:55:32 DEBUG : RCLONE_TEST: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f102f9c91820a554e_d20251111_m055359_c001_v0001183_t0050_u01762840439226")
2025/11/11 05:55:32 DEBUG : file1.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f11484f708179120d_d20251111_m055358_c001_v0001120_t0038_u01762840438667")
2025/11/11 05:55:32 DEBUG : file2.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1167d7f400c814d1_d20251111_m055358_c001_v0001099_t0006_u01762840438853")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f4216311f44b0dd80_d20251111_m055527_c001_v7007000_t0000_u01762840527895")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f103e6732e593f4a7_d20251111_m055526_c001_v0001180_t0019_u01762840526697")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f102f9c91820a58ac_d20251111_m055524_c001_v0001183_t0010_u01762840524903")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f4216311f44b0dd25_d20251111_m055519_c001_v7007000_t0000_u01762840519337")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f10736271ad50d888_d20251111_m055517_c001_v0001037_t0057_u01762840517973")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f110cc9110794127e_d20251111_m055515_c001_v0001131_t0006_u01762840515396")
2025/11/11 05:55:32 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f413f2cbeee85b516_d20251111_m055510_c001_v7007000_t0000_u01762840510889")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f102099d7f3f73620_d20251111_m055509_c001_v0001179_t0035_u01762840509689")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1167d7f400c81768_d20251111_m055507_c001_v0001099_t0021_u01762840507753")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f413f2cbeee85b49d_d20251111_m055502_c001_v7007000_t0000_u01762840502400")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f116b6e8d06d4b8b2_d20251111_m055500_c001_v0001150_t0037_u01762840500972")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f11484f7081791336_d20251111_m055459_c001_v0001120_t0058_u01762840499132")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f413f2cbeee85b442_d20251111_m055453_c001_v7007000_t0000_u01762840493359")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f110507d4e8db0af6_d20251111_m055451_c001_v0001146_t0037_u01762840491889")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f102f9c91820a5772_d20251111_m055449_c001_v0001183_t0016_u01762840489761")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f4199673b39d00c54_d20251111_m055445_c001_v7007000_t0000_u01762840485088")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f105c65ce1e668cb9_d20251111_m055443_c001_v0001172_t0012_u01762840483784")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f110cc9110794112c_d20251111_m055441_c001_v0001131_t0056_u01762840481453")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f415071e978d96a54_d20251111_m055436_c001_v7007000_t0000_u01762840476944")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f111f481017d6f73b_d20251111_m055435_c001_v0001171_t0049_u01762840475602")
2025/11/11 05:55:33 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1167d7f400c815f9_d20251111_m055433_c001_v0001099_t0038_u01762840473441")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f415071e978d96a2a_d20251111_m055427_c001_v7007000_t0000_u01762840467706")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f115f0aba43eac244_d20251111_m055425_c001_v0001038_t0038_u01762840465917")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f11484f7081791251_d20251111_m055423_c001_v0001120_t0025_u01762840463472")
2025/11/11 05:55:34 DEBUG : subdir/file20.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f110cc91107940f9c_d20251111_m055359_c001_v0001131_t0017_u01762840439048")
2025/11/11 05:55:34 INFO  : B2 bucket rclone-test-ninaroj9medu path 055351sa/filtersfile_checks/path2: cleaning bucket "rclone-test-ninaroj9medu" of all files
2025/11/11 05:55:34 DEBUG : RCLONE_TEST: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f10426c5b83403ca8_d20251111_m055400_c001_v0001183_t0040_u01762840440926")
2025/11/11 05:55:34 DEBUG : file1.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1062319ac2c44655_d20251111_m055400_c001_v0001146_t0036_u01762840440355")
2025/11/11 05:55:34 DEBUG : file2.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1106b2ae02118b5b_d20251111_m055420_c001_v0001116_t0016_u01762840440540")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f406602b1929192be_d20251111_m055532_c001_v7007000_t0000_u01762840532452")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f11272e9bd2a91ed3_d20251111_m055530_c001_v0001037_t0042_u01762840530978")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1106b2ae02118d0e_d20251111_m055528_c001_v0001116_t0022_u01762840528442")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f40108f13c71ae73a_d20251111_m055523_c001_v7007000_t0000_u01762840523618")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f11739e3296f372ec_d20251111_m055522_c001_v0001032_t0028_u01762840522135")
2025/11/11 05:55:34 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f10426c5b83403f56_d20251111_m055519_c001_v0001183_t0000_u01762840519896")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f4162881cf43ed0d2_d20251111_m055514_c001_v7007000_t0000_u01762840514854")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1110dff8af0d04c1_d20251111_m055513_c001_v0001182_t0039_u01762840513609")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f114a356f5b5c8097_d20251111_m055511_c001_v0001183_t0031_u01762840511445")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f417ea52e049d6401_d20251111_m055506_c001_v7007000_t0000_u01762840506830")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f108da0ab0c183972_d20251111_m055505_c001_v0001177_t0043_u01762840505431")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1062319ac2c447a4_d20251111_m055502_c001_v0001146_t0026_u01762840502973")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f418b07dcb4e39dbd_d20251111_m055457_c001_v7007000_t0000_u01762840497865")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1013f1beb36d2aaa_d20251111_m055456_c001_v0001092_t0029_u01762840496537")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1106b2ae02118c3f_d20251111_m055453_c001_v0001116_t0051_u01762840493903")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f41587366532ef9af_d20251111_m055449_c001_v7007000_t0000_u01762840489209")
2025/11/11 05:55:35 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1164d6d2d6b63d19_d20251111_m055447_c001_v0001090_t0041_u01762840487784")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f10426c5b83403e26_d20251111_m055445_c001_v0001183_t0006_u01762840485648")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f413f2cbeee85b3ae_d20251111_m055440_c001_v7007000_t0000_u01762840480885")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f102099d7f3f73520_d20251111_m055439_c001_v0001179_t0010_u01762840479623")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f114a356f5b5c7ee1_d20251111_m055437_c001_v0001183_t0028_u01762840477482")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f4120efd2da28f2ad_d20251111_m055432_c001_v7007000_t0000_u01762840472124")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f110df11d73ab573b_d20251111_m055430_c001_v0001144_t0029_u01762840470619")
2025/11/11 05:55:36 DEBUG : modtime_write_test: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f1062319ac2c446d4_d20251111_m055428_c001_v0001146_t0057_u01762840468244")
2025/11/11 05:55:36 DEBUG : subdir/file20.txt: Deleting (id "4_z6f525d3370ad7b1b9fa80e1b_f114a356f5b5c7d5b_d20251111_m055400_c001_v0001183_t0007_u01762840440734")
    bisync_test.go:608: TEST filtersfile_checks PASSED
2025/11/11 05:55:36 DEBUG : B2 bucket rclone-test-ninaroj9medu: Purge remote
2025/11/11 05:55:36 INFO  : B2 bucket rclone-test-ninaroj9medu: cleaning bucket "rclone-test-ninaroj9medu" of all files
--- PASS: TestBisyncRemoteRemote (106.72s)
    --- PASS: TestBisyncRemoteRemote/filtersfile_checks (104.88s)
PASS
2025/11/11 05:55:38 DEBUG : B2 bucket rclone-test-riyoqev1tega: Purge remote
2025/11/11 05:55:38 INFO  : B2 bucket rclone-test-riyoqev1tega: cleaning bucket "rclone-test-riyoqev1tega" of all files
2025/11/11 05:55:38 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteRemote$/^filtersfile_checks$'" - Finished OK in 2m47.438076329s (try 3/5)