"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/12/24 04:07:34 DEBUG : Creating backend with remote "TestB2:rclone-test-movixaq2noti"
2025/12/24 04:07:34 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/12/24 04:07:34 DEBUG : Creating backend with remote "/tmp/rclone17799679"
=== RUN   TestBisyncLocalRemote
2025/12/24 04:07:34 DEBUG : Creating backend with remote "TestB2:rclone-test-coxokum9qiza"
2025/12/24 04:07:35 NOTICE: remote: TestB2:rclone-test-coxokum9qiza
=== RUN   TestBisyncLocalRemote/check_access_filters
2025/12/24 04:07:35 DEBUG : Creating backend with remote "/tmp/040735ka"
2025/12/24 04:07:35 DEBUG : Creating backend with remote "/tmp/040735ka/check_access_filters/path1"
2025/12/24 04:07:35 DEBUG : Creating backend with remote "TestB2:rclone-test-coxokum9qiza/040735ka"
2025/12/24 04:07:36 INFO  : path2: Making directory
2025/12/24 04:07:37 DEBUG : Creating backend with remote "TestB2:rclone-test-coxokum9qiza/040735ka/check_access_filters/path2"
2025/12/24 04:07:38 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/12/24 04:07:38 DEBUG : Creating backend with remote "/tmp/040735ka/initdir/test_check_access_filters-kuqiniq9"
2025/12/24 04:07:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir-not: Making directory with metadata
2025/12/24 04:07:38 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/12/24 04:07:38 DEBUG : subdir: Making directory with metadata
2025/12/24 04:07:38 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:07:38 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:38 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/12/24 04:07:38 DEBUG : subdirX: Making directory with metadata
2025/12/24 04:07:38 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/12/24 04:07:38 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/12/24 04:07:38 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/12/24 04:07:38 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/12/24 04:07:38 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir/subdirA: Making directory with metadata
2025/12/24 04:07:38 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/12/24 04:07:38 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/12/24 04:07:38 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/12/24 04:07:38 DEBUG : subdir/subdirB: Making directory with metadata
2025/12/24 04:07:38 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:38 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/12/24 04:07:38 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:07:38 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/12/24 04:07:38 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : Local file system at /tmp/040735ka/initdir/test_check_access_filters-kuqiniq9: Waiting for checks to finish
2025/12/24 04:07:38 DEBUG : Local file system at /tmp/040735ka/initdir/test_check_access_filters-kuqiniq9: Waiting for transfers to finish
2025/12/24 04:07:38 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/12/24 04:07:38 INFO  : subdirX/file20.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/12/24 04:07:38 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/12/24 04:07:38 INFO  : subdir-not/file20.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/12/24 04:07:38 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/12/24 04:07:38 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/12/24 04:07:38 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:38 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/12/24 04:07:38 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:38 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/12/24 04:07:38 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/12/24 04:07:38 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 INFO  : subdir: Set directory modification time (using SetModTime)
2025/12/24 04:07:38 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/12/24 04:07:38 DEBUG : Creating backend with remote "/tmp/040735ka/datadir/test_check_access_filters-jisinup5"
2025/12/24 04:07:38 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:38 DEBUG : Local file system at /tmp/040735ka/datadir/test_check_access_filters-jisinup5: Waiting for checks to finish
2025/12/24 04:07:38 DEBUG : Local file system at /tmp/040735ka/datadir/test_check_access_filters-jisinup5: Waiting for transfers to finish
2025/12/24 04:07:38 DEBUG : include-other-filtersfile.txt.8126a844.partial: size = 178 OK
2025/12/24 04:07:38 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2025/12/24 04:07:38 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/12/24 04:07:38 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/12/24 04:07:38 INFO  : include-other-filtersfile.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/12/24 04:07:38 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/12/24 04:07:38 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/12/24 04:07:38 DEBUG : Waiting for deletions to finish
2025/12/24 04:07:38 ERROR : error listing: directory not found
2025/12/24 04:07:38 ERROR : Local file system at /tmp/040735ka/check_access_filters/path1: Failed to list "": directory not found
2025/12/24 04:07:38 DEBUG : removing 1 level 0 directories
2025/12/24 04:07:38 INFO  : Local file system at /tmp/040735ka/check_access_filters/path1: Removing directory
2025/12/24 04:07:38 ERROR : Failed to rmdir: stat /tmp/040735ka/check_access_filters/path1: no such file or directory
2025/12/24 04:07:38 INFO  : B2 bucket rclone-test-coxokum9qiza path 040735ka/check_access_filters/path2: cleaning bucket "rclone-test-coxokum9qiza" of all files
2025/12/24 04:07:39 ERROR : error listing: directory not found
2025/12/24 04:07:39 NOTICE: checking initFs Local file system at /tmp/040735ka/initdir/test_check_access_filters-kuqiniq9
2025/12/24 04:07:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir-not: Making directory with metadata
2025/12/24 04:07:39 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/12/24 04:07:39 DEBUG : subdir: Making directory with metadata
2025/12/24 04:07:39 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/12/24 04:07:39 DEBUG : subdirX: Making directory with metadata
2025/12/24 04:07:39 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/12/24 04:07:39 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/12/24 04:07:39 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/12/24 04:07:39 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:07:39 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirA: Making directory with metadata
2025/12/24 04:07:39 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/12/24 04:07:39 DEBUG : subdir/subdirB: Making directory with metadata
2025/12/24 04:07:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/12/24 04:07:39 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:07:39 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/12/24 04:07:39 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdirX/file20.txt.0c4add8a.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/12/24 04:07:39 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdirX/file20.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/12/24 04:07:39 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: size = 109 OK
2025/12/24 04:07:39 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:07:39 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/12/24 04:07:39 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/12/24 04:07:39 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/12/24 04:07:39 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/12/24 04:07:39 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : Local file system at /tmp/040735ka/check_access_filters/path1: Waiting for checks to finish
2025/12/24 04:07:39 DEBUG : Local file system at /tmp/040735ka/check_access_filters/path1: Waiting for transfers to finish
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/12/24 04:07:39 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/12/24 04:07:39 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: size = 0 OK
2025/12/24 04:07:39 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/12/24 04:07:39 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/12/24 04:07:39 INFO  : subdir-not/file20.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/12/24 04:07:39 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir/subdirA/file30.txt.03485624.partial: size = 0 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: size = 109 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/12/24 04:07:39 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:39 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/12/24 04:07:39 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/12/24 04:07:39 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 INFO  : subdir: Set directory modification time (using SetModTime)
2025/12/24 04:07:39 NOTICE: checking Path1 Local file system at /tmp/040735ka/check_access_filters/path1
2025/12/24 04:07:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdir-not: Making directory
2025/12/24 04:07:39 INFO  : subdir: Making directory
2025/12/24 04:07:39 INFO  : subdirX: Making directory
2025/12/24 04:07:39 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdirX/subdirX1: Making directory
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdir-not/subdir-not2: Making directory
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 INFO  : subdir/subdirA: Making directory
2025/12/24 04:07:39 INFO  : subdir/subdirB: Making directory
2025/12/24 04:07:39 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:39 DEBUG : B2 bucket rclone-test-coxokum9qiza path 040735ka/check_access_filters/path2: Waiting for checks to finish
2025/12/24 04:07:39 DEBUG : B2 bucket rclone-test-coxokum9qiza path 040735ka/check_access_filters/path2: Waiting for transfers to finish
2025/12/24 04:07:40 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:07:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:40 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:40 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:07:40 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:40 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:40 DEBUG : file3.txt: size = 0 OK
2025/12/24 04:07:40 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:40 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:40 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:07:40 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:40 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:40 DEBUG : subdirX/file20.txt: size = 0 OK
2025/12/24 04:07:40 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:40 INFO  : subdirX/file20.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : file4.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdirX/subdirX1/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdirX/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdirX/subdirX1/file30.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir-not/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir-not/file20.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdir-not/file20.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir-not/subdir-not2/file30.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir/subdirB/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:41 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:41 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir/file20.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir/subdirA/file30.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/12/24 04:07:41 DEBUG : subdir/subdirB/file30.txt: size = 0 OK
2025/12/24 04:07:41 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:41 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/12/24 04:07:42 DEBUG : subdir/subdirA/RCLONE_TEST: size = 109 OK
2025/12/24 04:07:42 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:42 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/12/24 04:07:42 NOTICE: checking path2 B2 bucket rclone-test-coxokum9qiza path 040735ka/check_access_filters/path2
2025/12/24 04:07:42 NOTICE: (01)  : test check-access-filters
2025/12/24 04:07:42 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/12/24 04:07:42 NOTICE: (03)  : copy-file /tmp/040735ka/datadir/test_check_access_filters-jisinup5/exclude-other-filtersfile.txt /tmp/040735ka/workdir/
2025/12/24 04:07:42 DEBUG : copyFile "/tmp/040735ka/datadir/test_check_access_filters-jisinup5/exclude-other-filtersfile.txt" to "/tmp/040735ka/workdir/" as ""
2025/12/24 04:07:42 DEBUG : Creating backend with remote "/tmp/040735ka/datadir/test_check_access_filters-jisinup5/exclude-other-filtersfile.txt"
2025/12/24 04:07:42 DEBUG : Creating backend with remote "/tmp/040735ka/workdir/"
2025/12/24 04:07:42 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/040735ka/workdir" as "exclude-other-filtersfile.txt"
2025/12/24 04:07:42 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:42 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: size = 173 OK
2025/12/24 04:07:42 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/12/24 04:07:42 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/12/24 04:07:42 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/12/24 04:07:42 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/12/24 04:07:42 NOTICE: (05)  : bisync resync filters-file=/tmp/040735ka/workdir/exclude-other-filtersfile.txt
2025/12/24 04:07:45 INFO  : subdir: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/12/24 04:07:45 DEBUG : B2 bucket rclone-test-coxokum9qiza path 040735ka/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:07:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:07:45 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/12/24 04:07:45 INFO  : Synching Path1 "/tmp/040735ka/check_access_filters/path1/" with Path2 "TestB2:rclone-test-coxokum9qiza/040735ka/check_access_filters/path2/"
2025/12/24 04:07:45 INFO  : Using filters file /tmp/040735ka/workdir/exclude-other-filtersfile.txt
2025/12/24 04:07:45 INFO  : Storing filters file hash to /tmp/040735ka/workdir/exclude-other-filtersfile.txt.md5
2025/12/24 04:07:45 INFO  : Copying Path2 files to Path1
2025/12/24 04:07:45 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:07:46 INFO  : There was nothing to transfer
2025/12/24 04:07:46 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:07:46 INFO  : There was nothing to transfer
2025/12/24 04:07:46 INFO  : Resync updating listings
2025/12/24 04:07:46 INFO  : Validating listings for Path1 "/tmp/040735ka/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-coxokum9qiza/040735ka/check_access_filters/path2/"
2025/12/24 04:07:46 INFO  : Bisync successful
2025/12/24 04:07:46 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/12/24 04:07:46 NOTICE: (07)  : bisync check-access filters-file=/tmp/040735ka/workdir/exclude-other-filtersfile.txt
2025/12/24 04:07:46 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1185-07.backblaze.com/b2api/v1/b2_upload_file/df227da380ad4beb9fb80e1b/c001_v0001185_t0037": EOF
2025/12/24 04:07:46 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1185-07.backblaze.com/b2api/v1/b2_upload_file/df227da380ad4beb9fb80e1b/c001_v0001185_t0037": EOF)
2025/12/24 04:07:46 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    bisync_test.go:1025: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1025
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1046
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/usr/local/go/src/strings/iter.go:61
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	Post "https://pod-000-1185-07.backblaze.com/b2api/v1/b2_upload_file/df227da380ad4beb9fb80e1b/c001_v0001185_t0037": EOF
        	Test:       	TestBisyncLocalRemote/check_access_filters
