"./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bS3: -verbose -size-limit 1024 -test.run '^TestBisyncRemoteRemote$/^(check_access|check_access_filters)$'" - Starting (try 2/5)
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-jifetif6nabe"
2025/10/09 02:42:15 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestS3:rclone-test-jifetif6nabe"
2025/10/09 02:42:15 DEBUG : Creating backend with remote "/tmp/rclone709894219"
=== RUN   TestBisyncRemoteRemote
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami"
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami"
2025/10/09 02:42:15 NOTICE: remote: TestChunkerChunk50bS3:rclone-test-bipagug7dami
=== RUN   TestBisyncRemoteRemote/check_access
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi"
2025/10/09 02:42:15 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi"
2025/10/09 02:42:15 INFO  : path1: Making directory
2025/10/09 02:42:16 INFO  : S3 bucket rclone-test-bipagug7dami path 024215hi: Bucket "rclone-test-bipagug7dami" created with ACL ""
2025/10/09 02:42:16 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1"
2025/10/09 02:42:16 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path1"
2025/10/09 02:42:16 INFO  : path2: Making directory
2025/10/09 02:42:16 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2"
2025/10/09 02:42:16 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path2"
2025/10/09 02:42:16 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/initial"
2025/10/09 02:42:16 DEBUG : Creating backend with remote "/tmp/024215hi/initdir/test_check_access-tabucer5"
2025/10/09 02:42:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : subdir: Making directory with metadata
2025/10/09 02:42:16 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:42:16 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/09 02:42:16 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : Local file system at /tmp/024215hi/initdir/test_check_access-tabucer5: Waiting for checks to finish
2025/10/09 02:42:16 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:16 DEBUG : Local file system at /tmp/024215hi/initdir/test_check_access-tabucer5: Waiting for transfers to finish
2025/10/09 02:42:16 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/09 02:42:16 INFO  : file2.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:16 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/09 02:42:16 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:42:16 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:16 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:16 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/09 02:42:16 INFO  : file3.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/09 02:42:16 INFO  : file4.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:16 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:16 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/09 02:42:16 INFO  : file1.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/09 02:42:16 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:16 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/10/09 02:42:16 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:42:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/09 02:42:16 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/modfiles"
2025/10/09 02:42:16 DEBUG : Creating backend with remote "/tmp/024215hi/datadir/test_check_access-yibofac6"
2025/10/09 02:42:16 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:16 DEBUG : Local file system at /tmp/024215hi/datadir/test_check_access-yibofac6: Waiting for checks to finish
2025/10/09 02:42:16 DEBUG : Local file system at /tmp/024215hi/datadir/test_check_access-yibofac6: Waiting for transfers to finish
2025/10/09 02:42:16 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/10/09 02:42:16 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2025/10/09 02:42:16 INFO  : hold.txt: Copied (new)
2025/10/09 02:42:16 DEBUG : S3 bucket rclone-test-bipagug7dami path 024215hi/check_access/path1: bucket is versioned: false
2025/10/09 02:42:16 DEBUG : Waiting for deletions to finish
2025/10/09 02:42:16 DEBUG : S3 bucket rclone-test-bipagug7dami path 024215hi/check_access/path2: bucket is versioned: false
2025/10/09 02:42:16 DEBUG : Waiting for deletions to finish
2025/10/09 02:42:17 NOTICE: checking initFs Local file system at /tmp/024215hi/initdir/test_check_access-tabucer5
2025/10/09 02:42:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:17 INFO  : subdir: Making directory
2025/10/09 02:42:17 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:17 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Waiting for checks to finish
2025/10/09 02:42:17 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Waiting for transfers to finish
2025/10/09 02:42:17 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : file1.txt.rclone_chunk.001_ul62j6: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file1.txt.rclone_chunk.001_ul62j6: Copied (server-side copy) to: file1.txt
2025/10/09 02:42:17 DEBUG : file2.txt.rclone_chunk.001_ul62qv: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file2.txt.rclone_chunk.001_ul62qv: Copied (server-side copy) to: file2.txt
2025/10/09 02:42:17 DEBUG : file3.txt.rclone_chunk.001_ul62li: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file3.txt.rclone_chunk.001_ul62li: Copied (server-side copy) to: file3.txt
2025/10/09 02:42:17 INFO  : file2.txt.rclone_chunk.001_ul62qv: Deleted
2025/10/09 02:42:17 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file2.txt: Copied (new)
2025/10/09 02:42:17 INFO  : file1.txt.rclone_chunk.001_ul62j6: Deleted
2025/10/09 02:42:17 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file1.txt: Copied (new)
2025/10/09 02:42:17 INFO  : file3.txt.rclone_chunk.001_ul62li: Deleted
2025/10/09 02:42:17 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:17 INFO  : file3.txt: Copied (new)
2025/10/09 02:42:17 DEBUG : subdir/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : subdir/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:17 DEBUG : RCLONE_TEST.rclone_chunk.001_ul629o: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:42:17 INFO  : RCLONE_TEST.rclone_chunk.001_ul629o: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.001
2025/10/09 02:42:17 INFO  : RCLONE_TEST.rclone_chunk.001_ul629o: Deleted
2025/10/09 02:42:17 DEBUG : RCLONE_TEST.rclone_chunk.002_ul629o: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:42:17 INFO  : RCLONE_TEST.rclone_chunk.002_ul629o: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.002
2025/10/09 02:42:17 INFO  : RCLONE_TEST.rclone_chunk.002_ul629o: Deleted
2025/10/09 02:42:18 DEBUG : file4.txt.rclone_chunk.001_ul62vq: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:18 INFO  : file4.txt.rclone_chunk.001_ul62vq: Copied (server-side copy) to: file4.txt
2025/10/09 02:42:18 DEBUG : subdir/file20.txt.rclone_chunk.001_ul629p: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:18 INFO  : subdir/file20.txt.rclone_chunk.001_ul629p: Copied (server-side copy) to: subdir/file20.txt
2025/10/09 02:42:18 DEBUG : RCLONE_TEST.rclone_chunk.003_ul629o: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:42:18 INFO  : RCLONE_TEST.rclone_chunk.003_ul629o: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.003
2025/10/09 02:42:18 INFO  : file4.txt.rclone_chunk.001_ul62vq: Deleted
2025/10/09 02:42:18 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:18 INFO  : file4.txt: Copied (new)
2025/10/09 02:42:18 INFO  : subdir/file20.txt.rclone_chunk.001_ul629p: Deleted
2025/10/09 02:42:18 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:18 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:42:18 INFO  : RCLONE_TEST.rclone_chunk.003_ul629o: Deleted
2025/10/09 02:42:18 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001_ul62xr: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul62xr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul62xr: Deleted
2025/10/09 02:42:18 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:18 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:42:18 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002_ul62xr: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul62xr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul62xr: Deleted
2025/10/09 02:42:18 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003_ul62xr: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul62xr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul62xr: Deleted
2025/10/09 02:42:18 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:18 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:42:18 NOTICE: checking Path1 Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1'
2025/10/09 02:42:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:19 INFO  : subdir: Making directory
2025/10/09 02:42:19 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:42:19 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Waiting for checks to finish
2025/10/09 02:42:19 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Waiting for transfers to finish
2025/10/09 02:42:19 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : file1.txt.rclone_chunk.001_ul64rn: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file1.txt.rclone_chunk.001_ul64rn: Copied (server-side copy) to: file1.txt
2025/10/09 02:42:19 DEBUG : file2.txt.rclone_chunk.001_ul64w0: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file2.txt.rclone_chunk.001_ul64w0: Copied (server-side copy) to: file2.txt
2025/10/09 02:42:19 DEBUG : file3.txt.rclone_chunk.001_ul64o0: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file3.txt.rclone_chunk.001_ul64o0: Copied (server-side copy) to: file3.txt
2025/10/09 02:42:19 INFO  : file1.txt.rclone_chunk.001_ul64rn: Deleted
2025/10/09 02:42:19 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file1.txt: Copied (new)
2025/10/09 02:42:19 INFO  : file2.txt.rclone_chunk.001_ul64w0: Deleted
2025/10/09 02:42:19 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file2.txt: Copied (new)
2025/10/09 02:42:19 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 INFO  : file3.txt.rclone_chunk.001_ul64o0: Deleted
2025/10/09 02:42:19 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file3.txt: Copied (new)
2025/10/09 02:42:19 DEBUG : subdir/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : subdir/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:42:19 DEBUG : RCLONE_TEST.rclone_chunk.001_ul64wy: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:42:19 INFO  : RCLONE_TEST.rclone_chunk.001_ul64wy: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.001
2025/10/09 02:42:19 INFO  : RCLONE_TEST.rclone_chunk.001_ul64wy: Deleted
2025/10/09 02:42:19 DEBUG : RCLONE_TEST.rclone_chunk.002_ul64wy: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:42:19 INFO  : RCLONE_TEST.rclone_chunk.002_ul64wy: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.002
2025/10/09 02:42:19 INFO  : RCLONE_TEST.rclone_chunk.002_ul64wy: Deleted
2025/10/09 02:42:19 DEBUG : file4.txt.rclone_chunk.001_ul64xs: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file4.txt.rclone_chunk.001_ul64xs: Copied (server-side copy) to: file4.txt
2025/10/09 02:42:19 DEBUG : RCLONE_TEST.rclone_chunk.003_ul64wy: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:42:19 INFO  : RCLONE_TEST.rclone_chunk.003_ul64wy: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.003
2025/10/09 02:42:19 INFO  : file4.txt.rclone_chunk.001_ul64xs: Deleted
2025/10/09 02:42:19 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : file4.txt: Copied (new)
2025/10/09 02:42:19 DEBUG : subdir/file20.txt.rclone_chunk.001_ul64rl: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:19 INFO  : subdir/file20.txt.rclone_chunk.001_ul64rl: Copied (server-side copy) to: subdir/file20.txt
2025/10/09 02:42:20 INFO  : RCLONE_TEST.rclone_chunk.003_ul64wy: Deleted
2025/10/09 02:42:20 INFO  : subdir/file20.txt.rclone_chunk.001_ul64rl: Deleted
2025/10/09 02:42:20 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:42:20 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:42:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001_ul64yr: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul64yr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul64yr: Deleted
2025/10/09 02:42:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:20 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:42:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002_ul64yr: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul64yr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul64yr: Deleted
2025/10/09 02:42:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003_ul64yr: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul64yr: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul64yr: Deleted
2025/10/09 02:42:20 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:42:20 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:42:20 NOTICE: checking path2 Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2'
2025/10/09 02:42:20 NOTICE: (01)  : test check-access
2025/10/09 02:42:20 NOTICE: (02)  : test initial bisync
2025/10/09 02:42:20 NOTICE: (03)  : bisync resync
2025/10/09 02:42:21 DEBUG : modtime_write_test.rclone_chunk.001_ul650a: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:21 INFO  : modtime_write_test.rclone_chunk.001_ul650a: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:21 INFO  : modtime_write_test.rclone_chunk.001_ul650a: Deleted
2025/10/09 02:42:23 DEBUG : modtime_write_test.rclone_chunk.001_ul68m0: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:23 INFO  : modtime_write_test.rclone_chunk.001_ul68m0: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:23 INFO  : modtime_write_test.rclone_chunk.001_ul68m0: Deleted
2025/10/09 02:42:25 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:25 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:25 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:25 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:25 INFO  : Copying Path2 files to Path1
2025/10/09 02:42:25 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/09 02:42:26 INFO  : There was nothing to transfer
2025/10/09 02:42:26 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/09 02:42:26 INFO  : There was nothing to transfer
2025/10/09 02:42:26 INFO  : Resync updating listings
2025/10/09 02:42:26 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:26 INFO  : Bisync successful
2025/10/09 02:42:26 NOTICE: (04)  : test 1. see that check-access passes with the initial setup
2025/10/09 02:42:26 NOTICE: (05)  : bisync check-access
2025/10/09 02:42:27 DEBUG : modtime_write_test.rclone_chunk.001_ul6bpi: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:27 INFO  : modtime_write_test.rclone_chunk.001_ul6bpi: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:27 INFO  : modtime_write_test.rclone_chunk.001_ul6bpi: Deleted
2025/10/09 02:42:29 DEBUG : modtime_write_test.rclone_chunk.001_ul6evw: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:29 INFO  : modtime_write_test.rclone_chunk.001_ul6evw: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:29 INFO  : modtime_write_test.rclone_chunk.001_ul6evw: Deleted
2025/10/09 02:42:31 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:31 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:31 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:31 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:31 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:31 INFO  : Building Path1 and Path2 listings
2025/10/09 02:42:32 INFO  : Path1 checking for diffs
2025/10/09 02:42:32 INFO  : Path2 checking for diffs
2025/10/09 02:42:32 INFO  : Checking access health
2025/10/09 02:42:32 INFO  : Found 2 matching "RCLONE_TEST" files on both paths
2025/10/09 02:42:32 INFO  : No changes found
2025/10/09 02:42:32 INFO  : Updating listings
2025/10/09 02:42:32 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:32 INFO  : Bisync successful
2025/10/09 02:42:32 NOTICE: (06)  : test 2. delete the path2 subdir RCLONE_TEST and run sync. should fail critical.
2025/10/09 02:42:32 NOTICE: (07)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/subdir/RCLONE_TEST
2025/10/09 02:42:32 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/subdir/"
2025/10/09 02:42:32 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path2/subdir"
2025/10/09 02:42:33 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:42:33 NOTICE: (08)  : bisync check-access
2025/10/09 02:42:33 DEBUG : modtime_write_test.rclone_chunk.001_ul6i6t: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:33 INFO  : modtime_write_test.rclone_chunk.001_ul6i6t: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:33 INFO  : modtime_write_test.rclone_chunk.001_ul6i6t: Deleted
2025/10/09 02:42:35 DEBUG : modtime_write_test.rclone_chunk.001_ul6k32: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:35 INFO  : modtime_write_test.rclone_chunk.001_ul6k32: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:35 INFO  : modtime_write_test.rclone_chunk.001_ul6k32: Deleted
2025/10/09 02:42:38 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:38 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:38 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:38 INFO  : Building Path1 and Path2 listings
2025/10/09 02:42:38 INFO  : Path1 checking for diffs
2025/10/09 02:42:38 INFO  : Path2 checking for diffs
2025/10/09 02:42:38 INFO  : - Path2    File was deleted          - subdir/RCLONE_TEST
2025/10/09 02:42:38 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/09 02:42:38 INFO  : Checking access health
2025/10/09 02:42:38 ERROR : Access test failed: Path1 count 2, Path2 count 1 - RCLONE_TEST
2025/10/09 02:42:38 ERROR : -          Access test failed: Path1 file not found in Path2 - subdir/RCLONE_TEST
2025/10/09 02:42:38 ERROR : Bisync critical error: check file check failed
2025/10/09 02:42:38 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/09 02:42:38 NOTICE: Bisync error: bisync aborted
2025/10/09 02:42:38 NOTICE: (09)  : copy-listings path2-missing
2025/10/09 02:42:38 NOTICE: (10)  : test 3. put the path2 subdir RCLONE_TEST back, resync.
2025/10/09 02:42:38 NOTICE: (11)  : copy-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir/RCLONE_TEST TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/
2025/10/09 02:42:38 DEBUG : copyFile "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir/RCLONE_TEST" to "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/" as ""
2025/10/09 02:42:38 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir/RCLONE_TEST"
2025/10/09 02:42:38 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir/RCLONE_TEST"
2025/10/09 02:42:38 DEBUG : fs cache: renaming child cache item "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir/RCLONE_TEST" to be canonical for parent "TestS3:rclone-test-bipagug7dami/024215hi/check_access/path1/subdir"
2025/10/09 02:42:38 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:38 DEBUG : operations.CopyFile "RCLONE_TEST" to "Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/'" as "RCLONE_TEST"
2025/10/09 02:42:39 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:42:39 DEBUG : RCLONE_TEST: Unchanged skipping
2025/10/09 02:42:39 NOTICE: (12)  : bisync resync
2025/10/09 02:42:39 DEBUG : modtime_write_test.rclone_chunk.001_ul6ote: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:39 INFO  : modtime_write_test.rclone_chunk.001_ul6ote: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:39 INFO  : modtime_write_test.rclone_chunk.001_ul6ote: Deleted
2025/10/09 02:42:41 DEBUG : modtime_write_test.rclone_chunk.001_ul6qbw: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:41 INFO  : modtime_write_test.rclone_chunk.001_ul6qbw: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:41 INFO  : modtime_write_test.rclone_chunk.001_ul6qbw: Deleted
2025/10/09 02:42:44 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:44 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:44 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:44 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:44 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:44 INFO  : Copying Path2 files to Path1
2025/10/09 02:42:44 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/09 02:42:44 INFO  : There was nothing to transfer
2025/10/09 02:42:44 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/09 02:42:45 INFO  : subdir/RCLONE_TEST: Copied (server-side copy)
2025/10/09 02:42:45 INFO  : Resync updating listings
2025/10/09 02:42:45 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:45 INFO  : Bisync successful
2025/10/09 02:42:45 NOTICE: (13)  : test 4. run sync with check-access. should pass.
2025/10/09 02:42:45 NOTICE: (14)  : bisync check-access
2025/10/09 02:42:45 DEBUG : modtime_write_test.rclone_chunk.001_ul6umx: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:45 INFO  : modtime_write_test.rclone_chunk.001_ul6umx: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:45 INFO  : modtime_write_test.rclone_chunk.001_ul6umx: Deleted
2025/10/09 02:42:48 DEBUG : modtime_write_test.rclone_chunk.001_ul6xrj: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:48 INFO  : modtime_write_test.rclone_chunk.001_ul6xrj: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:48 INFO  : modtime_write_test.rclone_chunk.001_ul6xrj: Deleted
2025/10/09 02:42:50 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:50 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:50 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:50 INFO  : Building Path1 and Path2 listings
2025/10/09 02:42:51 INFO  : Path1 checking for diffs
2025/10/09 02:42:51 INFO  : Path2 checking for diffs
2025/10/09 02:42:51 INFO  : Checking access health
2025/10/09 02:42:51 INFO  : Found 2 matching "RCLONE_TEST" files on both paths
2025/10/09 02:42:51 INFO  : No changes found
2025/10/09 02:42:51 INFO  : Updating listings
2025/10/09 02:42:51 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:51 INFO  : Bisync successful
2025/10/09 02:42:51 NOTICE: (15)  : test 5. delete path1 top level RCLONE_TEST, run sync. should fail critical.
2025/10/09 02:42:51 NOTICE: (16)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/RCLONE_TEST
2025/10/09 02:42:51 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/"
2025/10/09 02:42:51 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:42:51 NOTICE: (17)  : bisync check-access
2025/10/09 02:42:51 DEBUG : modtime_write_test.rclone_chunk.001_ul706i: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:51 INFO  : modtime_write_test.rclone_chunk.001_ul706i: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:51 INFO  : modtime_write_test.rclone_chunk.001_ul706i: Deleted
2025/10/09 02:42:54 DEBUG : modtime_write_test.rclone_chunk.001_ul73yx: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:54 INFO  : modtime_write_test.rclone_chunk.001_ul73yx: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:54 INFO  : modtime_write_test.rclone_chunk.001_ul73yx: Deleted
2025/10/09 02:42:56 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:56 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:42:56 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:42:56 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:42:56 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:42:56 INFO  : Building Path1 and Path2 listings
2025/10/09 02:42:57 INFO  : Path1 checking for diffs
2025/10/09 02:42:57 INFO  : - Path1    File was deleted          - RCLONE_TEST
2025/10/09 02:42:57 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/09 02:42:57 INFO  : Path2 checking for diffs
2025/10/09 02:42:57 INFO  : Checking access health
2025/10/09 02:42:57 ERROR : Access test failed: Path1 count 1, Path2 count 2 - RCLONE_TEST
2025/10/09 02:42:57 ERROR : -          Access test failed: Path2 file not found in Path1 - RCLONE_TEST
2025/10/09 02:42:57 ERROR : Bisync critical error: check file check failed
2025/10/09 02:42:57 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/09 02:42:57 NOTICE: Bisync error: bisync aborted
2025/10/09 02:42:57 NOTICE: (18)  : copy-listings path1-missing
2025/10/09 02:42:57 NOTICE: (19)  : test 6. run again. should fail critical due to missing listings.
2025/10/09 02:42:57 NOTICE: (20)  : bisync check-access
2025/10/09 02:42:57 DEBUG : modtime_write_test.rclone_chunk.001_ul76np: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:42:57 INFO  : modtime_write_test.rclone_chunk.001_ul76np: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:42:57 INFO  : modtime_write_test.rclone_chunk.001_ul76np: Deleted
2025/10/09 02:43:00 DEBUG : modtime_write_test.rclone_chunk.001_ul78di: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:00 INFO  : modtime_write_test.rclone_chunk.001_ul78di: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:00 INFO  : modtime_write_test.rclone_chunk.001_ul78di: Deleted
2025/10/09 02:43:02 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:02 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:02 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:02 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:02 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:43:02 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/024215hi/workdir/TestChunkerChunk50bS3_rclone-test-bipagug7dami_024215hi_check_access_path1..TestChunkerChunk50bS3_rclone-test-bipagug7dami_024215hi_check_access_path2.path1.lst
Path2: /tmp/024215hi/workdir/TestChunkerChunk50bS3_rclone-test-bipagug7dami_024215hi_check_access_path1..TestChunkerChunk50bS3_rclone-test-bipagug7dami_024215hi_check_access_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/024215hi/workdir"
2025/10/09 02:43:02 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/09 02:43:02 NOTICE: Bisync error: bisync aborted
2025/10/09 02:43:02 NOTICE: (21)  : move-listings missing-listings
2025/10/09 02:43:02 NOTICE: (22)  : test 7. run resync, which will copy the path2 top level back to path1.
2025/10/09 02:43:02 NOTICE: (23)  : bisync resync
2025/10/09 02:43:02 DEBUG : modtime_write_test.rclone_chunk.001_ul7bs5: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:02 INFO  : modtime_write_test.rclone_chunk.001_ul7bs5: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:02 INFO  : modtime_write_test.rclone_chunk.001_ul7bs5: Deleted
2025/10/09 02:43:05 DEBUG : modtime_write_test.rclone_chunk.001_ul7d2h: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:05 INFO  : modtime_write_test.rclone_chunk.001_ul7d2h: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:05 INFO  : modtime_write_test.rclone_chunk.001_ul7d2h: Deleted
2025/10/09 02:43:07 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:07 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:07 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:43:07 INFO  : Copying Path2 files to Path1
2025/10/09 02:43:07 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/09 02:43:08 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/09 02:43:08 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/09 02:43:08 INFO  : Resync updating listings
2025/10/09 02:43:08 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:43:08 INFO  : Bisync successful
2025/10/09 02:43:08 NOTICE: (24)  : test 8. run sync with --check-access. should pass.
2025/10/09 02:43:08 NOTICE: (25)  : bisync check-access
2025/10/09 02:43:08 DEBUG : modtime_write_test.rclone_chunk.001_ul7hcw: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:08 INFO  : modtime_write_test.rclone_chunk.001_ul7hcw: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:08 INFO  : modtime_write_test.rclone_chunk.001_ul7hcw: Deleted
2025/10/09 02:43:11 DEBUG : modtime_write_test.rclone_chunk.001_ul7jdl: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:11 INFO  : modtime_write_test.rclone_chunk.001_ul7jdl: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:11 INFO  : modtime_write_test.rclone_chunk.001_ul7jdl: Deleted
2025/10/09 02:43:13 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:13 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:13 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:13 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:13 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:43:13 INFO  : Building Path1 and Path2 listings
2025/10/09 02:43:14 INFO  : Path1 checking for diffs
2025/10/09 02:43:14 INFO  : Path2 checking for diffs
2025/10/09 02:43:14 INFO  : Checking access health
2025/10/09 02:43:14 INFO  : Found 2 matching "RCLONE_TEST" files on both paths
2025/10/09 02:43:14 INFO  : No changes found
2025/10/09 02:43:14 INFO  : Updating listings
2025/10/09 02:43:14 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access/path2/"
2025/10/09 02:43:14 INFO  : Bisync successful
2025/10/09 02:43:14 DEBUG : Waiting for deletions to finish
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file1.txt" version false
2025/10/09 02:43:14 DEBUG : file1.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file2.txt" version false
2025/10/09 02:43:14 DEBUG : file2.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file3.txt" version false
2025/10/09 02:43:14 DEBUG : file3.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file4.txt" version false
2025/10/09 02:43:14 DEBUG : file4.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/file20.txt" version false
2025/10/09 02:43:14 DEBUG : subdir/file20.txt: Deleting (id "")
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:43:14 INFO  : file2.txt: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:14 INFO  : file1.txt: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:43:14 INFO  : file3.txt: Deleted
2025/10/09 02:43:14 INFO  : file4.txt: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:43:14 INFO  : subdir/file20.txt: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:43:14 DEBUG : Waiting for deletions to finish
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file1.txt" version false
2025/10/09 02:43:14 DEBUG : file1.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file2.txt" version false
2025/10/09 02:43:14 DEBUG : file2.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file3.txt" version false
2025/10/09 02:43:14 DEBUG : file3.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "file4.txt" version false
2025/10/09 02:43:14 DEBUG : file4.txt: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:43:14 DEBUG : "subdir/file20.txt" version false
2025/10/09 02:43:14 DEBUG : subdir/file20.txt: Deleting (id "")
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:43:14 INFO  : file4.txt: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST: Deleted
2025/10/09 02:43:14 INFO  : RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:43:14 INFO  : file1.txt: Deleted
2025/10/09 02:43:14 INFO  : file3.txt: Deleted
2025/10/09 02:43:14 INFO  : file2.txt: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:43:14 INFO  : subdir/file20.txt: Deleted
    bisync_test.go:608: TEST check_access PASSED
