"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^check_access_filters$'" - Starting (try 4/5) 2026/03/05 05:10:29 DEBUG : Creating backend with remote "TestB2:rclone-test-judocob5biyi" 2026/03/05 05:10:29 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/03/05 05:10:29 DEBUG : Creating backend with remote "/tmp/rclone936053638" === RUN TestBisyncRemoteRemote 2026/03/05 05:10:29 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize" 2026/03/05 05:10:30 NOTICE: remote: TestB2:rclone-test-fubimez8wize === RUN TestBisyncRemoteRemote/check_access_filters 2026/03/05 05:10:30 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na" 2026/03/05 05:10:31 INFO : path1: Making directory 2026/03/05 05:10:32 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1" 2026/03/05 05:10:33 INFO : path2: Making directory 2026/03/05 05:10:33 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2" 2026/03/05 05:10:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial" 2026/03/05 05:10:34 DEBUG : Creating backend with remote "/tmp/051030na/initdir/test_check_access_filters-koyetaq9" 2026/03/05 05:10:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir-not: Making directory with metadata 2026/03/05 05:10:34 INFO : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not 2026/03/05 05:10:34 DEBUG : subdir: Making directory with metadata 2026/03/05 05:10:34 DEBUG : file1.txt.56d0d699.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdir", newDst=subdir 2026/03/05 05:10:34 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2026/03/05 05:10:34 DEBUG : subdirX: Making directory with metadata 2026/03/05 05:10:34 INFO : file1.txt: Copied (new) 2026/03/05 05:10:34 INFO : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdirX", newDst=subdirX 2026/03/05 05:10:34 DEBUG : file3.txt.a74079f2.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2026/03/05 05:10:34 INFO : file3.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file2.txt.3230ad67.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1: Making directory with metadata 2026/03/05 05:10:34 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2: Making directory with metadata 2026/03/05 05:10:34 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2026/03/05 05:10:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 INFO : file2.txt: Copied (new) 2026/03/05 05:10:34 INFO : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1 2026/03/05 05:10:34 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2026/03/05 05:10:34 INFO : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 INFO : RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA: Making directory with metadata 2026/03/05 05:10:34 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2026/03/05 05:10:34 INFO : file4.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt 2026/03/05 05:10:34 INFO : subdirX/file20.txt: Copied (new) 2026/03/05 05:10:34 INFO : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA 2026/03/05 05:10:34 DEBUG : subdir/subdirB: Making directory with metadata 2026/03/05 05:10:34 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt 2026/03/05 05:10:34 INFO : subdir-not/file20.txt: Copied (new) 2026/03/05 05:10:34 INFO : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2026/03/05 05:10:34 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB 2026/03/05 05:10:34 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST 2026/03/05 05:10:34 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST 2026/03/05 05:10:34 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt 2026/03/05 05:10:34 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : Local file system at /tmp/051030na/initdir/test_check_access_filters-koyetaq9: Waiting for checks to finish 2026/03/05 05:10:34 DEBUG : Local file system at /tmp/051030na/initdir/test_check_access_filters-koyetaq9: Waiting for transfers to finish 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt 2026/03/05 05:10:34 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2026/03/05 05:10:34 INFO : subdir/file20.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2026/03/05 05:10:34 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 INFO : subdir/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK 2026/03/05 05:10:34 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST 2026/03/05 05:10:34 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt 2026/03/05 05:10:34 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST 2026/03/05 05:10:34 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2026/03/05 05:10:34 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST 2026/03/05 05:10:34 INFO : subdir/subdirA/RCLONE_TEST: Copied (new) 2026/03/05 05:10:34 INFO : subdir/subdirB: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdir-not/subdir-not2: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdir/subdirA: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdirX/subdirX1: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdirX: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdir-not: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 INFO : subdir: Set directory modification time (using SetModTime) 2026/03/05 05:10:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles" 2026/03/05 05:10:34 DEBUG : Creating backend with remote "/tmp/051030na/datadir/test_check_access_filters-cicemir7" 2026/03/05 05:10:34 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:34 DEBUG : Local file system at /tmp/051030na/datadir/test_check_access_filters-cicemir7: Waiting for checks to finish 2026/03/05 05:10:34 DEBUG : Local file system at /tmp/051030na/datadir/test_check_access_filters-cicemir7: Waiting for transfers to finish 2026/03/05 05:10:34 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK 2026/03/05 05:10:34 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK 2026/03/05 05:10:34 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt 2026/03/05 05:10:34 INFO : include-other-filtersfile.txt: Copied (new) 2026/03/05 05:10:34 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK 2026/03/05 05:10:34 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK 2026/03/05 05:10:34 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt 2026/03/05 05:10:34 INFO : exclude-other-filtersfile.txt: Copied (new) 2026/03/05 05:10:34 INFO : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: cleaning bucket "rclone-test-fubimez8wize" of all files 2026/03/05 05:10:35 INFO : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: cleaning bucket "rclone-test-fubimez8wize" of all files 2026/03/05 05:10:36 NOTICE: checking initFs Local file system at /tmp/051030na/initdir/test_check_access_filters-koyetaq9 2026/03/05 05:10:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 INFO : subdir-not: Making directory 2026/03/05 05:10:36 INFO : subdir: Making directory 2026/03/05 05:10:36 INFO : subdirX: Making directory 2026/03/05 05:10:36 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 INFO : subdir-not/subdir-not2: Making directory 2026/03/05 05:10:36 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 INFO : subdir/subdirA: Making directory 2026/03/05 05:10:36 INFO : subdirX/subdirX1: Making directory 2026/03/05 05:10:36 INFO : subdir/subdirB: Making directory 2026/03/05 05:10:36 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:36 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Waiting for checks to finish 2026/03/05 05:10:36 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Waiting for transfers to finish 2026/03/05 05:10:37 DEBUG : file2.txt: size = 0 OK 2026/03/05 05:10:37 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:37 INFO : file2.txt: Copied (new) 2026/03/05 05:10:37 DEBUG : file1.txt: size = 0 OK 2026/03/05 05:10:37 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:37 INFO : file1.txt: Copied (new) 2026/03/05 05:10:37 DEBUG : RCLONE_TEST: size = 109 OK 2026/03/05 05:10:37 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:37 INFO : RCLONE_TEST: Copied (new) 2026/03/05 05:10:37 DEBUG : file4.txt: size = 0 OK 2026/03/05 05:10:37 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:37 INFO : file4.txt: Copied (new) 2026/03/05 05:10:37 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:37 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:37 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/03/05 05:10:37 DEBUG : file3.txt: size = 0 OK 2026/03/05 05:10:37 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:37 INFO : file3.txt: Copied (new) 2026/03/05 05:10:38 DEBUG : subdir-not/file20.txt: size = 0 OK 2026/03/05 05:10:38 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:38 INFO : subdir-not/file20.txt: Copied (new) 2026/03/05 05:10:38 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:38 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:38 INFO : subdir/RCLONE_TEST: Copied (new) 2026/03/05 05:10:38 DEBUG : subdirX/file20.txt: size = 0 OK 2026/03/05 05:10:38 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:38 INFO : subdirX/file20.txt: Copied (new) 2026/03/05 05:10:38 DEBUG : subdirX/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:38 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:38 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/03/05 05:10:38 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK 2026/03/05 05:10:38 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:38 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/03/05 05:10:38 DEBUG : subdir/file20.txt: size = 0 OK 2026/03/05 05:10:38 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:38 INFO : subdir/file20.txt: Copied (new) 2026/03/05 05:10:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:38 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/03/05 05:10:38 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:38 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:38 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/03/05 05:10:38 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK 2026/03/05 05:10:38 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:38 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/03/05 05:10:39 DEBUG : subdir/subdirA/file30.txt: size = 0 OK 2026/03/05 05:10:39 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:39 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/03/05 05:10:39 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:39 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:39 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/03/05 05:10:39 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:39 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:39 INFO : subdir/subdirA/RCLONE_TEST: Copied (new) 2026/03/05 05:10:39 DEBUG : subdir/subdirB/file30.txt: size = 0 OK 2026/03/05 05:10:39 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:39 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/03/05 05:10:39 NOTICE: checking Path1 B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1 2026/03/05 05:10:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : file1.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : file2.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : file3.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : file4.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 INFO : subdir-not: Making directory 2026/03/05 05:10:39 INFO : subdir: Making directory 2026/03/05 05:10:39 INFO : subdirX: Making directory 2026/03/05 05:10:39 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 INFO : subdirX/subdirX1: Making directory 2026/03/05 05:10:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 INFO : subdir-not/subdir-not2: Making directory 2026/03/05 05:10:39 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 INFO : subdir/subdirA: Making directory 2026/03/05 05:10:39 INFO : subdir/subdirB: Making directory 2026/03/05 05:10:39 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:39 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Waiting for checks to finish 2026/03/05 05:10:39 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Waiting for transfers to finish 2026/03/05 05:10:40 DEBUG : file1.txt: size = 0 OK 2026/03/05 05:10:40 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:40 INFO : file1.txt: Copied (new) 2026/03/05 05:10:40 DEBUG : file2.txt: size = 0 OK 2026/03/05 05:10:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:40 INFO : file2.txt: Copied (new) 2026/03/05 05:10:40 DEBUG : file3.txt: size = 0 OK 2026/03/05 05:10:40 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:40 INFO : file3.txt: Copied (new) 2026/03/05 05:10:41 DEBUG : RCLONE_TEST: size = 109 OK 2026/03/05 05:10:41 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:41 INFO : RCLONE_TEST: Copied (new) 2026/03/05 05:10:41 DEBUG : file4.txt: size = 0 OK 2026/03/05 05:10:41 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:41 INFO : file4.txt: Copied (new) 2026/03/05 05:10:41 DEBUG : subdirX/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:41 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:41 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/03/05 05:10:41 DEBUG : subdirX/file20.txt: size = 0 OK 2026/03/05 05:10:41 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:41 INFO : subdirX/file20.txt: Copied (new) 2026/03/05 05:10:41 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:41 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:41 INFO : subdirX/subdirX1/RCLONE_TEST: Copied (new) 2026/03/05 05:10:41 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:41 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:41 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/03/05 05:10:41 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK 2026/03/05 05:10:41 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:41 INFO : subdirX/subdirX1/file30.txt: Copied (new) 2026/03/05 05:10:41 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:41 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:41 INFO : subdir/RCLONE_TEST: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir-not/file20.txt: size = 0 OK 2026/03/05 05:10:42 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:42 INFO : subdir-not/file20.txt: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir/file20.txt: size = 0 OK 2026/03/05 05:10:42 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:42 INFO : subdir/file20.txt: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:42 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:42 INFO : subdir/subdirA/RCLONE_TEST: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:42 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:42 INFO : subdir/subdirB/RCLONE_TEST: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK 2026/03/05 05:10:42 DEBUG : subdir/subdirA/file30.txt: size = 0 OK 2026/03/05 05:10:42 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:10:42 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:42 INFO : subdir/subdirA/file30.txt: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir/subdirB/file30.txt: size = 0 OK 2026/03/05 05:10:42 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:42 INFO : subdir/subdirB/file30.txt: Copied (new) 2026/03/05 05:10:42 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK 2026/03/05 05:10:42 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2026/03/05 05:10:42 INFO : subdir-not/subdir-not2/file30.txt: Copied (new) 2026/03/05 05:10:42 NOTICE: checking path2 B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2 2026/03/05 05:10:43 NOTICE: (01) : test check-access-filters 2026/03/05 05:10:43 NOTICE: (02) : test EXCLUDE - OTHER TESTS 2026/03/05 05:10:43 NOTICE: (03) : copy-file /tmp/051030na/datadir/test_check_access_filters-cicemir7/exclude-other-filtersfile.txt /tmp/051030na/workdir/ 2026/03/05 05:10:43 DEBUG : copyFile "/tmp/051030na/datadir/test_check_access_filters-cicemir7/exclude-other-filtersfile.txt" to "/tmp/051030na/workdir/" as "" 2026/03/05 05:10:43 DEBUG : Creating backend with remote "/tmp/051030na/datadir/test_check_access_filters-cicemir7/exclude-other-filtersfile.txt" 2026/03/05 05:10:43 DEBUG : Creating backend with remote "/tmp/051030na/workdir/" 2026/03/05 05:10:43 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/051030na/workdir" as "exclude-other-filtersfile.txt" 2026/03/05 05:10:43 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/03/05 05:10:43 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK 2026/03/05 05:10:43 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK 2026/03/05 05:10:43 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt 2026/03/05 05:10:43 INFO : exclude-other-filtersfile.txt: Copied (new) 2026/03/05 05:10:43 NOTICE: (04) : test resync to get the filters file md5 built. 2026/03/05 05:10:43 NOTICE: (05) : bisync resync filters-file=/tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:10:51 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:10:51 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:10:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:10:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:10:51 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:10:51 INFO : Using filters file /tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:10:51 INFO : Storing filters file hash to /tmp/051030na/workdir/exclude-other-filtersfile.txt.md5 2026/03/05 05:10:51 INFO : Copying Path2 files to Path1 2026/03/05 05:10:51 INFO : - Path2 Resync is copying files to - Path1 2026/03/05 05:10:51 INFO : There was nothing to transfer 2026/03/05 05:10:51 INFO : - Path1 Resync is copying files to - Path2 2026/03/05 05:10:52 INFO : There was nothing to transfer 2026/03/05 05:10:52 INFO : Resync updating listings 2026/03/05 05:10:52 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:10:52 INFO : Bisync successful 2026/03/05 05:10:52 NOTICE: (06) : test EXCLUDE - test filters for check access 2026/03/05 05:10:52 NOTICE: (07) : bisync check-access filters-file=/tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:00 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:00 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:00 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:11:00 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:11:00 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:00 INFO : Using filters file /tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:00 INFO : Building Path1 and Path2 listings 2026/03/05 05:11:01 INFO : Path1 checking for diffs 2026/03/05 05:11:01 INFO : Path2 checking for diffs 2026/03/05 05:11:01 INFO : Checking access health 2026/03/05 05:11:01 INFO : Found 3 matching "RCLONE_TEST" files on both paths 2026/03/05 05:11:01 INFO : No changes found 2026/03/05 05:11:01 INFO : Updating listings 2026/03/05 05:11:01 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:01 INFO : Bisync successful 2026/03/05 05:11:01 NOTICE: (08) : copy-listings exclude-initial 2026/03/05 05:11:01 NOTICE: (09) : test EXCLUDE - delete RCLONE_TEST files in excluded directories 2026/03/05 05:11:01 NOTICE: (10) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA/RCLONE_TEST 2026/03/05 05:11:01 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA/" 2026/03/05 05:11:02 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA" 2026/03/05 05:11:03 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:03 NOTICE: (11) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not/RCLONE_TEST 2026/03/05 05:11:03 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not/" 2026/03/05 05:11:04 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not" 2026/03/05 05:11:05 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:05 NOTICE: (12) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST 2026/03/05 05:11:05 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2/" 2026/03/05 05:11:06 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2" 2026/03/05 05:11:07 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:07 NOTICE: (13) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/RCLONE_TEST 2026/03/05 05:11:07 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/" 2026/03/05 05:11:08 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX" 2026/03/05 05:11:09 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:09 NOTICE: (14) : test EXCLUDE - test should PASS 2026/03/05 05:11:09 NOTICE: (15) : bisync check-access filters-file=/tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:18 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:18 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:18 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:11:18 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:11:18 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:18 INFO : Using filters file /tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:18 INFO : Building Path1 and Path2 listings 2026/03/05 05:11:19 INFO : Path1 checking for diffs 2026/03/05 05:11:19 INFO : Path2 checking for diffs 2026/03/05 05:11:19 INFO : Checking access health 2026/03/05 05:11:19 INFO : Found 3 matching "RCLONE_TEST" files on both paths 2026/03/05 05:11:19 INFO : No changes found 2026/03/05 05:11:19 INFO : Updating listings 2026/03/05 05:11:19 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:19 INFO : Bisync successful 2026/03/05 05:11:19 NOTICE: (16) : copy-listings exclude-pass-run 2026/03/05 05:11:19 NOTICE: (17) : test EXCLUDE - delete RCLONE_TEST files in included directories 2026/03/05 05:11:19 NOTICE: (18) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/RCLONE_TEST 2026/03/05 05:11:19 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:20 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2" 2026/03/05 05:11:20 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:20 NOTICE: (19) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir/RCLONE_TEST 2026/03/05 05:11:20 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir/" 2026/03/05 05:11:22 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir" 2026/03/05 05:11:22 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:22 NOTICE: (20) : test EXCLUDE - test should ABORT 2026/03/05 05:11:22 NOTICE: (21) : bisync check-access filters-file=/tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:31 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:31 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:31 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:11:31 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:11:31 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:31 INFO : Using filters file /tmp/051030na/workdir/exclude-other-filtersfile.txt 2026/03/05 05:11:31 INFO : Building Path1 and Path2 listings 2026/03/05 05:11:32 INFO : Path1 checking for diffs 2026/03/05 05:11:32 INFO : - Path1 File was deleted - subdir/RCLONE_TEST 2026/03/05 05:11:32 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2026/03/05 05:11:32 INFO : Path2 checking for diffs 2026/03/05 05:11:32 INFO : - Path2 File was deleted - RCLONE_TEST 2026/03/05 05:11:32 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2026/03/05 05:11:32 INFO : Checking access health 2026/03/05 05:11:32 ERROR : -  Access test failed: Path1 file not found in Path2 - RCLONE_TEST 2026/03/05 05:11:32 ERROR : -  Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST 2026/03/05 05:11:32 ERROR : Bisync critical error: check file check failed 2026/03/05 05:11:32 ERROR : Bisync aborted. Must run --resync to recover. 2026/03/05 05:11:32 NOTICE: Bisync error: bisync aborted 2026/03/05 05:11:32 NOTICE: (22) : move-listings exclude-error-run 2026/03/05 05:11:32 NOTICE: (23) : test INCLUDE - OTHER TESTS 2026/03/05 05:11:32 NOTICE: (24) : test reset to the initial state 2026/03/05 05:11:32 NOTICE: (25) : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/ 2026/03/05 05:11:32 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" 2026/03/05 05:11:33 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1" 2026/03/05 05:11:33 DEBUG : RCLONE_TEST: size = 109 OK 2026/03/05 05:11:33 DEBUG : file3.txt: size = 0 OK 2026/03/05 05:11:33 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : file2.txt: size = 0 OK 2026/03/05 05:11:33 DEBUG : file3.txt: Unchanged skipping 2026/03/05 05:11:33 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:33 DEBUG : file1.txt: size = 0 OK 2026/03/05 05:11:33 DEBUG : file2.txt: Unchanged skipping 2026/03/05 05:11:33 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : file4.txt: size = 0 OK 2026/03/05 05:11:33 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : file1.txt: Unchanged skipping 2026/03/05 05:11:33 DEBUG : file4.txt: Unchanged skipping 2026/03/05 05:11:33 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:33 DEBUG : subdirX/file20.txt: size = 0 OK 2026/03/05 05:11:33 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:33 DEBUG : subdirX/file20.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:34 DEBUG : subdir/file20.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir/file20.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:34 DEBUG : subdir-not/file20.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir-not/file20.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir/subdirB/file30.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir/subdirA/file30.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK 2026/03/05 05:11:34 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Waiting for checks to finish 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:34 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:34 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Waiting for transfers to finish 2026/03/05 05:11:34 DEBUG : subdirX/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:34 INFO : subdirX/RCLONE_TEST: Copied (new) 2026/03/05 05:11:34 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:34 INFO : subdir/RCLONE_TEST: Copied (new) 2026/03/05 05:11:34 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:34 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:34 INFO : subdir-not/RCLONE_TEST: Copied (new) 2026/03/05 05:11:34 NOTICE: (26) : sync-dir TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/ TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/ 2026/03/05 05:11:34 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1" 2026/03/05 05:11:34 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2" 2026/03/05 05:11:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:35 DEBUG : file1.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : file1.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : file3.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : file2.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : file3.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : file2.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : file4.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : file4.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdirX/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdirX/file20.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdirX/file20.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir/file20.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir/file20.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir-not/file20.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir-not/file20.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir/subdirB/file30.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : RCLONE_TEST: size = 109 OK 2026/03/05 05:11:35 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:35 INFO : RCLONE_TEST: Copied (server-side copy) 2026/03/05 05:11:35 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:35 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination 2026/03/05 05:11:35 DEBUG : subdir/subdirA/file30.txt: size = 0 OK 2026/03/05 05:11:35 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Waiting for checks to finish 2026/03/05 05:11:35 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms) 2026/03/05 05:11:35 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping 2026/03/05 05:11:35 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Waiting for transfers to finish 2026/03/05 05:11:36 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:36 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:36 INFO : subdir/subdirA/RCLONE_TEST: Copied (server-side copy) 2026/03/05 05:11:36 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK 2026/03/05 05:11:36 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2026/03/05 05:11:36 INFO : subdir-not/subdir-not2/RCLONE_TEST: Copied (server-side copy) 2026/03/05 05:11:36 DEBUG : Waiting for deletions to finish 2026/03/05 05:11:36 NOTICE: (27) : copy-file /tmp/051030na/datadir/test_check_access_filters-cicemir7/include-other-filtersfile.txt /tmp/051030na/workdir/ 2026/03/05 05:11:36 DEBUG : copyFile "/tmp/051030na/datadir/test_check_access_filters-cicemir7/include-other-filtersfile.txt" to "/tmp/051030na/workdir/" as "" 2026/03/05 05:11:36 DEBUG : Creating backend with remote "/tmp/051030na/datadir/test_check_access_filters-cicemir7/include-other-filtersfile.txt" 2026/03/05 05:11:36 DEBUG : Creating backend with remote "/tmp/051030na/workdir/" 2026/03/05 05:11:36 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/051030na/workdir" as "include-other-filtersfile.txt" 2026/03/05 05:11:36 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination 2026/03/05 05:11:36 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK 2026/03/05 05:11:36 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK 2026/03/05 05:11:36 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt 2026/03/05 05:11:36 INFO : include-other-filtersfile.txt: Copied (new) 2026/03/05 05:11:36 NOTICE: (28) : bisync resync filters-file=/tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:11:45 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:45 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:45 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:11:45 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:11:45 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:45 INFO : Using filters file /tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:11:45 INFO : Storing filters file hash to /tmp/051030na/workdir/include-other-filtersfile.txt.md5 2026/03/05 05:11:45 INFO : Copying Path2 files to Path1 2026/03/05 05:11:45 INFO : - Path2 Resync is copying files to - Path1 2026/03/05 05:11:46 INFO : There was nothing to transfer 2026/03/05 05:11:46 INFO : - Path1 Resync is copying files to - Path2 2026/03/05 05:11:47 INFO : There was nothing to transfer 2026/03/05 05:11:47 INFO : Resync updating listings 2026/03/05 05:11:47 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:47 INFO : Bisync successful 2026/03/05 05:11:47 NOTICE: (29) : test INCLUDE - test include/exclude filters for check access 2026/03/05 05:11:47 NOTICE: (30) : bisync check-access filters-file=/tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:11:55 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:55 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:11:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:11:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:11:55 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:55 INFO : Using filters file /tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:11:55 INFO : Building Path1 and Path2 listings 2026/03/05 05:11:55 INFO : Path1 checking for diffs 2026/03/05 05:11:55 INFO : Path2 checking for diffs 2026/03/05 05:11:55 INFO : Checking access health 2026/03/05 05:11:55 INFO : Found 5 matching "RCLONE_TEST" files on both paths 2026/03/05 05:11:55 INFO : No changes found 2026/03/05 05:11:55 INFO : Updating listings 2026/03/05 05:11:55 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:11:55 INFO : Bisync successful 2026/03/05 05:11:55 NOTICE: (31) : copy-listings include-initial 2026/03/05 05:11:55 NOTICE: (32) : test INCLUDE - delete RCLONE_TEST files in excluded directories 2026/03/05 05:11:55 NOTICE: (33) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA/RCLONE_TEST 2026/03/05 05:11:55 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir/subdirA" 2026/03/05 05:11:56 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:56 NOTICE: (34) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not/RCLONE_TEST 2026/03/05 05:11:56 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir-not" 2026/03/05 05:11:58 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:58 NOTICE: (35) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST 2026/03/05 05:11:58 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/subdir-not/subdir-not2" 2026/03/05 05:11:59 INFO : RCLONE_TEST: Deleted 2026/03/05 05:11:59 NOTICE: (36) : test INCLUDE - test should PASS 2026/03/05 05:11:59 NOTICE: (37) : bisync check-access filters-file=/tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:12:07 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:12:07 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:12:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:12:07 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:12:07 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:12:07 INFO : Using filters file /tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:12:07 INFO : Building Path1 and Path2 listings 2026/03/05 05:12:07 INFO : Path1 checking for diffs 2026/03/05 05:12:07 INFO : Path2 checking for diffs 2026/03/05 05:12:07 INFO : Checking access health 2026/03/05 05:12:07 INFO : Found 5 matching "RCLONE_TEST" files on both paths 2026/03/05 05:12:07 INFO : No changes found 2026/03/05 05:12:07 INFO : Updating listings 2026/03/05 05:12:07 INFO : Validating listings for Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:12:07 INFO : Bisync successful 2026/03/05 05:12:07 NOTICE: (38) : copy-listings include-pass-run 2026/03/05 05:12:07 NOTICE: (39) : test INCLUDE - delete RCLONE_TEST files in included directories 2026/03/05 05:12:07 NOTICE: (40) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/RCLONE_TEST 2026/03/05 05:12:07 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2" 2026/03/05 05:12:08 INFO : RCLONE_TEST: Deleted 2026/03/05 05:12:08 NOTICE: (41) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir/RCLONE_TEST 2026/03/05 05:12:08 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir/" for canonical name "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdir" 2026/03/05 05:12:09 INFO : RCLONE_TEST: Deleted 2026/03/05 05:12:09 NOTICE: (42) : delete-file TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/subdirX1/RCLONE_TEST 2026/03/05 05:12:09 DEBUG : Creating backend with remote "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/subdirX1/" 2026/03/05 05:12:10 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/subdirX1/" to be canonical "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/subdirX/subdirX1" 2026/03/05 05:12:11 INFO : RCLONE_TEST: Deleted 2026/03/05 05:12:11 NOTICE: (43) : test INCLUDE - test should ABORT 2026/03/05 05:12:11 NOTICE: (44) : bisync check-access filters-file=/tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:12:19 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:12:19 DEBUG : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2026/03/05 05:12:19 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2026/03/05 05:12:19 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2026/03/05 05:12:19 INFO : Synching Path1 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path1/" with Path2 "TestB2:rclone-test-fubimez8wize/051030na/check_access_filters/path2/" 2026/03/05 05:12:19 INFO : Using filters file /tmp/051030na/workdir/include-other-filtersfile.txt 2026/03/05 05:12:19 INFO : Building Path1 and Path2 listings 2026/03/05 05:12:20 INFO : Path1 checking for diffs 2026/03/05 05:12:20 INFO : - Path1 File was deleted - subdir/RCLONE_TEST 2026/03/05 05:12:20 INFO : - Path1 File was deleted - subdirX/subdirX1/RCLONE_TEST 2026/03/05 05:12:20 INFO : Path1: 2 changes:  0 new,  0 modified,  2 deleted 2026/03/05 05:12:20 INFO : Path2 checking for diffs 2026/03/05 05:12:20 INFO : - Path2 File was deleted - RCLONE_TEST 2026/03/05 05:12:20 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2026/03/05 05:12:20 INFO : Checking access health 2026/03/05 05:12:20 ERROR : Access test failed: Path1 count 3, Path2 count 4 - RCLONE_TEST 2026/03/05 05:12:20 ERROR : -  Access test failed: Path1 file not found in Path2 - RCLONE_TEST 2026/03/05 05:12:20 ERROR : -  Access test failed: Path2 file not found in Path1 - subdirX/subdirX1/RCLONE_TEST 2026/03/05 05:12:20 ERROR : -  Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST 2026/03/05 05:12:20 ERROR : Bisync critical error: check file check failed 2026/03/05 05:12:20 ERROR : Bisync aborted. Must run --resync to recover. 2026/03/05 05:12:20 NOTICE: Bisync error: bisync aborted 2026/03/05 05:12:20 NOTICE: (45) : move-listings include-error-run 2026/03/05 05:12:20 INFO : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path1: cleaning bucket "rclone-test-fubimez8wize" of all files 2026/03/05 05:12:21 DEBUG : RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf176_d20260305_m051037_c001_v0001165_t0008_u01772687437432") 2026/03/05 05:12:21 DEBUG : file1.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba5ffc_d20260305_m051037_c001_v0001145_t0004_u01772687437232") 2026/03/05 05:12:21 DEBUG : file2.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c443502_d20260305_m051037_c001_v0001102_t0004_u01772687437073") 2026/03/05 05:12:21 DEBUG : file3.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118ef20b9a072ee7_d20260305_m051037_c001_v0001173_t0004_u01772687437599") 2026/03/05 05:12:21 DEBUG : file4.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c443506_d20260305_m051037_c001_v0001102_t0047_u01772687437386") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4165d8eb65a52cf5_d20260305_m051215_c001_v7007000_t0000_u01772687535495") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f100302dd43526c8a_d20260305_m051214_c001_v0001093_t0057_u01772687534055") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6109_d20260305_m051211_c001_v0001145_t0006_u01772687531786") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f418e544ddd401902_d20260305_m051202_c001_v7007000_t0000_u01772687522904") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f103196cd615e869c_d20260305_m051201_c001_v0001105_t0006_u01772687521619") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c4435c7_d20260305_m051159_c001_v0001102_t0046_u01772687519553") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f421c477ed68b60c3_d20260305_m051150_c001_v7007000_t0000_u01772687510893") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f114b44fd1d3694bb_d20260305_m051149_c001_v0001153_t0013_u01772687509502") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf278_d20260305_m051147_c001_v0001165_t0043_u01772687507720") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f421c477ed68b6084_d20260305_m051141_c001_v7007000_t0000_u01772687501571") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f112e034ab763dc75_d20260305_m051140_c001_v0001042_t0041_u01772687500149") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118ef20b9a073216_d20260305_m051137_c001_v0001173_t0035_u01772687497800") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f421c477ed68b602f_d20260305_m051127_c001_v7007000_t0000_u01772687487269") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1099162cffbddc7c_d20260305_m051125_c001_v0001185_t0055_u01772687485968") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118ef20b9a073168_d20260305_m051123_c001_v0001173_t0046_u01772687483438") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f414cdcdb89f7307a_d20260305_m051113_c001_v7007000_t0000_u01772687473489") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f104d0dcfb0a30383_d20260305_m051111_c001_v0001044_t0055_u01772687471997") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6060_d20260305_m051109_c001_v0001145_t0033_u01772687469684") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f409b52a3bb01158a_d20260305_m051056_c001_v7007000_t0000_u01772687456200") 2026/03/05 05:12:21 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f100f31400acf26c6_d20260305_m051054_c001_v0001144_t0033_u01772687454611") 2026/03/05 05:12:22 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c443532_d20260305_m051052_c001_v0001102_t0002_u01772687452744") 2026/03/05 05:12:22 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f409b52a3bb01157d_d20260305_m051047_c001_v7007000_t0000_u01772687447050") 2026/03/05 05:12:22 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1164f3c5d5e1f902_d20260305_m051045_c001_v0001098_t0050_u01772687445589") 2026/03/05 05:12:22 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf194_d20260305_m051043_c001_v0001165_t0046_u01772687443115") 2026/03/05 05:12:22 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f422639afb75ce1f3_d20260305_m051157_c001_v7007000_t0000_u01772687517920") 2026/03/05 05:12:22 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6098_d20260305_m051134_c001_v0001145_t0026_u01772687494688") 2026/03/05 05:12:22 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4165d8eb65a52c6f_d20260305_m051105_c001_v7007000_t0000_u01772687465040") 2026/03/05 05:12:22 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6000_d20260305_m051037_c001_v0001145_t0037_u01772687437601") 2026/03/05 05:12:22 DEBUG : subdir-not/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf178_d20260305_m051037_c001_v0001165_t0038_u01772687437623") 2026/03/05 05:12:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c44350c_d20260305_m051038_c001_v0001102_t0024_u01772687438434") 2026/03/05 05:12:22 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf182_d20260305_m051038_c001_v0001165_t0004_u01772687438679") 2026/03/05 05:12:22 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f40087d0f28a0e650_d20260305_m051209_c001_v7007000_t0000_u01772687529171") 2026/03/05 05:12:22 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c44358a_d20260305_m051134_c001_v0001102_t0030_u01772687494533") 2026/03/05 05:12:22 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f414cdcdb89f7309d_d20260305_m051122_c001_v7007000_t0000_u01772687482876") 2026/03/05 05:12:22 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6002_d20260305_m051037_c001_v0001145_t0011_u01772687437922") 2026/03/05 05:12:22 DEBUG : subdir/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118ef20b9a072ef1_d20260305_m051038_c001_v0001173_t0015_u01772687438049") 2026/03/05 05:12:22 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6006_d20260305_m051038_c001_v0001145_t0042_u01772687438829") 2026/03/05 05:12:22 DEBUG : subdir/subdirA/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf186_d20260305_m051038_c001_v0001165_t0009_u01772687438907") 2026/03/05 05:12:22 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c44350e_d20260305_m051038_c001_v0001102_t0051_u01772687438740") 2026/03/05 05:12:22 DEBUG : subdir/subdirB/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118ef20b9a072efb_d20260305_m051038_c001_v0001173_t0049_u01772687438730") 2026/03/05 05:12:22 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf24e_d20260305_m051134_c001_v0001165_t0041_u01772687494382") 2026/03/05 05:12:23 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4072516e7446db7d_d20260305_m051109_c001_v7007000_t0000_u01772687469111") 2026/03/05 05:12:23 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f761e8c443508_d20260305_m051037_c001_v0001102_t0038_u01772687437854") 2026/03/05 05:12:23 DEBUG : subdirX/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf17c_d20260305_m051038_c001_v0001165_t0059_u01772687438115") 2026/03/05 05:12:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f40619272b90961d7_d20260305_m051211_c001_v7007000_t0000_u01772687531209") 2026/03/05 05:12:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f106729e610ba6004_d20260305_m051038_c001_v0001145_t0045_u01772687438357") 2026/03/05 05:12:23 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1114160ed7bcf180_d20260305_m051038_c001_v0001165_t0052_u01772687438394") 2026/03/05 05:12:23 INFO : B2 bucket rclone-test-fubimez8wize path 051030na/check_access_filters/path2: cleaning bucket "rclone-test-fubimez8wize" of all files 2026/03/05 05:12:23 DEBUG : RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f420437d71d4b1b60_d20260305_m051208_c001_v7007000_t0000_u01772687528080") 2026/03/05 05:12:23 DEBUG : RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f103d03310967eadf_d20260305_m051135_c001_v0001101_t0031_u01772687495639") 2026/03/05 05:12:23 DEBUG : RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4072516e7446dbe3_d20260305_m051120_c001_v7007000_t0000_u01772687480854") 2026/03/05 05:12:23 DEBUG : RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da624_d20260305_m051040_c001_v0001113_t0029_u01772687440876") 2026/03/05 05:12:23 DEBUG : file1.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c07_d20260305_m051040_c001_v0001040_t0023_u01772687440317") 2026/03/05 05:12:23 DEBUG : file2.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed09166_d20260305_m051040_c001_v0001134_t0009_u01772687440497") 2026/03/05 05:12:23 DEBUG : file3.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdc78_d20260305_m051040_c001_v0001092_t0016_u01772687440684") 2026/03/05 05:12:23 DEBUG : file4.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c09_d20260305_m051040_c001_v0001040_t0008_u01772687440706") 2026/03/05 05:12:23 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f40619272b9096204_d20260305_m051219_c001_v7007000_t0000_u01772687539820") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1077ccd3e6343163_d20260305_m051218_c001_v0001103_t0054_u01772687538370") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdd38_d20260305_m051216_c001_v0001092_t0005_u01772687536078") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4165d8eb65a52cce_d20260305_m051207_c001_v7007000_t0000_u01772687527136") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1194214b75874ad3_d20260305_m051205_c001_v0001167_t0005_u01772687525344") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed0921c_d20260305_m051203_c001_v0001134_t0013_u01772687523444") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f410117c0ddadcb24_d20260305_m051155_c001_v7007000_t0000_u01772687515010") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f114a5b3a9a093088_d20260305_m051153_c001_v0001116_t0058_u01772687513460") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c81_d20260305_m051151_c001_v0001040_t0014_u01772687511439") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f401eb10061e920bb_d20260305_m051145_c001_v7007000_t0000_u01772687505775") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f113a3369e17f9533_d20260305_m051144_c001_v0001179_t0013_u01772687504575") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da65a_d20260305_m051142_c001_v0001113_t0056_u01772687502159") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f423fdfa32728adc6_d20260305_m051131_c001_v7007000_t0000_u01772687491750") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1007dfbc584435a3_d20260305_m051130_c001_v0001043_t0054_u01772687490277") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdcec_d20260305_m051127_c001_v0001092_t0023_u01772687487809") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4072516e7446dbc4_d20260305_m051118_c001_v7007000_t0000_u01772687478386") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10389506727aeaa8_d20260305_m051116_c001_v0001039_t0039_u01772687476573") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed091b0_d20260305_m051114_c001_v0001134_t0048_u01772687474071") 2026/03/05 05:12:24 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f422639afb75ce1a3_d20260305_m051100_c001_v7007000_t0000_u01772687460476") 2026/03/05 05:12:25 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1165e8fb9ea12d36_d20260305_m051059_c001_v0001183_t0046_u01772687459268") 2026/03/05 05:12:25 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c33_d20260305_m051056_c001_v0001040_t0017_u01772687456787") 2026/03/05 05:12:25 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f416ba81b96264bf0_d20260305_m051051_c001_v7007000_t0000_u01772687451079") 2026/03/05 05:12:25 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f11760c91f7f40ea3_d20260305_m051049_c001_v0001144_t0028_u01772687449494") 2026/03/05 05:12:25 DEBUG : modtime_write_test: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da632_d20260305_m051047_c001_v0001113_t0013_u01772687447234") 2026/03/05 05:12:25 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed0916a_d20260305_m051041_c001_v0001134_t0011_u01772687441394") 2026/03/05 05:12:25 DEBUG : subdir-not/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdc7c_d20260305_m051041_c001_v0001092_t0058_u01772687441480") 2026/03/05 05:12:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f411973d921a58e31_d20260305_m051159_c001_v7007000_t0000_u01772687519003") 2026/03/05 05:12:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f118f6546bbbf60c5_d20260305_m051136_c001_v0001106_t0047_u01772687496011") 2026/03/05 05:12:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f41533ddf9af84671_d20260305_m051107_c001_v7007000_t0000_u01772687467024") 2026/03/05 05:12:25 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c0f_d20260305_m051042_c001_v0001040_t0039_u01772687442301") 2026/03/05 05:12:25 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdc80_d20260305_m051042_c001_v0001092_t0005_u01772687442521") 2026/03/05 05:12:25 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da62a_d20260305_m051041_c001_v0001113_t0034_u01772687441633") 2026/03/05 05:12:25 DEBUG : subdir/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed0916e_d20260305_m051041_c001_v0001134_t0022_u01772687441716") 2026/03/05 05:12:25 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f404edd6f1b41e53f_d20260305_m051156_c001_v7007000_t0000_u01772687516841") 2026/03/05 05:12:25 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f101c083eb2f076a0_d20260305_m051136_c001_v0001099_t0034_u01772687496023") 2026/03/05 05:12:25 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f4072516e7446db4c_d20260305_m051103_c001_v7007000_t0000_u01772687463041") 2026/03/05 05:12:25 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c0d_d20260305_m051041_c001_v0001040_t0006_u01772687441840") 2026/03/05 05:12:25 DEBUG : subdir/subdirA/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da62c_d20260305_m051041_c001_v0001113_t0034_u01772687441927") 2026/03/05 05:12:25 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdc7e_d20260305_m051042_c001_v0001092_t0041_u01772687442171") 2026/03/05 05:12:26 DEBUG : subdir/subdirB/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed09170_d20260305_m051042_c001_v0001134_t0032_u01772687442199") 2026/03/05 05:12:26 DEBUG : subdirX/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f117561366ed09168_d20260305_m051040_c001_v0001134_t0044_u01772687440990") 2026/03/05 05:12:26 DEBUG : subdirX/file20.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10376d3e538fdc7a_d20260305_m051041_c001_v0001092_t0002_u01772687441053") 2026/03/05 05:12:26 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f10118d5f1c0da626_d20260305_m051041_c001_v0001113_t0043_u01772687441168") 2026/03/05 05:12:26 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "4_z4f029d83607dbbcb9fc80e1b_f1079058c9dcb9c0b_d20260305_m051041_c001_v0001040_t0026_u01772687441385") bisync_test.go:608: TEST check_access_filters PASSED 2026/03/05 05:12:26 DEBUG : B2 bucket rclone-test-fubimez8wize: Purge remote 2026/03/05 05:12:26 INFO : B2 bucket rclone-test-fubimez8wize: cleaning bucket "rclone-test-fubimez8wize" of all files --- PASS: TestBisyncRemoteRemote (118.14s) --- PASS: TestBisyncRemoteRemote/check_access_filters (116.44s) PASS 2026/03/05 05:12:27 DEBUG : B2 bucket rclone-test-judocob5biyi: Purge remote 2026/03/05 05:12:27 INFO : B2 bucket rclone-test-judocob5biyi: cleaning bucket "rclone-test-judocob5biyi" of all files 2026/03/05 05:12:28 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^check_access_filters$'" - Finished OK in 1m59.536321825s (try 4/5)