2025/12/24 04:07:46 DEBUG : B2 bucket rclone-test-coxokum9qiza: Purge remote
2025/12/24 04:07:46 INFO  : B2 bucket rclone-test-coxokum9qiza: cleaning bucket "rclone-test-coxokum9qiza" of all files
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f10326d02572dbd93_d20251224_m040740_c001_v0001185_t0027_u01766549260779")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/file1.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f11553c43253ab3ab_d20251224_m040740_c001_v0001167_t0055_u01766549260248")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/file2.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f100cd420b4eefd21_d20251224_m040740_c001_v0001136_t0001_u01766549260448")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/file3.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f04_d20251224_m040740_c001_v0001185_t0012_u01766549260619")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/file4.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f100cd420b4eefd25_d20251224_m040740_c001_v0001136_t0049_u01766549260749")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/modtime_write_test: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f401ef7717c40ea52_d20251224_m040745_c001_v7007000_t0000_u01766549265646")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/modtime_write_test: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f111e9542f2fb9e95_d20251224_m040744_c001_v0001167_t0035_u01766549264209")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/modtime_write_test: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f10326d02572dbdfc_d20251224_m040742_c001_v0001185_t0058_u01766549262317")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/subdir-not/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f100cd420b4eefd27_d20251224_m040741_c001_v0001136_t0015_u01766549261122")
2025/12/24 04:07:47 DEBUG : 040735ka/check_access_filters/path2/subdir-not/file20.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f10326d02572dbdae_d20251224_m040741_c001_v0001185_t0019_u01766549261168")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f11553c43253ab3d3_d20251224_m040741_c001_v0001167_t0047_u01766549261193")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir-not/subdir-not2/file30.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f33_d20251224_m040741_c001_v0001185_t0040_u01766549261301")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f100cd420b4eefd29_d20251224_m040741_c001_v0001136_t0001_u01766549261446")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/file20.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f10326d02572dbdc8_d20251224_m040741_c001_v0001185_t0034_u01766549261551")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f100cd420b4eefd2b_d20251224_m040741_c001_v0001136_t0056_u01766549261765")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/subdirA/file30.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f52_d20251224_m040741_c001_v0001185_t0024_u01766549261776")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/subdirB/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f44_d20251224_m040741_c001_v0001185_t0028_u01766549261570")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdir/subdirB/file30.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f11553c43253ab3e3_d20251224_m040741_c001_v0001167_t0005_u01766549261601")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdirX/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f11553c43253ab3c1_d20251224_m040740_c001_v0001167_t0022_u01766549260770")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdirX/file20.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f11_d20251224_m040740_c001_v0001185_t0002_u01766549260821")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f10326d02572dbd9b_d20251224_m040740_c001_v0001185_t0000_u01766549260982")
2025/12/24 04:07:48 DEBUG : 040735ka/check_access_filters/path2/subdirX/subdirX1/file30.txt: Deleting (id "4_zdf227da380ad4beb9fb80e1b_f107fd83a9fdd8f25_d20251224_m040741_c001_v0001185_t0024_u01766549261040")
--- FAIL: TestBisyncLocalRemote (14.66s)
    --- FAIL: TestBisyncLocalRemote/check_access_filters (11.52s)