=== RUN   TestBisyncRemoteRemote/check_access_filters
2025/10/09 02:43:14 INFO  : path1: Making directory
2025/10/09 02:43:14 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1"
2025/10/09 02:43:14 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1"
2025/10/09 02:43:14 INFO  : path2: Making directory
2025/10/09 02:43:14 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2"
2025/10/09 02:43:14 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2"
2025/10/09 02:43:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/10/09 02:43:14 DEBUG : Creating backend with remote "/tmp/024215hi/initdir/test_check_access_filters-pexeguh2"
2025/10/09 02:43:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir-not: Making directory with metadata
2025/10/09 02:43:14 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/10/09 02:43:14 DEBUG : subdir: Making directory with metadata
2025/10/09 02:43:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/09 02:43:14 INFO  : file1.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/09 02:43:14 INFO  : file2.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/09 02:43:14 DEBUG : subdirX: Making directory with metadata
2025/10/09 02:43:14 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/10/09 02:43:14 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/09 02:43:14 INFO  : file4.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/10/09 02:43:14 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/10/09 02:43:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir/subdirA: Making directory with metadata
2025/10/09 02:43:14 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/10/09 02:43:14 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/10/09 02:43:14 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/10/09 02:43:14 DEBUG : subdir/subdirB: Making directory with metadata
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/09 02:43:14 INFO  : file3.txt: Copied (new)
2025/10/09 02:43:14 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/09 02:43:14 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/10/09 02:43:14 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/10/09 02:43:14 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/10/09 02:43:14 INFO  : subdirX/file20.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/09 02:43:14 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : Local file system at /tmp/024215hi/initdir/test_check_access_filters-pexeguh2: Waiting for checks to finish
2025/10/09 02:43:14 DEBUG : Local file system at /tmp/024215hi/initdir/test_check_access_filters-pexeguh2: Waiting for transfers to finish
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/10/09 02:43:14 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/10/09 02:43:14 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/10/09 02:43:14 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:14 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/10/09 02:43:14 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:14 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/10/09 02:43:14 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/09 02:43:14 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/09 02:43:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/10/09 02:43:14 DEBUG : Creating backend with remote "/tmp/024215hi/datadir/test_check_access_filters-yiwopaf9"
2025/10/09 02:43:14 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:14 DEBUG : Local file system at /tmp/024215hi/datadir/test_check_access_filters-yiwopaf9: Waiting for checks to finish
2025/10/09 02:43:14 DEBUG : Local file system at /tmp/024215hi/datadir/test_check_access_filters-yiwopaf9: Waiting for transfers to finish
2025/10/09 02:43:14 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/10/09 02:43:14 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/10/09 02:43:14 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/10/09 02:43:14 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/10/09 02:43:14 INFO  : include-other-filtersfile.txt: Copied (new)
2025/10/09 02:43:14 DEBUG : S3 bucket rclone-test-bipagug7dami path 024215hi/check_access_filters/path1: bucket is versioned: false
2025/10/09 02:43:14 DEBUG : Waiting for deletions to finish
2025/10/09 02:43:14 DEBUG : S3 bucket rclone-test-bipagug7dami path 024215hi/check_access_filters/path2: bucket is versioned: false
2025/10/09 02:43:14 DEBUG : Waiting for deletions to finish
2025/10/09 02:43:15 NOTICE: checking initFs Local file system at /tmp/024215hi/initdir/test_check_access_filters-pexeguh2
2025/10/09 02:43:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 INFO  : subdir-not: Making directory
2025/10/09 02:43:15 INFO  : subdir: Making directory
2025/10/09 02:43:15 INFO  : subdirX: Making directory
2025/10/09 02:43:15 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 INFO  : subdirX/subdirX1: Making directory
2025/10/09 02:43:15 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 INFO  : subdir-not/subdir-not2: Making directory
2025/10/09 02:43:15 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 INFO  : subdir/subdirA: Making directory
2025/10/09 02:43:15 INFO  : subdir/subdirB: Making directory
2025/10/09 02:43:15 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:15 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Waiting for checks to finish
2025/10/09 02:43:15 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Waiting for transfers to finish
2025/10/09 02:43:15 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : file1.txt.rclone_chunk.001_ul7o60: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file1.txt.rclone_chunk.001_ul7o60: Copied (server-side copy) to: file1.txt
2025/10/09 02:43:15 DEBUG : file2.txt.rclone_chunk.001_ul7omm: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file2.txt.rclone_chunk.001_ul7omm: Copied (server-side copy) to: file2.txt
2025/10/09 02:43:15 INFO  : file1.txt.rclone_chunk.001_ul7o60: Deleted
2025/10/09 02:43:15 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file1.txt: Copied (new)
2025/10/09 02:43:15 DEBUG : file3.txt.rclone_chunk.001_ul7ol7: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file3.txt.rclone_chunk.001_ul7ol7: Copied (server-side copy) to: file3.txt
2025/10/09 02:43:15 INFO  : file2.txt.rclone_chunk.001_ul7omm: Deleted
2025/10/09 02:43:15 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file2.txt: Copied (new)
2025/10/09 02:43:15 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 INFO  : file3.txt.rclone_chunk.001_ul7ol7: Deleted
2025/10/09 02:43:15 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:15 INFO  : file3.txt: Copied (new)
2025/10/09 02:43:15 DEBUG : subdirX/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : subdirX/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:15 DEBUG : RCLONE_TEST.rclone_chunk.001_ul7ok2: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:15 INFO  : RCLONE_TEST.rclone_chunk.001_ul7ok2: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:15 INFO  : RCLONE_TEST.rclone_chunk.001_ul7ok2: Deleted
2025/10/09 02:43:15 DEBUG : RCLONE_TEST.rclone_chunk.002_ul7ok2: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:15 INFO  : RCLONE_TEST.rclone_chunk.002_ul7ok2: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:15 INFO  : RCLONE_TEST.rclone_chunk.002_ul7ok2: Deleted
2025/10/09 02:43:16 DEBUG : file4.txt.rclone_chunk.001_ul7o76: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : file4.txt.rclone_chunk.001_ul7o76: Copied (server-side copy) to: file4.txt
2025/10/09 02:43:16 DEBUG : subdirX/file20.txt.rclone_chunk.001_ul7o51: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdirX/file20.txt.rclone_chunk.001_ul7o51: Copied (server-side copy) to: subdirX/file20.txt
2025/10/09 02:43:16 INFO  : file4.txt.rclone_chunk.001_ul7o76: Deleted
2025/10/09 02:43:16 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : file4.txt: Copied (new)
2025/10/09 02:43:16 DEBUG : RCLONE_TEST.rclone_chunk.003_ul7ok2: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:16 INFO  : RCLONE_TEST.rclone_chunk.003_ul7ok2: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 INFO  : subdirX/file20.txt.rclone_chunk.001_ul7o51: Deleted
2025/10/09 02:43:16 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdirX/file20.txt: Copied (new)
2025/10/09 02:43:16 INFO  : RCLONE_TEST.rclone_chunk.003_ul7ok2: Deleted
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.001_ul7oe7: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul7oe7: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul7oe7: Deleted
2025/10/09 02:43:16 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:16 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:43:16 DEBUG : subdir-not/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.002_ul7oe7: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul7oe7: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul7oe7: Deleted
2025/10/09 02:43:16 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.003_ul7oe7: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul7oe7: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7pmt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7pmt: Copied (server-side copy) to: subdirX/subdirX1/file30.txt
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul7oe7: Deleted
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7pmt: Deleted
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/09 02:43:16 DEBUG : subdir-not/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7p75: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7p75: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7p75: Deleted
2025/10/09 02:43:16 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:16 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/09 02:43:16 DEBUG : subdir/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7p75: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7p75: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:16 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7psx: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:16 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7psx: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7p75: Deleted
2025/10/09 02:43:16 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7psx: Deleted
2025/10/09 02:43:16 DEBUG : subdir-not/file20.txt.rclone_chunk.001_ul7ph8: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdir-not/file20.txt.rclone_chunk.001_ul7ph8: Copied (server-side copy) to: subdir-not/file20.txt
2025/10/09 02:43:16 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7p75: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7p75: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:16 INFO  : subdir-not/file20.txt.rclone_chunk.001_ul7ph8: Deleted
2025/10/09 02:43:16 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:16 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/09 02:43:16 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7psx: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:16 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7psx: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:16 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7p75: Deleted
2025/10/09 02:43:16 DEBUG : subdir/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:16 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7psx: Deleted
2025/10/09 02:43:17 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:17 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/09 02:43:17 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7psx: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:17 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7psx: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:17 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7psx: Deleted
2025/10/09 02:43:17 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001_ul7pap: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul7pap: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul7pap: Deleted
2025/10/09 02:43:17 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:17 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/09 02:43:17 DEBUG : subdir/file20.txt.rclone_chunk.001_ul7pkf: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:17 INFO  : subdir/file20.txt.rclone_chunk.001_ul7pkf: Copied (server-side copy) to: subdir/file20.txt
2025/10/09 02:43:17 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002_ul7pap: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul7pap: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:17 INFO  : subdir/file20.txt.rclone_chunk.001_ul7pkf: Deleted
2025/10/09 02:43:17 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:17 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul7pap: Deleted
2025/10/09 02:43:17 DEBUG : subdir/subdirB/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:17 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003_ul7pap: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul7pap: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul7pap: Deleted
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7qsk: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7qsk: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7qsk: Deleted
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7qx5: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7qx5: Copied (server-side copy) to: subdir-not/subdir-not2/file30.txt
2025/10/09 02:43:17 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:17 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:43:17 DEBUG : subdir/subdirA/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7qx5: Deleted
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7qsk: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7qsk: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:17 DEBUG : subdir/subdirB/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7qsk: Deleted
2025/10/09 02:43:17 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7qhg: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:17 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7qhg: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:17 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7qsk: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7qsk: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:17 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7qhg: Deleted
2025/10/09 02:43:17 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7qsk: Deleted
2025/10/09 02:43:17 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7qhg: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:17 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7qhg: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:18 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7qhg: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirB/file30.txt.rclone_chunk.001_ul7qbl: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:18 INFO  : subdir/subdirB/file30.txt.rclone_chunk.001_ul7qbl: Copied (server-side copy) to: subdir/subdirB/file30.txt
2025/10/09 02:43:18 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:18 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/09 02:43:18 INFO  : subdir/subdirB/file30.txt.rclone_chunk.001_ul7qbl: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:18 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/09 02:43:18 DEBUG : subdir/subdirA/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:18 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7qhg: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:18 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7qhg: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:18 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7qkf: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7qkf: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:18 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7qhg: Deleted
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7qkf: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7qkf: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7qkf: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:18 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:18 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7qkf: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirA/file30.txt.rclone_chunk.001_ul7rlc: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/file30.txt.rclone_chunk.001_ul7rlc: Copied (server-side copy) to: subdir/subdirA/file30.txt
2025/10/09 02:43:18 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7qkf: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7qkf: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:18 INFO  : subdir/subdirA/file30.txt.rclone_chunk.001_ul7rlc: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7qkf: Deleted
2025/10/09 02:43:18 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:18 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/09 02:43:18 NOTICE: checking Path1 Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1'
2025/10/09 02:43:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 INFO  : subdir-not: Making directory
2025/10/09 02:43:19 INFO  : subdir: Making directory
2025/10/09 02:43:19 INFO  : subdirX: Making directory
2025/10/09 02:43:19 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 INFO  : subdirX/subdirX1: Making directory
2025/10/09 02:43:19 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 INFO  : subdir-not/subdir-not2: Making directory
2025/10/09 02:43:19 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 INFO  : subdir/subdirA: Making directory
2025/10/09 02:43:19 INFO  : subdir/subdirB: Making directory
2025/10/09 02:43:19 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:19 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Waiting for checks to finish
2025/10/09 02:43:19 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Waiting for transfers to finish
2025/10/09 02:43:19 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:19 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:19 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:19 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 DEBUG : file1.txt.rclone_chunk.001_ul7sbq: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file1.txt.rclone_chunk.001_ul7sbq: Copied (server-side copy) to: file1.txt
2025/10/09 02:43:20 DEBUG : file3.txt.rclone_chunk.001_ul7spq: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file3.txt.rclone_chunk.001_ul7spq: Copied (server-side copy) to: file3.txt
2025/10/09 02:43:20 DEBUG : file2.txt.rclone_chunk.001_ul7ssu: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file2.txt.rclone_chunk.001_ul7ssu: Copied (server-side copy) to: file2.txt
2025/10/09 02:43:20 INFO  : file1.txt.rclone_chunk.001_ul7sbq: Deleted
2025/10/09 02:43:20 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file1.txt: Copied (new)
2025/10/09 02:43:20 INFO  : file3.txt.rclone_chunk.001_ul7spq: Deleted
2025/10/09 02:43:20 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file3.txt: Copied (new)
2025/10/09 02:43:20 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 INFO  : file2.txt.rclone_chunk.001_ul7ssu: Deleted
2025/10/09 02:43:20 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file2.txt: Copied (new)
2025/10/09 02:43:20 DEBUG : subdirX/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 DEBUG : subdirX/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 DEBUG : RCLONE_TEST.rclone_chunk.001_ul7sab: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.001_ul7sab: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.001_ul7sab: Deleted
2025/10/09 02:43:20 DEBUG : RCLONE_TEST.rclone_chunk.002_ul7sab: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.002_ul7sab: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.002_ul7sab: Deleted
2025/10/09 02:43:20 DEBUG : file4.txt.rclone_chunk.001_ul7t0q: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file4.txt.rclone_chunk.001_ul7t0q: Copied (server-side copy) to: file4.txt
2025/10/09 02:43:20 DEBUG : subdirX/file20.txt.rclone_chunk.001_ul7tbg: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : subdirX/file20.txt.rclone_chunk.001_ul7tbg: Copied (server-side copy) to: subdirX/file20.txt
2025/10/09 02:43:20 INFO  : file4.txt.rclone_chunk.001_ul7t0q: Deleted
2025/10/09 02:43:20 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : file4.txt: Copied (new)
2025/10/09 02:43:20 DEBUG : RCLONE_TEST.rclone_chunk.003_ul7sab: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.003_ul7sab: Copied (server-side copy) to: RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:20 DEBUG : subdirX/subdirX1/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 INFO  : subdirX/file20.txt.rclone_chunk.001_ul7tbg: Deleted
2025/10/09 02:43:20 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : subdirX/file20.txt: Copied (new)
2025/10/09 02:43:20 INFO  : RCLONE_TEST.rclone_chunk.003_ul7sab: Deleted
2025/10/09 02:43:20 DEBUG : subdirX/subdirX1/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.001_ul7tz0: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul7tz0: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul7tz0: Deleted
2025/10/09 02:43:20 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:20 INFO  : RCLONE_TEST: Copied (new)
2025/10/09 02:43:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.002_ul7tz0: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul7tz0: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:20 DEBUG : subdir-not/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul7tz0: Deleted
2025/10/09 02:43:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.003_ul7tz0: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul7tz0: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:20 DEBUG : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7t81: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7t81: Copied (server-side copy) to: subdirX/subdirX1/file30.txt
2025/10/09 02:43:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul7tz0: Deleted
2025/10/09 02:43:20 INFO  : subdirX/subdirX1/file30.txt.rclone_chunk.001_ul7t81: Deleted
2025/10/09 02:43:20 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:20 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/09 02:43:21 DEBUG : subdir-not/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7tro: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7tro: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001_ul7tro: Deleted
2025/10/09 02:43:21 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:21 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/09 02:43:21 DEBUG : subdir/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7tro: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7tro: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002_ul7tro: Deleted
2025/10/09 02:43:21 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7tro: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7tro: Copied (server-side copy) to: subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:21 DEBUG : subdir-not/file20.txt.rclone_chunk.001_ul7u0p: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:21 INFO  : subdir-not/file20.txt.rclone_chunk.001_ul7u0p: Copied (server-side copy) to: subdir-not/file20.txt
2025/10/09 02:43:21 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7tvg: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7tvg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003_ul7tro: Deleted
2025/10/09 02:43:21 INFO  : subdir-not/file20.txt.rclone_chunk.001_ul7u0p: Deleted
2025/10/09 02:43:21 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:21 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul7tvg: Deleted
2025/10/09 02:43:21 DEBUG : subdir/file20.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:21 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/09 02:43:21 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7tvg: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7tvg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:21 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul7tvg: Deleted
2025/10/09 02:43:21 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001_ul7uuc: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul7uuc: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul7uuc: Deleted
2025/10/09 02:43:21 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7tvg: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7tvg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul7tvg: Deleted
2025/10/09 02:43:21 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002_ul7uuc: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul7uuc: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:21 DEBUG : subdir/file20.txt.rclone_chunk.001_ul7uv1: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:21 INFO  : subdir/file20.txt.rclone_chunk.001_ul7uv1: Copied (server-side copy) to: subdir/file20.txt
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul7uuc: Deleted
2025/10/09 02:43:21 INFO  : subdir/file20.txt.rclone_chunk.001_ul7uv1: Deleted
2025/10/09 02:43:21 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:21 INFO  : subdir/file20.txt: Copied (new)
2025/10/09 02:43:21 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:21 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/09 02:43:21 DEBUG : subdir-not/subdir-not2/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003_ul7uuc: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul7uuc: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:21 DEBUG : subdir/subdirB/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:21 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul7uuc: Deleted
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7uze: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7uze: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001_ul7uze: Deleted
2025/10/09 02:43:22 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:22 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:43:22 DEBUG : subdir/subdirB/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7uod: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7uod: Copied (server-side copy) to: subdir-not/subdir-not2/file30.txt
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7uze: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7uze: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/file30.txt.rclone_chunk.001_ul7uod: Deleted
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002_ul7uze: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirA/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7uze: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7uze: Copied (server-side copy) to: subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003_ul7uze: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7u8e: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7u8e: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:22 DEBUG : subdir/subdirB/file30.txt.rclone_chunk.001_ul7vsz: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/file30.txt.rclone_chunk.001_ul7vsz: Copied (server-side copy) to: subdir/subdirB/file30.txt
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001_ul7u8e: Deleted
2025/10/09 02:43:22 INFO  : subdir/subdirB/file30.txt.rclone_chunk.001_ul7vsz: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/09 02:43:22 DEBUG : subdir/subdirA/file30.txt: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/09 02:43:22 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7u8e: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7u8e: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002_ul7u8e: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7vfp: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:22 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7vfp: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:22 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7u8e: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7u8e: Copied (server-side copy) to: subdir/subdirB/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:22 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.001_ul7vfp: Deleted
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003_ul7u8e: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirA/file30.txt.rclone_chunk.001_ul7v4o: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir/subdirA/file30.txt.rclone_chunk.001_ul7v4o: Copied (server-side copy) to: subdir/subdirA/file30.txt
2025/10/09 02:43:22 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7vfp: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:22 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7vfp: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:22 INFO  : subdir/subdirA/file30.txt.rclone_chunk.001_ul7v4o: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/09 02:43:22 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/09 02:43:22 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.002_ul7vfp: Deleted
2025/10/09 02:43:22 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:22 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/09 02:43:23 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7vfp: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:23 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7vfp: Copied (server-side copy) to: subdir/subdirA/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:23 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.003_ul7vfp: Deleted
2025/10/09 02:43:23 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:23 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/09 02:43:23 NOTICE: checking path2 Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2'
2025/10/09 02:43:24 NOTICE: (01)  : test check-access-filters
2025/10/09 02:43:24 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/10/09 02:43:24 NOTICE: (03)  : copy-file /tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/exclude-other-filtersfile.txt /tmp/024215hi/workdir/
2025/10/09 02:43:24 DEBUG : copyFile "/tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/exclude-other-filtersfile.txt" to "/tmp/024215hi/workdir/" as ""
2025/10/09 02:43:24 DEBUG : Creating backend with remote "/tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/exclude-other-filtersfile.txt"
2025/10/09 02:43:24 DEBUG : Creating backend with remote "/tmp/024215hi/workdir/"
2025/10/09 02:43:24 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/024215hi/workdir" as "exclude-other-filtersfile.txt"
2025/10/09 02:43:24 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:24 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/10/09 02:43:24 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/10/09 02:43:24 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/10/09 02:43:24 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/10/09 02:43:24 NOTICE: (05)  : bisync resync filters-file=/tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:24 DEBUG : modtime_write_test.rclone_chunk.001_ul7x09: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:24 INFO  : modtime_write_test.rclone_chunk.001_ul7x09: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:24 INFO  : modtime_write_test.rclone_chunk.001_ul7x09: Deleted
2025/10/09 02:43:27 DEBUG : modtime_write_test.rclone_chunk.001_ul7zs1: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:27 INFO  : modtime_write_test.rclone_chunk.001_ul7zs1: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:27 INFO  : modtime_write_test.rclone_chunk.001_ul7zs1: Deleted
2025/10/09 02:43:29 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:29 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:29 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:29 INFO  : Using filters file /tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:29 INFO  : Storing filters file hash to /tmp/024215hi/workdir/exclude-other-filtersfile.txt.md5
2025/10/09 02:43:29 INFO  : Copying Path2 files to Path1
2025/10/09 02:43:29 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/09 02:43:30 INFO  : There was nothing to transfer
2025/10/09 02:43:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/09 02:43:30 INFO  : There was nothing to transfer
2025/10/09 02:43:30 INFO  : Resync updating listings
2025/10/09 02:43:30 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:30 INFO  : Bisync successful
2025/10/09 02:43:30 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/10/09 02:43:30 NOTICE: (07)  : bisync check-access filters-file=/tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:30 DEBUG : modtime_write_test.rclone_chunk.001_ul83c5: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:30 INFO  : modtime_write_test.rclone_chunk.001_ul83c5: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:30 INFO  : modtime_write_test.rclone_chunk.001_ul83c5: Deleted
2025/10/09 02:43:33 DEBUG : modtime_write_test.rclone_chunk.001_ul862x: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:33 INFO  : modtime_write_test.rclone_chunk.001_ul862x: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:33 INFO  : modtime_write_test.rclone_chunk.001_ul862x: Deleted
2025/10/09 02:43:35 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:35 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:35 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:35 INFO  : Using filters file /tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:35 INFO  : Building Path1 and Path2 listings
2025/10/09 02:43:36 INFO  : Path1 checking for diffs
2025/10/09 02:43:36 INFO  : Path2 checking for diffs
2025/10/09 02:43:36 INFO  : Checking access health
2025/10/09 02:43:36 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/10/09 02:43:36 INFO  : No changes found
2025/10/09 02:43:36 INFO  : Updating listings
2025/10/09 02:43:36 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:36 INFO  : Bisync successful
2025/10/09 02:43:36 NOTICE: (08)  : copy-listings exclude-initial
2025/10/09 02:43:36 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2025/10/09 02:43:36 NOTICE: (10)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/10/09 02:43:36 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir/subdirA/"
2025/10/09 02:43:36 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir/subdirA"
2025/10/09 02:43:36 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:36 NOTICE: (11)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/10/09 02:43:36 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir-not/"
2025/10/09 02:43:36 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir-not"
2025/10/09 02:43:37 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:37 NOTICE: (12)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/10/09 02:43:37 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir-not/subdir-not2/"
2025/10/09 02:43:37 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir-not/subdir-not2"
2025/10/09 02:43:37 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:37 NOTICE: (13)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX/RCLONE_TEST
2025/10/09 02:43:37 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX/"
2025/10/09 02:43:37 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX"
2025/10/09 02:43:38 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:38 NOTICE: (14)  : test EXCLUDE - test should PASS
2025/10/09 02:43:38 NOTICE: (15)  : bisync check-access filters-file=/tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:38 DEBUG : modtime_write_test.rclone_chunk.001_ul8bq0: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:38 INFO  : modtime_write_test.rclone_chunk.001_ul8bq0: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:38 INFO  : modtime_write_test.rclone_chunk.001_ul8bq0: Deleted
2025/10/09 02:43:41 DEBUG : modtime_write_test.rclone_chunk.001_ul8d0l: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:41 INFO  : modtime_write_test.rclone_chunk.001_ul8d0l: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:41 INFO  : modtime_write_test.rclone_chunk.001_ul8d0l: Deleted
2025/10/09 02:43:43 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:43 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:43 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:43 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:43 INFO  : Using filters file /tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:43 INFO  : Building Path1 and Path2 listings
2025/10/09 02:43:44 INFO  : Path1 checking for diffs
2025/10/09 02:43:44 INFO  : Path2 checking for diffs
2025/10/09 02:43:44 INFO  : Checking access health
2025/10/09 02:43:44 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/10/09 02:43:44 INFO  : No changes found
2025/10/09 02:43:44 INFO  : Updating listings
2025/10/09 02:43:44 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:44 INFO  : Bisync successful
2025/10/09 02:43:44 NOTICE: (16)  : copy-listings exclude-pass-run
2025/10/09 02:43:44 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2025/10/09 02:43:44 NOTICE: (18)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/RCLONE_TEST
2025/10/09 02:43:44 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:44 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:44 NOTICE: (19)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir/RCLONE_TEST
2025/10/09 02:43:44 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir/"
2025/10/09 02:43:44 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir"
2025/10/09 02:43:45 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:43:45 NOTICE: (20)  : test EXCLUDE - test should ABORT
2025/10/09 02:43:45 NOTICE: (21)  : bisync check-access filters-file=/tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:45 DEBUG : modtime_write_test.rclone_chunk.001_ul8iiz: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:45 INFO  : modtime_write_test.rclone_chunk.001_ul8iiz: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:45 INFO  : modtime_write_test.rclone_chunk.001_ul8iiz: Deleted
2025/10/09 02:43:48 DEBUG : modtime_write_test.rclone_chunk.001_ul8kpp: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:48 INFO  : modtime_write_test.rclone_chunk.001_ul8kpp: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:48 INFO  : modtime_write_test.rclone_chunk.001_ul8kpp: Deleted
2025/10/09 02:43:50 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:50 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:50 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:50 INFO  : Using filters file /tmp/024215hi/workdir/exclude-other-filtersfile.txt
2025/10/09 02:43:50 INFO  : Building Path1 and Path2 listings
2025/10/09 02:43:50 INFO  : Path1 checking for diffs
2025/10/09 02:43:50 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/10/09 02:43:50 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/09 02:43:50 INFO  : Path2 checking for diffs
2025/10/09 02:43:50 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/10/09 02:43:50 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/09 02:43:50 INFO  : Checking access health
2025/10/09 02:43:50 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/10/09 02:43:50 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/10/09 02:43:50 ERROR : Bisync critical error: check file check failed
2025/10/09 02:43:50 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/09 02:43:50 NOTICE: Bisync error: bisync aborted
2025/10/09 02:43:50 NOTICE: (22)  : move-listings exclude-error-run
2025/10/09 02:43:50 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2025/10/09 02:43:50 NOTICE: (24)  : test reset to the initial state
2025/10/09 02:43:50 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/
2025/10/09 02:43:50 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/"
2025/10/09 02:43:51 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:51 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : file2.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : file1.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:51 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : file3.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : file4.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:51 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:51 DEBUG : subdirX/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:51 DEBUG : subdir/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:51 DEBUG : subdir-not/RCLONE_TEST: skip slow MD5 on source file, hashing in-transit
2025/10/09 02:43:51 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir/file20.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/': Waiting for checks to finish
2025/10/09 02:43:51 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:51 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:51 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/10/09 02:43:51 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/': Waiting for transfers to finish
2025/10/09 02:43:51 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.001_ul8oqg: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:51 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul8oqg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:51 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001_ul8oc6: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:51 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul8oc6: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:51 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.001_ul8odt: md5 = 1f5d11f24a3997002c91d8675746558f OK
2025/10/09 02:43:51 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul8odt: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.001
2025/10/09 02:43:51 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001_ul8oqg: Deleted
2025/10/09 02:43:51 INFO  : subdir/RCLONE_TEST.rclone_chunk.001_ul8oc6: Deleted
2025/10/09 02:43:51 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001_ul8odt: Deleted
2025/10/09 02:43:51 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.002_ul8oqg: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:51 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul8oqg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:51 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002_ul8oc6: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:51 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul8oc6: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:51 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.002_ul8odt: md5 = 474150b57cbc60d53b05b84320a89f9c OK
2025/10/09 02:43:51 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul8odt: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.002
2025/10/09 02:43:51 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002_ul8oqg: Deleted
2025/10/09 02:43:51 INFO  : subdir/RCLONE_TEST.rclone_chunk.002_ul8oc6: Deleted
2025/10/09 02:43:51 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002_ul8odt: Deleted
2025/10/09 02:43:51 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.003_ul8oqg: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:51 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul8oqg: Copied (server-side copy) to: subdir-not/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:51 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003_ul8oc6: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:51 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul8oc6: Copied (server-side copy) to: subdir/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:51 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.003_ul8odt: md5 = e641f8a1b253df4e844647f6e960b4e4 OK
2025/10/09 02:43:51 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul8odt: Copied (server-side copy) to: subdirX/RCLONE_TEST.rclone_chunk.003
2025/10/09 02:43:52 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003_ul8oqg: Deleted
2025/10/09 02:43:52 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003_ul8odt: Deleted
2025/10/09 02:43:52 INFO  : subdir/RCLONE_TEST.rclone_chunk.003_ul8oc6: Deleted
2025/10/09 02:43:52 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/09 02:43:52 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/09 02:43:52 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/09 02:43:52 NOTICE: (26)  : sync-dir TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/ TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/
2025/10/09 02:43:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:52 DEBUG : RCLONE_TEST: copy 3 data chunks...
2025/10/09 02:43:52 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : file3.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : file2.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : file4.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : file1.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:52 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/09 02:43:52 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: copy 3 data chunks...
2025/10/09 02:43:52 DEBUG : subdir/subdirA/RCLONE_TEST: copy 3 data chunks...
2025/10/09 02:43:52 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/': Waiting for checks to finish
2025/10/09 02:43:52 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir/file20.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/10/09 02:43:52 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/09 02:43:52 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/10/09 02:43:52 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/': Waiting for transfers to finish
2025/10/09 02:43:52 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/09 02:43:52 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (server-side copy)
2025/10/09 02:43:52 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/09 02:43:52 INFO  : subdir/subdirA/RCLONE_TEST: Copied (server-side copy)
2025/10/09 02:43:52 DEBUG : Waiting for deletions to finish
2025/10/09 02:43:52 NOTICE: (27)  : copy-file /tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/include-other-filtersfile.txt /tmp/024215hi/workdir/
2025/10/09 02:43:52 DEBUG : copyFile "/tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/include-other-filtersfile.txt" to "/tmp/024215hi/workdir/" as ""
2025/10/09 02:43:52 DEBUG : Creating backend with remote "/tmp/024215hi/datadir/test_check_access_filters-yiwopaf9/include-other-filtersfile.txt"
2025/10/09 02:43:52 DEBUG : Config file has changed externally - reloading
2025/10/09 02:43:52 DEBUG : Creating backend with remote "/tmp/024215hi/workdir/"
2025/10/09 02:43:52 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/024215hi/workdir" as "include-other-filtersfile.txt"
2025/10/09 02:43:52 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/09 02:43:52 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/10/09 02:43:52 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/10/09 02:43:52 INFO  : include-other-filtersfile.txt: Copied (new)
2025/10/09 02:43:52 NOTICE: (28)  : bisync resync filters-file=/tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:43:53 DEBUG : modtime_write_test.rclone_chunk.001_ul8qdf: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:53 INFO  : modtime_write_test.rclone_chunk.001_ul8qdf: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:53 INFO  : modtime_write_test.rclone_chunk.001_ul8qdf: Deleted
2025/10/09 02:43:55 DEBUG : modtime_write_test.rclone_chunk.001_ul8sj7: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:55 INFO  : modtime_write_test.rclone_chunk.001_ul8sj7: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:55 INFO  : modtime_write_test.rclone_chunk.001_ul8sj7: Deleted
2025/10/09 02:43:58 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:58 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:43:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:43:58 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:43:58 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:58 INFO  : Using filters file /tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:43:58 INFO  : Storing filters file hash to /tmp/024215hi/workdir/include-other-filtersfile.txt.md5
2025/10/09 02:43:58 INFO  : Copying Path2 files to Path1
2025/10/09 02:43:58 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/09 02:43:59 INFO  : There was nothing to transfer
2025/10/09 02:43:59 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/09 02:43:59 INFO  : There was nothing to transfer
2025/10/09 02:43:59 INFO  : Resync updating listings
2025/10/09 02:43:59 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:43:59 INFO  : Bisync successful
2025/10/09 02:43:59 NOTICE: (29)  : test INCLUDE - test include/exclude filters for check access
2025/10/09 02:43:59 NOTICE: (30)  : bisync check-access filters-file=/tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:43:59 DEBUG : modtime_write_test.rclone_chunk.001_ul8wdr: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:43:59 INFO  : modtime_write_test.rclone_chunk.001_ul8wdr: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:43:59 INFO  : modtime_write_test.rclone_chunk.001_ul8wdr: Deleted
2025/10/09 02:44:02 DEBUG : modtime_write_test.rclone_chunk.001_ul8zi0: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:44:02 INFO  : modtime_write_test.rclone_chunk.001_ul8zi0: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:44:02 INFO  : modtime_write_test.rclone_chunk.001_ul8zi0: Deleted
2025/10/09 02:44:04 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:04 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:44:04 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:44:04 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:44:04 INFO  : Using filters file /tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:44:04 INFO  : Building Path1 and Path2 listings
2025/10/09 02:44:05 INFO  : Path1 checking for diffs
2025/10/09 02:44:05 INFO  : Path2 checking for diffs
2025/10/09 02:44:05 INFO  : Checking access health
2025/10/09 02:44:05 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2025/10/09 02:44:05 INFO  : No changes found
2025/10/09 02:44:05 INFO  : Updating listings
2025/10/09 02:44:05 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:44:05 INFO  : Bisync successful
2025/10/09 02:44:05 NOTICE: (31)  : copy-listings include-initial
2025/10/09 02:44:05 NOTICE: (32)  : test INCLUDE - delete RCLONE_TEST files in excluded directories
2025/10/09 02:44:05 NOTICE: (33)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/10/09 02:44:05 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:05 NOTICE: (34)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/10/09 02:44:06 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:06 NOTICE: (35)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/10/09 02:44:06 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:06 NOTICE: (36)  : test INCLUDE - test should PASS
2025/10/09 02:44:06 NOTICE: (37)  : bisync check-access filters-file=/tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:44:06 DEBUG : modtime_write_test.rclone_chunk.001_ul93c5: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:44:06 INFO  : modtime_write_test.rclone_chunk.001_ul93c5: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:44:07 INFO  : modtime_write_test.rclone_chunk.001_ul93c5: Deleted
2025/10/09 02:44:09 DEBUG : modtime_write_test.rclone_chunk.001_ul969t: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:44:09 INFO  : modtime_write_test.rclone_chunk.001_ul969t: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:44:09 INFO  : modtime_write_test.rclone_chunk.001_ul969t: Deleted
2025/10/09 02:44:11 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:11 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:44:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:44:11 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:44:11 INFO  : Using filters file /tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:44:11 INFO  : Building Path1 and Path2 listings
2025/10/09 02:44:12 INFO  : Path1 checking for diffs
2025/10/09 02:44:12 INFO  : Path2 checking for diffs
2025/10/09 02:44:12 INFO  : Checking access health
2025/10/09 02:44:12 INFO  : Found 5 matching "RCLONE_TEST" files on both paths
2025/10/09 02:44:12 INFO  : No changes found
2025/10/09 02:44:12 INFO  : Updating listings
2025/10/09 02:44:12 INFO  : Validating listings for Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" vs Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:44:12 INFO  : Bisync successful
2025/10/09 02:44:12 NOTICE: (38)  : copy-listings include-pass-run
2025/10/09 02:44:12 NOTICE: (39)  : test INCLUDE - delete RCLONE_TEST files in included directories
2025/10/09 02:44:12 NOTICE: (40)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/RCLONE_TEST
2025/10/09 02:44:12 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:12 NOTICE: (41)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdir/RCLONE_TEST
2025/10/09 02:44:13 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:13 NOTICE: (42)  : delete-file TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX/subdirX1/RCLONE_TEST
2025/10/09 02:44:13 DEBUG : Creating backend with remote "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX/subdirX1/"
2025/10/09 02:44:13 DEBUG : Creating backend with remote "TestS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/subdirX/subdirX1"
2025/10/09 02:44:13 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:13 NOTICE: (43)  : test INCLUDE - test should ABORT
2025/10/09 02:44:13 NOTICE: (44)  : bisync check-access filters-file=/tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:44:14 DEBUG : modtime_write_test.rclone_chunk.001_ul9avg: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:44:14 INFO  : modtime_write_test.rclone_chunk.001_ul9avg: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:44:14 INFO  : modtime_write_test.rclone_chunk.001_ul9avg: Deleted
2025/10/09 02:44:16 DEBUG : modtime_write_test.rclone_chunk.001_ul9doc: md5 = 2237e9a79505ead7e8b4a18ecb700786 OK
2025/10/09 02:44:16 INFO  : modtime_write_test.rclone_chunk.001_ul9doc: Copied (server-side copy) to: modtime_write_test
2025/10/09 02:44:16 INFO  : modtime_write_test.rclone_chunk.001_ul9doc: Deleted
2025/10/09 02:44:18 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:18 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2': Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/09 02:44:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/09 02:44:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/09 02:44:18 INFO  : Synching Path1 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path1/" with Path2 "TestChunkerChunk50bS3:rclone-test-bipagug7dami/024215hi/check_access_filters/path2/"
2025/10/09 02:44:18 INFO  : Using filters file /tmp/024215hi/workdir/include-other-filtersfile.txt
2025/10/09 02:44:18 INFO  : Building Path1 and Path2 listings
2025/10/09 02:44:19 INFO  : Path1 checking for diffs
2025/10/09 02:44:19 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/10/09 02:44:19 INFO  : - Path1    File was deleted          - subdirX/subdirX1/RCLONE_TEST
2025/10/09 02:44:19 INFO  : Path1:    2 changes:    0 new,    0 modified,    2 deleted
2025/10/09 02:44:19 INFO  : Path2 checking for diffs
2025/10/09 02:44:19 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/10/09 02:44:19 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/09 02:44:19 INFO  : Checking access health
2025/10/09 02:44:19 ERROR : Access test failed: Path1 count 3, Path2 count 4 - RCLONE_TEST
2025/10/09 02:44:19 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/10/09 02:44:19 ERROR : -          Access test failed: Path2 file not found in Path1 - subdirX/subdirX1/RCLONE_TEST
2025/10/09 02:44:19 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/10/09 02:44:19 ERROR : Bisync critical error: check file check failed
2025/10/09 02:44:19 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/09 02:44:19 NOTICE: Bisync error: bisync aborted
2025/10/09 02:44:19 NOTICE: (45)  : move-listings include-error-run
2025/10/09 02:44:19 DEBUG : Waiting for deletions to finish
2025/10/09 02:44:19 DEBUG : "RCLONE_TEST" version false
2025/10/09 02:44:19 DEBUG : RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:19 DEBUG : RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:19 DEBUG : RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:19 DEBUG : RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "file1.txt" version false
2025/10/09 02:44:19 DEBUG : file1.txt: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "file2.txt" version false
2025/10/09 02:44:19 DEBUG : file2.txt: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "file3.txt" version false
2025/10/09 02:44:19 DEBUG : file3.txt: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "file4.txt" version false
2025/10/09 02:44:19 DEBUG : file4.txt: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "subdir-not/file20.txt" version false
2025/10/09 02:44:19 DEBUG : subdir-not/file20.txt: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "subdir-not/subdir-not2/RCLONE_TEST" version false
2025/10/09 02:44:19 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:19 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:19 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:19 DEBUG : "subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:19 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:19 INFO  : RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:19 DEBUG : "subdir-not/subdir-not2/file30.txt" version false
2025/10/09 02:44:19 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "")
2025/10/09 02:44:19 INFO  : file4.txt: Deleted
2025/10/09 02:44:19 DEBUG : "subdir/file20.txt" version false
2025/10/09 02:44:19 DEBUG : subdir/file20.txt: Deleting (id "")
2025/10/09 02:44:19 INFO  : file2.txt: Deleted
2025/10/09 02:44:19 DEBUG : "subdir/subdirA/RCLONE_TEST" version false
2025/10/09 02:44:19 DEBUG : subdir/subdirA/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:19 INFO  : file3.txt: Deleted
2025/10/09 02:44:19 DEBUG : "subdir/subdirA/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:19 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 INFO  : RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirA/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/subdirA/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirA/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 INFO  : file1.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirA/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirA/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : RCLONE_TEST: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/file20.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 INFO  : RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/file20.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirA/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/file30.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/file20.txt" version false
2025/10/09 02:44:20 DEBUG : subdirX/file20.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirA/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirA/file30.txt: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/file30.txt: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdirX/file20.txt: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/file30.txt: Deleted
2025/10/09 02:44:20 DEBUG : Waiting for deletions to finish
2025/10/09 02:44:20 DEBUG : "file1.txt" version false
2025/10/09 02:44:20 DEBUG : file1.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "file2.txt" version false
2025/10/09 02:44:20 DEBUG : file2.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "file3.txt" version false
2025/10/09 02:44:20 DEBUG : file3.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "file4.txt" version false
2025/10/09 02:44:20 DEBUG : file4.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdir-not/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdir-not/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/file20.txt" version false
2025/10/09 02:44:20 DEBUG : subdir-not/file20.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir-not/subdir-not2/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdir-not/subdir-not2/file30.txt: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdir/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : file1.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdir/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 INFO  : file2.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/file20.txt" version false
2025/10/09 02:44:20 DEBUG : subdir/file20.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : file4.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirA/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirA/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : file3.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdir-not/subdir-not2/file30.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdir/subdirB/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdir/subdirB/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir-not/file20.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/RCLONE_TEST: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdirX/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/file20.txt" version false
2025/10/09 02:44:20 DEBUG : subdirX/file20.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/RCLONE_TEST" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/RCLONE_TEST: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/file20.txt: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirA/file30.txt: Deleted
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003: Deleting (id "")
2025/10/09 02:44:20 DEBUG : "subdirX/subdirX1/file30.txt" version false
2025/10/09 02:44:20 DEBUG : subdirX/subdirX1/file30.txt: Deleting (id "")
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdir/subdirB/file30.txt: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 INFO  : subdirX/file20.txt: Deleted
2025/10/09 02:44:20 INFO  : subdirX/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/RCLONE_TEST: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.001: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.002: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/RCLONE_TEST.rclone_chunk.003: Deleted
2025/10/09 02:44:20 INFO  : subdirX/subdirX1/file30.txt: Deleted
    bisync_test.go:608: TEST check_access_filters PASSED