=== RUN   TestBisyncRemoteRemote
2025/12/24 04:07:49 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki"
2025/12/24 04:07:50 NOTICE: remote: TestB2:rclone-test-maqiciw1feki
=== RUN   TestBisyncRemoteRemote/resync
2025/12/24 04:07:50 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me"
2025/12/24 04:07:51 INFO  : path1: Making directory
2025/12/24 04:07:51 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1"
2025/12/24 04:07:53 INFO  : path2: Making directory
2025/12/24 04:07:53 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2"
2025/12/24 04:07:54 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/12/24 04:07:54 DEBUG : Creating backend with remote "/tmp/040750me/initdir/test_resync-deqakuk9"
2025/12/24 04:07:54 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:07:54 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/12/24 04:07:54 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : Local file system at /tmp/040750me/initdir/test_resync-deqakuk9: Waiting for checks to finish
2025/12/24 04:07:54 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:07:54 DEBUG : Local file system at /tmp/040750me/initdir/test_resync-deqakuk9: Waiting for transfers to finish
2025/12/24 04:07:54 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/12/24 04:07:54 INFO  : file5.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:07:54 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:07:54 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:54 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/12/24 04:07:54 INFO  : file6.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/12/24 04:07:54 INFO  : file7.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/12/24 04:07:54 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:07:54 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/12/24 04:07:54 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/12/24 04:07:54 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/12/24 04:07:54 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7"
2025/12/24 04:07:54 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:54 DEBUG : Local file system at /tmp/040750me/datadir/test_resync-jeruqat7: Waiting for checks to finish
2025/12/24 04:07:54 DEBUG : Local file system at /tmp/040750me/datadir/test_resync-jeruqat7: Waiting for transfers to finish
2025/12/24 04:07:54 DEBUG : fileA.txt.04c13af3.partial: size = 19 OK
2025/12/24 04:07:54 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:07:54 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/12/24 04:07:54 INFO  : fileA.txt: Copied (new)
2025/12/24 04:07:54 DEBUG : fileB.txt.b8ec97e6.partial: size = 19 OK
2025/12/24 04:07:54 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:07:54 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/12/24 04:07:54 INFO  : fileB.txt: Copied (new)
2025/12/24 04:07:54 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:07:54 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:07:55 NOTICE: checking initFs Local file system at /tmp/040750me/initdir/test_resync-deqakuk9
2025/12/24 04:07:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:56 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Waiting for checks to finish
2025/12/24 04:07:56 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Waiting for transfers to finish
2025/12/24 04:07:57 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file5.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file5.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file3.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file4.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file6.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file6.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : file7.txt: size = 0 OK
2025/12/24 04:07:57 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:57 INFO  : file7.txt: Copied (new)
2025/12/24 04:07:57 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:07:57 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:57 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:57 NOTICE: checking Path1 B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1
2025/12/24 04:07:58 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:07:58 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Waiting for checks to finish
2025/12/24 04:07:58 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Waiting for transfers to finish
2025/12/24 04:07:59 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file1.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : file3.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file3.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : file4.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file4.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : file6.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file6.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : file5.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file5.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : file7.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file7.txt: Copied (new)
2025/12/24 04:07:59 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:07:59 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:07:59 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:07:59 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:07:59 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:07:59 INFO  : file2.txt: Copied (new)
2025/12/24 04:07:59 NOTICE: checking path2 B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2
2025/12/24 04:08:00 NOTICE: (01)  : test resync
2025/12/24 04:08:00 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/12/24 04:08:00 NOTICE: (03)  : purge-children TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/
2025/12/24 04:08:00 DEBUG : RCLONE_TEST: Remove file
2025/12/24 04:08:00 DEBUG : file1.txt: Remove file
2025/12/24 04:08:00 DEBUG : file2.txt: Remove file
2025/12/24 04:08:00 DEBUG : file3.txt: Remove file
2025/12/24 04:08:00 DEBUG : file4.txt: Remove file
2025/12/24 04:08:01 DEBUG : file5.txt: Remove file
2025/12/24 04:08:01 DEBUG : file6.txt: Remove file
2025/12/24 04:08:01 DEBUG : file7.txt: Remove file
2025/12/24 04:08:01 NOTICE: (04)  : bisync resync
2025/12/24 04:08:09 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:09 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:08:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:08:09 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:09 INFO  : Copying Path2 files to Path1
2025/12/24 04:08:09 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:08:10 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file2.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file3.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file4.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file5.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file6.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : file7.txt: Copied (server-side copy)
2025/12/24 04:08:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:08:11 INFO  : Resync updating listings
2025/12/24 04:08:11 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:11 INFO  : Bisync successful
2025/12/24 04:08:11 NOTICE: (05)  : move-listings empty-path1
2025/12/24 04:08:11 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/12/24 04:08:11 NOTICE: (07)  : purge-children TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/
2025/12/24 04:08:11 DEBUG : RCLONE_TEST: Remove file
2025/12/24 04:08:11 DEBUG : file1.txt: Remove file
2025/12/24 04:08:11 DEBUG : file2.txt: Remove file
2025/12/24 04:08:11 DEBUG : file3.txt: Remove file
2025/12/24 04:08:12 DEBUG : file4.txt: Remove file
2025/12/24 04:08:12 DEBUG : file5.txt: Remove file
2025/12/24 04:08:12 DEBUG : file6.txt: Remove file
2025/12/24 04:08:12 DEBUG : file7.txt: Remove file
2025/12/24 04:08:12 NOTICE: (08)  : bisync resync
2025/12/24 04:08:20 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:20 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:20 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:08:20 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:08:20 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:20 INFO  : Copying Path2 files to Path1
2025/12/24 04:08:20 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:08:20 INFO  : There was nothing to transfer
2025/12/24 04:08:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:08:21 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file2.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file3.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file4.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file6.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file7.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : file5.txt: Copied (server-side copy)
2025/12/24 04:08:21 INFO  : Resync updating listings
2025/12/24 04:08:21 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:21 INFO  : Bisync successful
2025/12/24 04:08:21 NOTICE: (09)  : move-listings empty-path2
2025/12/24 04:08:21 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/12/24 04:08:21 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/040750me/datadir/test_resync-jeruqat7/ fileA.txt
2025/12/24 04:08:21 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/"
2025/12/24 04:08:21 DEBUG : fs cache: renaming cache item "/tmp/040750me/datadir/test_resync-jeruqat7/" to be canonical "/tmp/040750me/datadir/test_resync-jeruqat7"
2025/12/24 04:08:21 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/12/24 04:08:21 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/040750me/datadir/test_resync-jeruqat7/ fileB.txt
2025/12/24 04:08:21 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync-jeruqat7/" for canonical name "/tmp/040750me/datadir/test_resync-jeruqat7"
2025/12/24 04:08:21 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/12/24 04:08:21 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/12/24 04:08:21 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/12/24 04:08:21 NOTICE: (15)  : delete-file TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/file1.txt
2025/12/24 04:08:21 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:22 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/" to be canonical "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2"
2025/12/24 04:08:23 INFO  : file1.txt: Deleted
2025/12/24 04:08:23 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/12/24 04:08:23 NOTICE: (17)  : delete-file TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/file2.txt
2025/12/24 04:08:23 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/"
2025/12/24 04:08:24 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" to be canonical "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1"
2025/12/24 04:08:24 INFO  : file2.txt: Deleted
2025/12/24 04:08:24 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/12/24 04:08:24 NOTICE: (19)  : copy-as /tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/ file3.txt
2025/12/24 04:08:24 DEBUG : copyFile "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/" as "file3.txt"
2025/12/24 04:08:24 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt"
2025/12/24 04:08:24 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:26 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2" as "file3.txt"
2025/12/24 04:08:26 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/040750me/datadir/test_resync-jeruqat7)
2025/12/24 04:08:26 DEBUG : file3.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2)
2025/12/24 04:08:26 DEBUG : fileA.txt: Sizes differ
2025/12/24 04:08:28 DEBUG : file3.txt: size = 19 OK
2025/12/24 04:08:28 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/12/24 04:08:28 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/12/24 04:08:28 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/12/24 04:08:28 NOTICE: (21)  : delete-file TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/file4.txt
2025/12/24 04:08:28 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" for canonical name "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1"
2025/12/24 04:08:28 INFO  : file4.txt: Deleted
2025/12/24 04:08:28 NOTICE: (22)  : copy-as /tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/ file4.txt
2025/12/24 04:08:28 DEBUG : copyFile "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/" as "file4.txt"
2025/12/24 04:08:28 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt"
2025/12/24 04:08:28 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:29 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2" as "file4.txt"
2025/12/24 04:08:29 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/040750me/datadir/test_resync-jeruqat7)
2025/12/24 04:08:29 DEBUG : file4.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2)
2025/12/24 04:08:29 DEBUG : fileA.txt: Sizes differ
2025/12/24 04:08:31 DEBUG : file4.txt: size = 19 OK
2025/12/24 04:08:31 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/12/24 04:08:31 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/12/24 04:08:31 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/12/24 04:08:31 NOTICE: (24)  : copy-as /tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/ file5.txt
2025/12/24 04:08:31 DEBUG : copyFile "/tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/" as "file5.txt"
2025/12/24 04:08:31 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt"
2025/12/24 04:08:31 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:32 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2" as "file5.txt"
2025/12/24 04:08:32 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/040750me/datadir/test_resync-jeruqat7)
2025/12/24 04:08:32 DEBUG : file5.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2)
2025/12/24 04:08:32 DEBUG : fileB.txt: Sizes differ
2025/12/24 04:08:34 DEBUG : file5.txt: size = 19 OK
2025/12/24 04:08:34 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/12/24 04:08:34 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/12/24 04:08:34 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/12/24 04:08:34 NOTICE: (26)  : copy-as /tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/ file6.txt
2025/12/24 04:08:34 DEBUG : copyFile "/tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" as "file6.txt"
2025/12/24 04:08:34 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/fileB.txt"
2025/12/24 04:08:34 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/"
2025/12/24 04:08:35 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1" as "file6.txt"
2025/12/24 04:08:35 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/040750me/datadir/test_resync-jeruqat7)
2025/12/24 04:08:35 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1)
2025/12/24 04:08:35 DEBUG : fileB.txt: Sizes differ
2025/12/24 04:08:37 DEBUG : file6.txt: size = 19 OK
2025/12/24 04:08:37 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/12/24 04:08:37 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/12/24 04:08:37 NOTICE: (27)  : copy-as /tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/ file6.txt
2025/12/24 04:08:37 DEBUG : copyFile "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/" as "file6.txt"
2025/12/24 04:08:37 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync-jeruqat7/fileA.txt"
2025/12/24 04:08:37 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:38 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2" as "file6.txt"
2025/12/24 04:08:38 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/040750me/datadir/test_resync-jeruqat7)
2025/12/24 04:08:38 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2)
2025/12/24 04:08:38 DEBUG : fileA.txt: Sizes differ
2025/12/24 04:08:40 DEBUG : file6.txt: size = 19 OK
2025/12/24 04:08:40 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/12/24 04:08:40 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/12/24 04:08:40 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/12/24 04:08:40 NOTICE: (29)  : test run bisync with resync
2025/12/24 04:08:40 NOTICE: (30)  : bisync resync
2025/12/24 04:08:48 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:48 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:08:48 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:08:48 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:48 INFO  : Copying Path2 files to Path1
2025/12/24 04:08:48 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:08:48 INFO  : file2.txt: Copied (server-side copy)
2025/12/24 04:08:49 INFO  : file4.txt: Copied (server-side copy)
2025/12/24 04:08:49 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:08:49 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:08:50 INFO  : file3.txt: Copied (server-side copy)
2025/12/24 04:08:50 INFO  : file5.txt: Copied (server-side copy)
2025/12/24 04:08:50 INFO  : file6.txt: Updated modification time in destination
2025/12/24 04:08:50 INFO  : Resync updating listings
2025/12/24 04:08:50 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:50 INFO  : Bisync successful
2025/12/24 04:08:50 NOTICE: (31)  : copy-listings mixed-diffs
2025/12/24 04:08:50 NOTICE: (32)  : test run normal bisync
2025/12/24 04:08:50 NOTICE: (33)  : bisync
2025/12/24 04:08:58 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:58 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:08:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:08: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/12/24 04:08:58 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:58 INFO  : Building Path1 and Path2 listings
2025/12/24 04:08:58 INFO  : Path1 checking for diffs
2025/12/24 04:08:58 INFO  : Path2 checking for diffs
2025/12/24 04:08:58 INFO  : No changes found
2025/12/24 04:08:58 INFO  : Updating listings
2025/12/24 04:08:58 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:08:58 INFO  : Bisync successful
2025/12/24 04:08:58 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/12/24 04:08:58 NOTICE: (35)  : purge-children TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/
2025/12/24 04:08:59 DEBUG : RCLONE_TEST: Remove file
2025/12/24 04:08:59 DEBUG : file1.txt: Remove file
2025/12/24 04:08:59 DEBUG : file2.txt: Remove file
2025/12/24 04:08:59 DEBUG : file3.txt: Remove file
2025/12/24 04:08:59 DEBUG : file4.txt: Remove file
2025/12/24 04:09:00 DEBUG : file5.txt: Remove file
2025/12/24 04:09:00 DEBUG : file6.txt: Remove file
2025/12/24 04:09:00 DEBUG : file7.txt: Remove file
2025/12/24 04:09:00 NOTICE: (36)  : bisync
2025/12/24 04:09:09 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:09 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:09:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:09:09 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync/path2/"
2025/12/24 04:09:09 INFO  : Building Path1 and Path2 listings
2025/12/24 04:09:09 INFO  : Path1 checking for diffs
2025/12/24 04:09:09 INFO  : Path2 checking for diffs
2025/12/24 04:09:09 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/040750me/workdir/TestB2_rclone-test-maqiciw1feki_040750me_resync_path1..TestB2_rclone-test-maqiciw1feki_040750me_resync_path2.path2.lst-new
2025/12/24 04:09:09 ERROR : Bisync critical error: empty current Path2 listing: /tmp/040750me/workdir/TestB2_rclone-test-maqiciw1feki_040750me_resync_path1..TestB2_rclone-test-maqiciw1feki_040750me_resync_path2.path2.lst-new
2025/12/24 04:09:09 ERROR : Bisync aborted. Must run --resync to recover.
2025/12/24 04:09:09 NOTICE: Bisync error: bisync aborted
2025/12/24 04:09:09 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:09:09 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f111150ce92c58bcc_d20251224_m040809_c001_v0001041_t0025_u01766549289746")
2025/12/24 04:09:09 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea71_d20251224_m040800_c001_v7007000_t0000_u01766549280327")
2025/12/24 04:09:09 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11553c43253ab638_d20251224_m040757_c001_v0001167_t0004_u01766549277361")
2025/12/24 04:09:09 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1181392669deea9e_d20251224_m040810_c001_v0001173_t0011_u01766549290114")
2025/12/24 04:09:09 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea72_d20251224_m040800_c001_v7007000_t0000_u01766549280511")
2025/12/24 04:09:09 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13ec0f6_d20251224_m040756_c001_v0001185_t0042_u01766549276954")
2025/12/24 04:09:09 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1171cdba2537e44d_d20251224_m040848_c001_v0001185_t0036_u01766549328823")
2025/12/24 04:09:09 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4236d929c4d02d9a_d20251224_m040824_c001_v7007000_t0000_u01766549304876")
2025/12/24 04:09:09 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1001775dc345e2ec_d20251224_m040810_c001_v0001092_t0039_u01766549290117")
2025/12/24 04:09:09 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea73_d20251224_m040800_c001_v7007000_t0000_u01766549280694")
2025/12/24 04:09:09 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f104fc5294311761c_d20251224_m040756_c001_v0001041_t0049_u01766549276810")
2025/12/24 04:09:10 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f112236609554b131_d20251224_m040810_c001_v0001032_t0033_u01766549290117")
2025/12/24 04:09:10 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea74_d20251224_m040800_c001_v7007000_t0000_u01766549280876")
2025/12/24 04:09:10 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f109d6b4c7abf45ce_d20251224_m040757_c001_v0001134_t0052_u01766549277184")
2025/12/24 04:09:10 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1055bd921580fe40_d20251224_m040849_c001_v0001099_t0047_u01766549329031")
2025/12/24 04:09:10 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4236d929c4d02d9e_d20251224_m040828_c001_v7007000_t0000_u01766549308471")
2025/12/24 04:09:10 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115dae787fd88f86_d20251224_m040810_c001_v0001109_t0029_u01766549290127")
2025/12/24 04:09:10 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea75_d20251224_m040801_c001_v7007000_t0000_u01766549281061")
2025/12/24 04:09:10 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f104fc5294311761e_d20251224_m040757_c001_v0001041_t0041_u01766549277208")
2025/12/24 04:09:10 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10871f4059b726f8_d20251224_m040810_c001_v0001177_t0055_u01766549290589")
2025/12/24 04:09:10 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea76_d20251224_m040801_c001_v7007000_t0000_u01766549281244")
2025/12/24 04:09:10 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13ec106_d20251224_m040757_c001_v0001185_t0048_u01766549277238")
2025/12/24 04:09:10 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115ae245776c0c09_d20251224_m040837_c001_v0001106_t0050_u01766549317117")
2025/12/24 04:09:10 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10450e5a42af3ecb_d20251224_m040810_c001_v0001177_t0022_u01766549290598")
2025/12/24 04:09:10 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea77_d20251224_m040801_c001_v7007000_t0000_u01766549281426")
2025/12/24 04:09:10 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13ec117_d20251224_m040757_c001_v0001185_t0032_u01766549277485")
2025/12/24 04:09:10 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1193b1a969487380_d20251224_m040810_c001_v0001104_t0057_u01766549290602")
2025/12/24 04:09:10 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea78_d20251224_m040801_c001_v7007000_t0000_u01766549281608")
2025/12/24 04:09:10 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f109d6b4c7abf45d0_d20251224_m040757_c001_v0001134_t0007_u01766549277543")
2025/12/24 04:09:10 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f422c02464af0b8ef_d20251224_m040904_c001_v7007000_t0000_u01766549344455")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1037327a8bd90a06_d20251224_m040903_c001_v0001167_t0027_u01766549343023")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f104fc5294311767f_d20251224_m040901_c001_v0001041_t0040_u01766549341028")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f422c02464af0b8e8_d20251224_m040854_c001_v7007000_t0000_u01766549334348")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1137ec3a5b30d6c1_d20251224_m040853_c001_v0001185_t0020_u01766549333162")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11553c43253abc60_d20251224_m040850_c001_v0001167_t0014_u01766549330912")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f422c02464af0b8da_d20251224_m040844_c001_v7007000_t0000_u01766549324526")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f102de00abe74c8b2_d20251224_m040842_c001_v0001150_t0013_u01766549322999")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f109d6b4c7abf4608_d20251224_m040841_c001_v0001134_t0003_u01766549321114")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4236d929c4d02d79_d20251224_m040816_c001_v7007000_t0000_u01766549296208")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1137ec3a5b30cef0_d20251224_m040814_c001_v0001185_t0008_u01766549294987")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13ec433_d20251224_m040813_c001_v0001185_t0017_u01766549293241")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f401ef7717c40ea7f_d20251224_m040805_c001_v7007000_t0000_u01766549285102")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f5378153ac_d20251224_m040803_c001_v0001185_t0000_u01766549283833")
2025/12/24 04:09:11 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f104fc5294311762c_d20251224_m040801_c001_v0001041_t0027_u01766549281795")
2025/12/24 04:09:12 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:09:12 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8420_d20251224_m040859_c001_v7007000_t0000_u01766549339214")
2025/12/24 04:09:12 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100b765b80ae7264_d20251224_m040820_c001_v0001165_t0050_u01766549300775")
2025/12/24 04:09:12 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f4_d20251224_m040811_c001_v7007000_t0000_u01766549291417")
2025/12/24 04:09:12 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f110a707381935d69_d20251224_m040759_c001_v0001042_t0034_u01766549279222")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8421_d20251224_m040859_c001_v7007000_t0000_u01766549339395")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1137ec3a5b30d618_d20251224_m040849_c001_v0001185_t0056_u01766549329713")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4070f514cda4cf53_d20251224_m040823_c001_v7007000_t0000_u01766549303059")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f113ca81b681bf117_d20251224_m040821_c001_v0001042_t0018_u01766549301173")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f5_d20251224_m040811_c001_v7007000_t0000_u01766549291600")
2025/12/24 04:09:12 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100530eba762d701_d20251224_m040759_c001_v0001182_t0055_u01766549279036")
2025/12/24 04:09:12 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8422_d20251224_m040859_c001_v7007000_t0000_u01766549339578")
2025/12/24 04:09:13 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115b2f30a2c071c7_d20251224_m040821_c001_v0001171_t0020_u01766549301142")
2025/12/24 04:09:13 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f6_d20251224_m040811_c001_v7007000_t0000_u01766549291782")
2025/12/24 04:09:13 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f105fdef1d124b70c_d20251224_m040759_c001_v0001167_t0000_u01766549279391")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8423_d20251224_m040859_c001_v7007000_t0000_u01766549339759")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118756e63e8771f2_d20251224_m040850_c001_v0001154_t0024_u01766549330041")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f107dae962973bc01_d20251224_m040827_c001_v0001106_t0000_u01766549307866")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f114b9d80fcdafa6e_d20251224_m040821_c001_v0001185_t0035_u01766549301157")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f8_d20251224_m040811_c001_v7007000_t0000_u01766549291966")
2025/12/24 04:09:13 DEBUG : file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec63cd_d20251224_m040758_c001_v0001167_t0030_u01766549278824")
2025/12/24 04:09:13 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8424_d20251224_m040859_c001_v7007000_t0000_u01766549339939")
2025/12/24 04:09:13 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10722d4fcb80af06_d20251224_m040831_c001_v0001157_t0031_u01766549311261")
2025/12/24 04:09:13 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f112c985bc1fff352_d20251224_m040821_c001_v0001157_t0021_u01766549301162")
2025/12/24 04:09:13 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f9_d20251224_m040812_c001_v7007000_t0000_u01766549292147")
2025/12/24 04:09:13 DEBUG : file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100530eba762d705_d20251224_m040759_c001_v0001182_t0055_u01766549279237")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8425_d20251224_m040900_c001_v7007000_t0000_u01766549340119")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11553c43253abc35_d20251224_m040850_c001_v0001167_t0041_u01766549330090")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1015440c0b3553ba_d20251224_m040834_c001_v0001183_t0047_u01766549314128")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100d0d717be6b825_d20251224_m040821_c001_v0001100_t0024_u01766549301431")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1fa_d20251224_m040812_c001_v7007000_t0000_u01766549292330")
2025/12/24 04:09:13 DEBUG : file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec63de_d20251224_m040759_c001_v0001167_t0007_u01766549279262")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8427_d20251224_m040900_c001_v7007000_t0000_u01766549340300")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1114df3d949e1dd5_d20251224_m040850_c001_v0001153_t0016_u01766549330091")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1116a121634a64cf_d20251224_m040840_c001_v0001120_t0057_u01766549320307")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f114b9d80fcdafa8a_d20251224_m040821_c001_v0001185_t0040_u01766549301441")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1fb_d20251224_m040812_c001_v7007000_t0000_u01766549292512")
2025/12/24 04:09:14 DEBUG : file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100530eba762d707_d20251224_m040759_c001_v0001182_t0027_u01766549279431")
2025/12/24 04:09:14 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c8429_d20251224_m040900_c001_v7007000_t0000_u01766549340482")
2025/12/24 04:09:14 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115a780f92aab31e_d20251224_m040821_c001_v0001185_t0002_u01766549301523")
2025/12/24 04:09:14 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1fc_d20251224_m040812_c001_v7007000_t0000_u01766549292693")
2025/12/24 04:09:14 DEBUG : file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100530eba762d70d_d20251224_m040759_c001_v0001182_t0000_u01766549279640")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f406a9478801c7c0d_d20251224_m040909_c001_v7007000_t0000_u01766549349082")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f111cc3c327d9301c_d20251224_m040907_c001_v0001157_t0003_u01766549347755")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec6d08_d20251224_m040905_c001_v0001167_t0048_u01766549345020")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f417a86844a0c841f_d20251224_m040858_c001_v7007000_t0000_u01766549338652")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f113d509e96430590_d20251224_m040857_c001_v0001167_t0002_u01766549337075")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f105fdef1d124bd6f_d20251224_m040854_c001_v0001167_t0003_u01766549334897")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4236d929c4d02daa_d20251224_m040848_c001_v7007000_t0000_u01766549328442")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116574489e5ced0c_d20251224_m040847_c001_v0001102_t0056_u01766549327100")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f110a707381935da4_d20251224_m040845_c001_v0001042_t0009_u01766549325109")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4070f514cda4cf51_d20251224_m040820_c001_v7007000_t0000_u01766549300189")
2025/12/24 04:09:14 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f114aa897529417d7_d20251224_m040818_c001_v0001177_t0027_u01766549298922")
2025/12/24 04:09:15 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100530eba762d762_d20251224_m040816_c001_v0001182_t0059_u01766549296796")
2025/12/24 04:09:15 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f1f0_d20251224_m040809_c001_v7007000_t0000_u01766549289346")
2025/12/24 04:09:15 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f106d512448f685a8_d20251224_m040808_c001_v0001180_t0042_u01766549288124")
2025/12/24 04:09:15 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec64aa_d20251224_m040805_c001_v0001167_t0014_u01766549285698")
    bisync_test.go:608: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/12/24 04:09:15 INFO  : path1: Making directory
2025/12/24 04:09:15 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1"
2025/12/24 04:09:16 INFO  : path2: Making directory
2025/12/24 04:09:16 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2"
2025/12/24 04:09:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/12/24 04:09:17 DEBUG : Creating backend with remote "/tmp/040750me/initdir/test_resync_modes-nuyuvon4"
2025/12/24 04:09:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : Local file system at /tmp/040750me/initdir/test_resync_modes-nuyuvon4: Waiting for checks to finish
2025/12/24 04:09:17 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:09:17 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:09:17 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/12/24 04:09:17 DEBUG : Local file system at /tmp/040750me/initdir/test_resync_modes-nuyuvon4: Waiting for transfers to finish
2025/12/24 04:09:17 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:09:17 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:09:17 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:09:17 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:09:17 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:09:17 INFO  : file1.txt: Copied (new)
2025/12/24 04:09:17 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:09:17 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/12/24 04:09:17 INFO  : file2.txt: Copied (new)
2025/12/24 04:09:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/12/24 04:09:17 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:17 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:17 DEBUG : Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1: Waiting for checks to finish
2025/12/24 04:09:17 DEBUG : Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1: Waiting for transfers to finish
2025/12/24 04:09:17 DEBUG : file1L.txt.684d937d.partial: size = 33 OK
2025/12/24 04:09:17 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/12/24 04:09:17 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/12/24 04:09:17 INFO  : file1L.txt: Copied (new)
2025/12/24 04:09:17 DEBUG : file1R.txt.d467e63a.partial: size = 42 OK
2025/12/24 04:09:17 DEBUG : file2.txt.ac4156f2.partial: size = 37 OK
2025/12/24 04:09:17 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/12/24 04:09:17 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/12/24 04:09:17 INFO  : file2.txt: Copied (new)
2025/12/24 04:09:17 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/12/24 04:09:17 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/12/24 04:09:17 INFO  : file1R.txt: Copied (new)
2025/12/24 04:09:17 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:09:18 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:09:19 NOTICE: checking initFs Local file system at /tmp/040750me/initdir/test_resync_modes-nuyuvon4
2025/12/24 04:09:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:09:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:19 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Waiting for checks to finish
2025/12/24 04:09:19 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Waiting for transfers to finish
2025/12/24 04:09:20 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:09:20 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:09:20 INFO  : file1.txt: Copied (new)
2025/12/24 04:09:21 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:09:21 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:09:21 INFO  : file2.txt: Copied (new)
2025/12/24 04:09:21 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:09:21 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:09:21 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:09:21 NOTICE: checking Path1 B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1
2025/12/24 04:09:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:09:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:09:21 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Waiting for checks to finish
2025/12/24 04:09:21 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Waiting for transfers to finish
2025/12/24 04:09:22 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:09:22 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:09:22 INFO  : file1.txt: Copied (new)
2025/12/24 04:09:23 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:09:23 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:09:23 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:09:23 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:09:23 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:09:23 INFO  : file2.txt: Copied (new)
2025/12/24 04:09:23 NOTICE: checking path2 B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2
2025/12/24 04:09:23 NOTICE: (01)  : test resync-mode
2025/12/24 04:09:23 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:09:23 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:09:23 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/"
2025/12/24 04:09:23 DEBUG : fs cache: renaming cache item "/tmp/040750me/datadir/test_resync_modes-hefowed1/" to be canonical "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:23 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/12/24 04:09:23 NOTICE: (04)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:09:23 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:09:23 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:09:23 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:24 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:09:24 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:09:24 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:09:24 DEBUG : file1R.txt: Sizes differ
2025/12/24 04:09:26 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:09:26 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:09:26 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:09:26 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:09:26 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:26 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/12/24 04:09:26 NOTICE: (06)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:09:26 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:09:26 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:09:26 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:09:27 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:09:28 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:09:28 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1)
2025/12/24 04:09:28 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:09:29 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:09:29 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:09:29 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:09:29 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/12/24 04:09:29 NOTICE: (08)  : bisync resync resync-mode=newer
2025/12/24 04:09:38 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:38 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:09: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/12/24 04:09:38 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:38 INFO  : Copying Path2 files to Path1
2025/12/24 04:09:38 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:09:39 INFO  : There was nothing to transfer
2025/12/24 04:09:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:09:39 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:09:39 INFO  : Resync updating listings
2025/12/24 04:09:39 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:39 INFO  : Bisync successful
2025/12/24 04:09:39 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:09:39 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:09:39 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:39 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/12/24 04:09:39 NOTICE: (11)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:09:39 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:09:39 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:09:39 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:41 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:09:41 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:09:41 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:09:41 DEBUG : file1R.txt: Sizes differ
2025/12/24 04:09:43 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:09:43 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:09:43 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:09:43 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:09:43 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:43 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/12/24 04:09:43 NOTICE: (13)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:09:43 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:09:43 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:09:43 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:09:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:09:44 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:09:44 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/12/24 04:09:44 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:09:45 INFO  : file1L.txt: Updated modification time in destination
2025/12/24 04:09:45 DEBUG : file1L.txt: Unchanged skipping
2025/12/24 04:09:45 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/12/24 04:09:45 NOTICE: (15)  : bisync resync resync-mode=path2
2025/12/24 04:09:54 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:54 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:09:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:09:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:09:54 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:54 INFO  : Copying Path2 files to Path1
2025/12/24 04:09:54 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:09:55 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:09:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:09:55 INFO  : Resync updating listings
2025/12/24 04:09:55 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:55 INFO  : Bisync successful
2025/12/24 04:09:55 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:09:55 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:09:55 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:55 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/12/24 04:09:55 NOTICE: (18)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:09:55 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:09:55 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:09:55 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:09:56 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:09:56 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:09:56 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/12/24 04:09:56 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:09:58 INFO  : file1R.txt: Updated modification time in destination
2025/12/24 04:09:58 DEBUG : file1R.txt: Unchanged skipping
2025/12/24 04:09:58 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:09:58 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:09:58 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/12/24 04:09:58 NOTICE: (20)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:09:58 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:09:58 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:09:58 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:09:59 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:09:59 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:09:59 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1)
2025/12/24 04:09:59 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:10:01 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:10:01 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:10:01 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:10:01 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/12/24 04:10:01 NOTICE: (22)  : bisync resync resync-mode=larger
2025/12/24 04:10:10 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:10 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:10 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:10:10 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:10:10 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:10 INFO  : Copying Path2 files to Path1
2025/12/24 04:10:10 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:10:10 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/12/24 04:10:11 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:10:11 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:10:11 INFO  : Resync updating listings
2025/12/24 04:10:11 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:11 INFO  : Bisync successful
2025/12/24 04:10:11 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:10:11 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:10:11 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:11 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/12/24 04:10:11 NOTICE: (25)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:10:11 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:10:11 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:10:11 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:12 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:10:12 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:10:12 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/12/24 04:10:12 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:10:14 INFO  : file1R.txt: Updated modification time in destination
2025/12/24 04:10:14 DEBUG : file1R.txt: Unchanged skipping
2025/12/24 04:10:14 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:10:14 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:14 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/12/24 04:10:14 NOTICE: (27)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:10:14 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:10:14 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:10:14 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:10:15 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:10:15 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:10:15 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1)
2025/12/24 04:10:15 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:10:17 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:10:17 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:10:17 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:10:17 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/12/24 04:10:17 NOTICE: (29)  : bisync resync resync-mode=older
2025/12/24 04:10:25 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:25 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:10: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/12/24 04:10:25 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:25 INFO  : Copying Path2 files to Path1
2025/12/24 04:10:25 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:10:26 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/12/24 04:10:26 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:10:26 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:10:27 INFO  : Resync updating listings
2025/12/24 04:10:27 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:27 INFO  : Bisync successful
2025/12/24 04:10:27 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:10:27 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:10:27 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:27 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/12/24 04:10:27 NOTICE: (32)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:10:27 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:10:27 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:10:27 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:28 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:10:28 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:10:28 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/12/24 04:10:28 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:10:29 INFO  : file1R.txt: Updated modification time in destination
2025/12/24 04:10:29 DEBUG : file1R.txt: Unchanged skipping
2025/12/24 04:10:29 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:10:29 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:29 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/12/24 04:10:29 NOTICE: (34)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:10:29 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:10:29 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:10:29 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:10:30 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:10:30 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:10:30 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1)
2025/12/24 04:10:30 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:10:32 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:10:32 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:10:32 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:10:32 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/12/24 04:10:32 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/12/24 04:10:41 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:41 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:10:41 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:10:41 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:10:41 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:41 INFO  : Copying Path2 files to Path1
2025/12/24 04:10:41 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:10:41 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/12/24 04:10:41 INFO  : There was nothing to transfer
2025/12/24 04:10:41 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:10:41 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/12/24 04:10:41 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:10:41 INFO  : Resync updating listings
2025/12/24 04:10:41 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:41 INFO  : Bisync successful
2025/12/24 04:10:41 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/12/24 04:10:41 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:10:41 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:41 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/12/24 04:10:41 NOTICE: (39)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:10:41 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:10:41 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:10:41 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:42 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:10:42 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:10:42 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:10:42 DEBUG : file1R.txt: Sizes differ
2025/12/24 04:10:44 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:10:44 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:10:44 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:10:44 NOTICE: (40)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file2.txt
2025/12/24 04:10:44 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file2.txt"
2025/12/24 04:10:44 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:10:44 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:10:45 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file2.txt"
2025/12/24 04:10:45 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:10:45 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1)
2025/12/24 04:10:45 DEBUG : file1R.txt: Sizes differ
2025/12/24 04:10:47 DEBUG : file2.txt: size = 42 OK
2025/12/24 04:10:47 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:10:47 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/12/24 04:10:47 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:10:47 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:10:47 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/12/24 04:10:47 NOTICE: (42)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:10:47 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:10:47 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:10:47 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:10:48 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:10:48 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:10:48 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/12/24 04:10:48 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:10:49 INFO  : file1L.txt: Updated modification time in destination
2025/12/24 04:10:49 DEBUG : file1L.txt: Unchanged skipping
2025/12/24 04:10:49 NOTICE: (43)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file2.txt
2025/12/24 04:10:49 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file2.txt"
2025/12/24 04:10:49 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:10:49 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:10:51 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file2.txt"
2025/12/24 04:10:51 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:10:51 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:10:51 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:10:52 DEBUG : file2.txt: size = 33 OK
2025/12/24 04:10:52 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:10:52 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/12/24 04:10:52 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/12/24 04:10:52 NOTICE: (45)  : bisync resync resync-mode=path1
2025/12/24 04:11:01 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:11:01 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:11:01 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:11:01 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:11:01 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:01 INFO  : Copying Path2 files to Path1
2025/12/24 04:11:01 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:11:01 INFO  : There was nothing to transfer
2025/12/24 04:11:01 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:11:02 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:11:02 INFO  : file2.txt: Copied (server-side copy)
2025/12/24 04:11:02 INFO  : Resync updating listings
2025/12/24 04:11:02 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:02 INFO  : Bisync successful
2025/12/24 04:11:02 NOTICE: (46)  : test no winner
2025/12/24 04:11:02 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1R.txt
2025/12/24 04:11:02 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:11:02 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/12/24 04:11:02 NOTICE: (48)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file1.txt
2025/12/24 04:11:02 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file1.txt"
2025/12/24 04:11:02 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:11:02 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:03 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file1.txt"
2025/12/24 04:11:03 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:11:03 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:11:03 DEBUG : file1R.txt: Sizes differ
2025/12/24 04:11:05 DEBUG : file1.txt: size = 42 OK
2025/12/24 04:11:05 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:11:05 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/12/24 04:11:05 NOTICE: (49)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file2.txt
2025/12/24 04:11:05 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file2.txt"
2025/12/24 04:11:05 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1R.txt"
2025/12/24 04:11:05 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:11:06 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file2.txt"
2025/12/24 04:11:06 DEBUG : file2.txt: size = 42 OK
2025/12/24 04:11:06 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/12/24 04:11:06 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/12/24 04:11:08 INFO  : file1R.txt: Updated modification time in destination
2025/12/24 04:11:08 DEBUG : file1R.txt: Unchanged skipping
2025/12/24 04:11:08 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/040750me/datadir/test_resync_modes-hefowed1/ file1L.txt
2025/12/24 04:11:08 DEBUG : fs cache: switching user supplied name "/tmp/040750me/datadir/test_resync_modes-hefowed1/" for canonical name "/tmp/040750me/datadir/test_resync_modes-hefowed1"
2025/12/24 04:11:08 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/12/24 04:11:08 NOTICE: (51)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/ file1.txt
2025/12/24 04:11:08 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" as "file1.txt"
2025/12/24 04:11:08 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:11:08 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/"
2025/12/24 04:11:09 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1" as "file1.txt"
2025/12/24 04:11:09 DEBUG : file1.txt: size = 33 OK
2025/12/24 04:11:09 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/12/24 04:11:09 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:11:10 INFO  : file1L.txt: Updated modification time in destination
2025/12/24 04:11:10 DEBUG : file1L.txt: Unchanged skipping
2025/12/24 04:11:10 NOTICE: (52)  : copy-as /tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/ file2.txt
2025/12/24 04:11:10 DEBUG : copyFile "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt" to "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/" as "file2.txt"
2025/12/24 04:11:10 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_resync_modes-hefowed1/file1L.txt"
2025/12/24 04:11:10 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:11 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2" as "file2.txt"
2025/12/24 04:11:12 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/040750me/datadir/test_resync_modes-hefowed1)
2025/12/24 04:11:12 DEBUG : file2.txt: size = 42 (B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2)
2025/12/24 04:11:12 DEBUG : file1L.txt: Sizes differ
2025/12/24 04:11:13 DEBUG : file2.txt: size = 33 OK
2025/12/24 04:11:13 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/12/24 04:11:13 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/12/24 04:11:13 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/12/24 04:11:13 NOTICE: (54)  : bisync resync resync-mode=newer
2025/12/24 04:11:22 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:11:22 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/12/24 04:11:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/12/24 04:11:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/12/24 04:11:22 INFO  : Synching Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" with Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:22 INFO  : Copying Path2 files to Path1
2025/12/24 04:11:22 INFO  : - Path2    Resync is copying files to         - Path1
2025/12/24 04:11:22 INFO  : file1.txt: Copied (server-side copy)
2025/12/24 04:11:23 INFO  : file2.txt: Copied (server-side copy)
2025/12/24 04:11:23 INFO  : - Path1    Resync is copying files to         - Path2
2025/12/24 04:11:23 INFO  : Resync updating listings
2025/12/24 04:11:23 INFO  : Validating listings for Path1 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-maqiciw1feki/040750me/resync_modes/path2/"
2025/12/24 04:11:23 INFO  : Bisync successful
2025/12/24 04:11:23 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:23 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116a374f154e7083_d20251224_m040920_c001_v0001167_t0012_u01766549360851")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1137ec3a5b30f6ec_d20251224_m041122_c001_v0001185_t0018_u01766549482657")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1098663c8a7843ae_d20251224_m041110_c001_v0001185_t0039_u01766549470669")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1013c5a0e8982b76_d20251224_m041049_c001_v0001182_t0056_u01766549449796")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f102c02b80b021b83_d20251224_m041032_c001_v0001134_t0028_u01766549432232")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116a374f154e7796_d20251224_m041026_c001_v0001167_t0010_u01766549426293")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1133027baa275d0b_d20251224_m041016_c001_v0001043_t0043_u01766549416765")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f109dc582167f8414_d20251224_m041010_c001_v0001120_t0045_u01766549410960")
2025/12/24 04:11:23 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1113e195c0f21f88_d20251224_m041000_c001_v0001131_t0018_u01766549400917")
2025/12/24 04:11:24 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108e6fda4434bdfe_d20251224_m040954_c001_v0001131_t0051_u01766549394969")
2025/12/24 04:11:24 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116268b31868111c_d20251224_m040945_c001_v0001178_t0029_u01766549385451")
2025/12/24 04:11:24 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118324f1274984ca_d20251224_m040929_c001_v0001130_t0058_u01766549369493")
2025/12/24 04:11:24 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1065b8be78d33256_d20251224_m040920_c001_v0001173_t0029_u01766549360446")
2025/12/24 04:11:24 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100d2bac9873ac74_d20251224_m041122_c001_v0001167_t0030_u01766549482940")
2025/12/24 04:11:24 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f102f9da3ab499aed_d20251224_m041107_c001_v0001173_t0049_u01766549467963")
2025/12/24 04:11:24 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1165681dd245f7ef_d20251224_m041047_c001_v0001185_t0045_u01766549447302")
2025/12/24 04:11:24 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10383a34916d4fc8_d20251224_m040920_c001_v0001173_t0031_u01766549360664")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f408e6ed2ce3355a7_d20251224_m041117_c001_v7007000_t0000_u01766549477661")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10452966387d7ad5_d20251224_m041116_c001_v0001185_t0048_u01766549476423")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1065b8be78d33ccf_d20251224_m041114_c001_v0001173_t0059_u01766549474025")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4070f514cda4d656_d20251224_m041056_c001_v7007000_t0000_u01766549456934")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1126f6e257e130e9_d20251224_m041055_c001_v0001185_t0048_u01766549455742")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116a374f154e7af8_d20251224_m041053_c001_v0001167_t0001_u01766549453274")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4236d929c4d0362c_d20251224_m041036_c001_v7007000_t0000_u01766549436531")
2025/12/24 04:11:24 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1137ec3a5b30ec35_d20251224_m041035_c001_v0001185_t0056_u01766549435343")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10383a34916d54c8_d20251224_m041032_c001_v0001173_t0002_u01766549432963")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f40284e022bdd93f3_d20251224_m041021_c001_v7007000_t0000_u01766549421452")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1059e4add814be58_d20251224_m041020_c001_v0001100_t0004_u01766549420052")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1065b8be78d33767_d20251224_m041017_c001_v0001173_t0036_u01766549417666")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f410a873dfb0363e4_d20251224_m041005_c001_v7007000_t0000_u01766549405966")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108474cf3d315b32_d20251224_m041004_c001_v0001144_t0039_u01766549404075")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f116a374f154e7508_d20251224_m041001_c001_v0001167_t0058_u01766549401676")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f422c02464af0bffe_d20251224_m040950_c001_v7007000_t0000_u01766549390170")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1165681dd245ebd3_d20251224_m040948_c001_v0001185_t0035_u01766549388748")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10383a34916d5199_d20251224_m040946_c001_v0001173_t0041_u01766549386050")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4186c3374a9939fb_d20251224_m040934_c001_v7007000_t0000_u01766549374182")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f106b4c1be1a7b949_d20251224_m040932_c001_v0001167_t0021_u01766549372623")
2025/12/24 04:11:25 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1065b8be78d33377_d20251224_m040930_c001_v0001173_t0039_u01766549370240")
2025/12/24 04:11:26 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/resync_modes/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:26 DEBUG : RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100d2bac98739c27_d20251224_m040922_c001_v0001167_t0006_u01766549362910")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10592de8cd1e641f_d20251224_m041105_c001_v0001173_t0029_u01766549465232")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108474abaca5902f_d20251224_m041101_c001_v0001161_t0022_u01766549461976")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11184b802fb0f8f7_d20251224_m041044_c001_v0001171_t0041_u01766549444412")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1039330b1ae14edf_d20251224_m041041_c001_v0001165_t0021_u01766549441531")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100eb1119a44f7c9_d20251224_m041029_c001_v0001185_t0014_u01766549429442")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11479896ab24c8c6_d20251224_m041013_c001_v0001113_t0004_u01766549413873")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1096e9c6eeb22ffc_d20251224_m040957_c001_v0001091_t0043_u01766549397854")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10592de8cd1e5d3f_d20251224_m040942_c001_v0001173_t0029_u01766549382618")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10771154b42edcec_d20251224_m040939_c001_v0001167_t0005_u01766549379457")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1181392669def03d_d20251224_m040926_c001_v0001173_t0043_u01766549366441")
2025/12/24 04:11:26 DEBUG : file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118356d0ac9224e3_d20251224_m040922_c001_v0001036_t0048_u01766549362335")
2025/12/24 04:11:26 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f106fa333df5307c2_d20251224_m041113_c001_v0001185_t0019_u01766549473463")
2025/12/24 04:11:26 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f106fa333df530506_d20251224_m041102_c001_v0001185_t0009_u01766549462341")
2025/12/24 04:11:26 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1011ab166805cca2_d20251224_m041052_c001_v0001185_t0036_u01766549452675")
2025/12/24 04:11:27 DEBUG : file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1135f0640bdcc08b_d20251224_m040923_c001_v0001173_t0054_u01766549363190")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4186c3374a993aa5_d20251224_m041122_c001_v7007000_t0000_u01766549482182")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f101d417a72b5af59_d20251224_m041120_c001_v0001090_t0055_u01766549480794")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118356d0ac9225ae_d20251224_m041118_c001_v0001036_t0037_u01766549478248")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4217ec34eeae393c_d20251224_m041101_c001_v7007000_t0000_u01766549461374")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10351ab8dbb73bea_d20251224_m041100_c001_v0001150_t0056_u01766549460101")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1135f0640bdcc794_d20251224_m041057_c001_v0001173_t0004_u01766549457726")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f41958158be3d07b8_d20251224_m041040_c001_v7007000_t0000_u01766549440965")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1087fe6c7a7b576d_d20251224_m041039_c001_v0001136_t0053_u01766549439535")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100d2bac9873a57f_d20251224_m041037_c001_v0001167_t0051_u01766549437069")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f4070f514cda4d639_d20251224_m041025_c001_v7007000_t0000_u01766549425891")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13ede0c_d20251224_m041024_c001_v0001185_t0044_u01766549424681")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118356d0ac92253e_d20251224_m041022_c001_v0001036_t0020_u01766549422012")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f418cda2746250040_d20251224_m041010_c001_v7007000_t0000_u01766549410532")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f110277308e44ce2f_d20251224_m041008_c001_v0001167_t0021_u01766549408975")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1135f0640bdcc3f9_d20251224_m041006_c001_v0001173_t0053_u01766549406700")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f41617d9fc65d1f11_d20251224_m040954_c001_v7007000_t0000_u01766549394524")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1014f96c02b06908_d20251224_m040953_c001_v0001120_t0030_u01766549393135")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100d2bac98739fed_d20251224_m040950_c001_v0001167_t0027_u01766549390709")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f419448a153c1f672_d20251224_m040938_c001_v7007000_t0000_u01766549378878")
2025/12/24 04:11:27 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f104b1dbbce24a198_d20251224_m040937_c001_v0001167_t0028_u01766549377375")
2025/12/24 04:11:28 DEBUG : modtime_write_test: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118356d0ac9224f3_d20251224_m040934_c001_v0001036_t0030_u01766549374770")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/12/24 04:11:28 INFO  : path1: Making directory
2025/12/24 04:11:28 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/rmdirs/path1"
2025/12/24 04:11:29 INFO  : path2: Making directory
2025/12/24 04:11:29 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/rmdirs/path2"
2025/12/24 04:11:30 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/12/24 04:11:30 DEBUG : Creating backend with remote "/tmp/040750me/initdir/test_rmdirs-rudoref5"
2025/12/24 04:11:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : subdir: Making directory with metadata
2025/12/24 04:11:30 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/12/24 04:11:30 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/12/24 04:11:30 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : Local file system at /tmp/040750me/initdir/test_rmdirs-rudoref5: Waiting for checks to finish
2025/12/24 04:11:30 DEBUG : Local file system at /tmp/040750me/initdir/test_rmdirs-rudoref5: Waiting for transfers to finish
2025/12/24 04:11:30 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/12/24 04:11:30 INFO  : file1.copy2.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/12/24 04:11:30 INFO  : file1.copy4.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:11:30 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:11:30 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/12/24 04:11:30 INFO  : file1.copy1.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:11:30 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:30 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/12/24 04:11:30 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/12/24 04:11:30 INFO  : file1.copy3.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/12/24 04:11:30 INFO  : file1.copy5.txt: Copied (new)
2025/12/24 04:11:30 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:11:30 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:30 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:11:30 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:30 INFO  : subdir: Set directory modification time (using SetModTime)
2025/12/24 04:11:30 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/12/24 04:11:30 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_rmdirs-puzuduz5"
2025/12/24 04:11:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:30 DEBUG : Local file system at /tmp/040750me/datadir/test_rmdirs-puzuduz5: Waiting for checks to finish
2025/12/24 04:11:30 DEBUG : Local file system at /tmp/040750me/datadir/test_rmdirs-puzuduz5: Waiting for transfers to finish
2025/12/24 04:11:30 DEBUG : file1.txt.f41fd5ee.partial: size = 19 OK
2025/12/24 04:11:30 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:30 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/12/24 04:11:30 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:30 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:31 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:32 NOTICE: checking initFs Local file system at /tmp/040750me/initdir/test_rmdirs-rudoref5
2025/12/24 04:11:32 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 INFO  : subdir: Making directory
2025/12/24 04:11:32 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:32 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path1: Waiting for checks to finish
2025/12/24 04:11:32 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path1: Waiting for transfers to finish
2025/12/24 04:11:33 DEBUG : file1.copy1.txt: size = 0 OK
2025/12/24 04:11:33 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:33 INFO  : file1.copy1.txt: Copied (new)
2025/12/24 04:11:33 DEBUG : file1.copy4.txt: size = 0 OK
2025/12/24 04:11:33 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:33 INFO  : file1.copy4.txt: Copied (new)
2025/12/24 04:11:33 DEBUG : file1.copy3.txt: size = 0 OK
2025/12/24 04:11:33 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:33 INFO  : file1.copy3.txt: Copied (new)
2025/12/24 04:11:34 DEBUG : file1.copy5.txt: size = 0 OK
2025/12/24 04:11:34 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:34 INFO  : file1.copy5.txt: Copied (new)
2025/12/24 04:11:34 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:11:34 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:11:34 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:34 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:11:34 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:34 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:34 DEBUG : file1.copy2.txt: size = 0 OK
2025/12/24 04:11:34 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:34 INFO  : file1.copy2.txt: Copied (new)
2025/12/24 04:11:34 DEBUG : subdir/file20.txt: size = 0 OK
2025/12/24 04:11:34 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:34 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:11:34 NOTICE: checking Path1 B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path1
2025/12/24 04:11:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 INFO  : subdir: Making directory
2025/12/24 04:11:34 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:34 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path2: Waiting for checks to finish
2025/12/24 04:11:34 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path2: Waiting for transfers to finish
2025/12/24 04:11:35 DEBUG : file1.copy1.txt: size = 0 OK
2025/12/24 04:11:35 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:35 INFO  : file1.copy1.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : file1.copy2.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : file1.copy2.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : file1.copy4.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : file1.copy4.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:11:36 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:11:36 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:36 DEBUG : file1.copy5.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : file1.copy5.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : file1.copy3.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : file1.copy3.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:36 DEBUG : subdir/file20.txt: size = 0 OK
2025/12/24 04:11:36 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:36 INFO  : subdir/file20.txt: Copied (new)
2025/12/24 04:11:36 NOTICE: checking path2 B2 bucket rclone-test-maqiciw1feki path 040750me/rmdirs/path2
2025/12/24 04:11:36 NOTICE: (01)  : test rmdirs
2025/12/24 04:11:36 NOTICE: (02)  : test initial bisync
2025/12/24 04:11:36 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/12/24 04:11:36 INFO  : path1: Making directory
2025/12/24 04:11:36 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/volatile/path1"
2025/12/24 04:11:38 INFO  : path2: Making directory
2025/12/24 04:11:38 DEBUG : Creating backend with remote "TestB2:rclone-test-maqiciw1feki/040750me/volatile/path2"
2025/12/24 04:11:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/12/24 04:11:39 DEBUG : Creating backend with remote "/tmp/040750me/initdir/test_volatile-hikuzir4"
2025/12/24 04:11:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/12/24 04:11:39 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/12/24 04:11:39 INFO  : file3.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/12/24 04:11:39 INFO  : file4.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/12/24 04:11:39 INFO  : file5.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/12/24 04:11:39 INFO  : file6.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/12/24 04:11:39 INFO  : file7.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/12/24 04:11:39 INFO  : file8.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/12/24 04:11:39 INFO  : file2.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/12/24 04:11:39 DEBUG : Local file system at /tmp/040750me/initdir/test_volatile-hikuzir4: Waiting for checks to finish
2025/12/24 04:11:39 DEBUG : Local file system at /tmp/040750me/initdir/test_volatile-hikuzir4: Waiting for transfers to finish
2025/12/24 04:11:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/12/24 04:11:39 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/12/24 04:11:39 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/12/24 04:11:39 DEBUG : Creating backend with remote "/tmp/040750me/datadir/test_volatile-sanezop6"
2025/12/24 04:11:39 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:39 DEBUG : Local file system at /tmp/040750me/datadir/test_volatile-sanezop6: Waiting for checks to finish
2025/12/24 04:11:39 DEBUG : Local file system at /tmp/040750me/datadir/test_volatile-sanezop6: Waiting for transfers to finish
2025/12/24 04:11:39 DEBUG : dummy.txt.b3782999.partial: size = 0 OK
2025/12/24 04:11:39 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/12/24 04:11:39 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/12/24 04:11:39 INFO  : dummy.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file1.txt.be52b1c9.partial: size = 19 OK
2025/12/24 04:11:39 DEBUG : file10.txt.bdde3bf8.partial: size = 19 OK
2025/12/24 04:11:39 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:39 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:39 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/12/24 04:11:39 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/12/24 04:11:39 INFO  : file10.txt: Copied (new)
2025/12/24 04:11:39 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file11.txt.d4a7309d.partial: size = 19 OK
2025/12/24 04:11:39 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:39 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/12/24 04:11:39 INFO  : file11.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK
2025/12/24 04:11:39 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/12/24 04:11:39 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/12/24 04:11:39 INFO  : file5R.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK
2025/12/24 04:11:39 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/12/24 04:11:39 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/12/24 04:11:39 INFO  : file5L.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file6.txt.7b4c87b3.partial: size = 19 OK
2025/12/24 04:11:39 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:39 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/12/24 04:11:39 INFO  : file6.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file7.txt.38c43fbf.partial: size = 19 OK
2025/12/24 04:11:39 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/12/24 04:11:39 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/12/24 04:11:39 INFO  : file7.txt: Copied (new)
2025/12/24 04:11:39 DEBUG : file2.txt.23ff1769.partial: size = 13 OK
2025/12/24 04:11:39 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/12/24 04:11:39 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/12/24 04:11:39 INFO  : file2.txt: Copied (new)
2025/12/24 04:11:39 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path1: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:39 INFO  : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path2: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:40 NOTICE: checking initFs Local file system at /tmp/040750me/initdir/test_volatile-hikuzir4
2025/12/24 04:11:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:41 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path1: Waiting for checks to finish
2025/12/24 04:11:41 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path1: Waiting for transfers to finish
2025/12/24 04:11:41 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:11:41 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:41 INFO  : file2.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file4.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file4.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file3.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file3.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:11:42 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:11:42 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:42 DEBUG : file6.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file6.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file5.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file5.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file8.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file8.txt: Copied (new)
2025/12/24 04:11:42 DEBUG : file7.txt: size = 0 OK
2025/12/24 04:11:42 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:42 INFO  : file7.txt: Copied (new)
2025/12/24 04:11:42 NOTICE: checking Path1 B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path1
2025/12/24 04:11:43 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/12/24 04:11:43 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path2: Waiting for checks to finish
2025/12/24 04:11:43 DEBUG : B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path2: Waiting for transfers to finish
2025/12/24 04:11:44 DEBUG : file1.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file1.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : file2.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file2.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : file3.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file3.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : file5.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file5.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : RCLONE_TEST: size = 109 OK
2025/12/24 04:11:44 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/12/24 04:11:44 INFO  : RCLONE_TEST: Copied (new)
2025/12/24 04:11:44 DEBUG : file4.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file4.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : file7.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file7.txt: Copied (new)
2025/12/24 04:11:44 DEBUG : file6.txt: size = 0 OK
2025/12/24 04:11:44 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:44 INFO  : file6.txt: Copied (new)
2025/12/24 04:11:45 DEBUG : file8.txt: size = 0 OK
2025/12/24 04:11:45 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/12/24 04:11:45 INFO  : file8.txt: Copied (new)
2025/12/24 04:11:45 NOTICE: checking path2 B2 bucket rclone-test-maqiciw1feki path 040750me/volatile/path2
2025/12/24 04:11:45 NOTICE: (01)  : test volatile
2025/12/24 04:11:45 NOTICE: (02)  : test initial bisync
2025/12/24 04:11:45 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/12/24 04:11:45 DEBUG : B2 bucket rclone-test-maqiciw1feki: Purge remote
2025/12/24 04:11:45 INFO  : B2 bucket rclone-test-maqiciw1feki: cleaning bucket "rclone-test-maqiciw1feki" of all files
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1011ab166805d7bd_d20251224_m041133_c001_v0001185_t0037_u01766549493709")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10326d02572deff5_d20251224_m041133_c001_v0001185_t0000_u01766549493363")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f107793dada62f0fb_d20251224_m041133_c001_v0001105_t0050_u01766549493544")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118607fc5011bb79_d20251224_m041133_c001_v0001185_t0009_u01766549493896")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10326d02572df002_d20251224_m041133_c001_v0001185_t0053_u01766549493644")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10326d02572df00c_d20251224_m041133_c001_v0001185_t0030_u01766549493929")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f118607fc5011bb85_d20251224_m041134_c001_v0001185_t0002_u01766549494087")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f10326d02572df019_d20251224_m041134_c001_v0001185_t0058_u01766549494128")
2025/12/24 04:11:45 DEBUG : 040750me/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115743263763ac24_d20251224_m041136_c001_v0001157_t0045_u01766549496033")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108c3a983426b157_d20251224_m041135_c001_v0001157_t0040_u01766549495468")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100eb1119a450674_d20251224_m041135_c001_v0001185_t0012_u01766549495817")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1118f5540756b694_d20251224_m041135_c001_v0001173_t0005_u01766549495656")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108c3a983426b15b_d20251224_m041135_c001_v0001157_t0049_u01766549495978")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f100eb1119a450682_d20251224_m041136_c001_v0001185_t0003_u01766549496168")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108c3a983426b15d_d20251224_m041136_c001_v0001157_t0013_u01766549496402")
2025/12/24 04:11:46 DEBUG : 040750me/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f115743263763ac28_d20251224_m041136_c001_v0001157_t0034_u01766549496423")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13eee34_d20251224_m041142_c001_v0001185_t0048_u01766549502351")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f537818268_d20251224_m041142_c001_v0001185_t0058_u01766549502125")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1011ab166805d98b_d20251224_m041141_c001_v0001185_t0013_u01766549501730")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec82e1_d20251224_m041141_c001_v0001167_t0046_u01766549501962")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1011ab166805d99f_d20251224_m041141_c001_v0001185_t0028_u01766549501959")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1011ab166805d9a9_d20251224_m041142_c001_v0001185_t0035_u01766549502147")
2025/12/24 04:11:46 DEBUG : 040750me/volatile/path1/file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f537818271_d20251224_m041142_c001_v0001185_t0037_u01766549502314")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path1/file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1189546372ec82f1_d20251224_m041142_c001_v0001167_t0048_u01766549502514")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path1/file8.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f1108fd89e13eee40_d20251224_m041142_c001_v0001185_t0021_u01766549502602")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/RCLONE_TEST: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f111a6b7507dafd7d_d20251224_m041144_c001_v0001185_t0019_u01766549504515")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file1.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f119d2e109f6a03a0_d20251224_m041143_c001_v0001043_t0030_u01766549503941")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file2.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f5378182ea_d20251224_m041144_c001_v0001185_t0008_u01766549504299")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file3.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11319c068edea829_d20251224_m041144_c001_v0001101_t0020_u01766549504148")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file4.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f119d2e109f6a03a2_d20251224_m041144_c001_v0001043_t0007_u01766549504414")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file5.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f5378182fa_d20251224_m041144_c001_v0001185_t0025_u01766549504493")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file6.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f11319c068edea82b_d20251224_m041144_c001_v0001101_t0055_u01766549504545")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file7.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f108d54f537818307_d20251224_m041144_c001_v0001185_t0052_u01766549504679")
2025/12/24 04:11:47 DEBUG : 040750me/volatile/path2/file8.txt: Deleting (id "4_z4f42bd4380ad4beb9fb80e1b_f111a6b7507dafd8d_d20251224_m041144_c001_v0001185_t0021_u01766549504813")
--- PASS: TestBisyncRemoteRemote (238.97s)
    --- PASS: TestBisyncRemoteRemote/resync (85.58s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (132.84s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.47s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.29s)
FAIL
2025/12/24 04:11:48 DEBUG : B2 bucket rclone-test-movixaq2noti: Purge remote
2025/12/24 04:11:48 INFO  : B2 bucket rclone-test-movixaq2noti: cleaning bucket "rclone-test-movixaq2noti" of all files
2025/12/24 04:11:49 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_access_filters$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished ERROR in 4m14.982905637s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/check_access_filters]