2025/10/09 02:44:20 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-bipagug7dami': Purge remote
2025/10/09 02:44:20 DEBUG : S3 bucket rclone-test-bipagug7dami: bucket is versioned: false
2025/10/09 02:44:20 DEBUG : Waiting for deletions to finish
2025/10/09 02:44:20 INFO  : S3 bucket rclone-test-bipagug7dami: Bucket "rclone-test-bipagug7dami" deleted
--- PASS: TestBisyncRemoteRemote (125.21s)
    --- PASS: TestBisyncRemoteRemote/check_access (58.77s)
    --- PASS: TestBisyncRemoteRemote/check_access_filters (65.95s)
PASS
2025/10/09 02:44:20 DEBUG : Chunked 'TestChunkerChunk50bS3:rclone-test-jifetif6nabe': Purge remote
2025/10/09 02:44:21 ERROR : S3 bucket rclone-test-jifetif6nabe: Failed to read versioning status, assuming unversioned: operation error S3: GetBucketVersioning, https response error StatusCode: 404, RequestID: WP383AQTGC5C2AGX, HostID: 5+khvAxgUS3VGpxQysjr7nRxQ8TLeQfdUmlY/66OBihYjqdVc/622hLWBo5p/XhkyH3ohFQ8Soy2DiFoUfiF5JBnxWNDLWzQz7r7xVwpk5M=, api error NoSuchBucket: The specified bucket does not exist
2025/10/09 02:44:21 DEBUG : S3 bucket rclone-test-jifetif6nabe: bucket is versioned: false
2025/10/09 02:44:21 DEBUG : Waiting for deletions to finish
2025/10/09 02:44:21 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bS3: -verbose -size-limit 1024 -test.run '^TestBisyncRemoteRemote$/^(check_access|check_access_filters)$'" - Finished OK in 2m5.519346961s (try 2/5)