"./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/10/15 04:03:51 DEBUG : Creating backend with remote "TestB2:rclone-test-pegukej4fahe"
2025/10/15 04:03:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/15 04:03:52 DEBUG : Creating backend with remote "/tmp/rclone174394700"
=== RUN   TestBisyncLocalRemote
2025/10/15 04:03:52 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo"
2025/10/15 04:03:52 NOTICE: remote: TestB2:rclone-test-juvoxir2zumo
=== RUN   TestBisyncLocalRemote/check_access_filters
2025/10/15 04:03:52 DEBUG : Creating backend with remote "/tmp/040352xo"
2025/10/15 04:03:52 DEBUG : Creating backend with remote "/tmp/040352xo/check_access_filters/path1"
2025/10/15 04:03:52 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo/040352xo"
2025/10/15 04:03:53 INFO  : path2: Making directory
2025/10/15 04:03:54 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2"
2025/10/15 04:03:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/10/15 04:03:55 DEBUG : Creating backend with remote "/tmp/040352xo/initdir/test_check_access_filters-xocutab2"
2025/10/15 04:03:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not: Making directory with metadata
2025/10/15 04:03:55 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/10/15 04:03:55 DEBUG : subdir: Making directory with metadata
2025/10/15 04:03:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/15 04:03:55 DEBUG : subdirX: Making directory with metadata
2025/10/15 04:03:55 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:03:55 INFO  : file1.txt: Copied (new)
2025/10/15 04:03:55 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/10/15 04:03:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/15 04:03:55 INFO  : file2.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/15 04:03:55 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 INFO  : file3.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/10/15 04:03:55 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/10/15 04:03:55 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/10/15 04:03:55 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/15 04:03:55 INFO  : file4.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/10/15 04:03:55 INFO  : subdirX/file20.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/10/15 04:03:55 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:03:55 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir/subdirA: Making directory with metadata
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/10/15 04:03:55 DEBUG : subdir/subdirB: Making directory with metadata
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/10/15 04:03:55 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/15 04:03:55 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:55 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/10/15 04:03:55 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/10/15 04:03:55 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/15 04:03:55 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/10/15 04:03:55 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : Local file system at /tmp/040352xo/initdir/test_check_access_filters-xocutab2: Waiting for checks to finish
2025/10/15 04:03:55 DEBUG : Local file system at /tmp/040352xo/initdir/test_check_access_filters-xocutab2: Waiting for transfers to finish
2025/10/15 04:03:55 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:55 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/10/15 04:03:55 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:55 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/10/15 04:03:55 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/15 04:03:55 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:03:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/10/15 04:03:55 DEBUG : Creating backend with remote "/tmp/040352xo/datadir/test_check_access_filters-duxekus8"
2025/10/15 04:03:55 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:55 DEBUG : Local file system at /tmp/040352xo/datadir/test_check_access_filters-duxekus8: Waiting for checks to finish
2025/10/15 04:03:55 DEBUG : Local file system at /tmp/040352xo/datadir/test_check_access_filters-duxekus8: Waiting for transfers to finish
2025/10/15 04:03:55 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/10/15 04:03:55 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/10/15 04:03:55 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/10/15 04:03:55 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/10/15 04:03:55 INFO  : include-other-filtersfile.txt: Copied (new)
2025/10/15 04:03:55 DEBUG : Waiting for deletions to finish
2025/10/15 04:03:55 ERROR : error listing: directory not found
2025/10/15 04:03:55 ERROR : Local file system at /tmp/040352xo/check_access_filters/path1: Failed to list "": directory not found
2025/10/15 04:03:55 DEBUG : removing 1 level 0 directories
2025/10/15 04:03:55 INFO  : Local file system at /tmp/040352xo/check_access_filters/path1: Removing directory
2025/10/15 04:03:55 ERROR : Failed to rmdir: stat /tmp/040352xo/check_access_filters/path1: no such file or directory
2025/10/15 04:03:55 INFO  : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: cleaning bucket "rclone-test-juvoxir2zumo" of all files
2025/10/15 04:03:56 ERROR : error listing: directory not found
2025/10/15 04:03:56 NOTICE: checking initFs Local file system at /tmp/040352xo/initdir/test_check_access_filters-xocutab2
2025/10/15 04:03:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir-not: Making directory with metadata
2025/10/15 04:03:56 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/10/15 04:03:56 DEBUG : subdir: Making directory with metadata
2025/10/15 04:03:56 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:03:56 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/15 04:03:56 DEBUG : subdirX: Making directory with metadata
2025/10/15 04:03:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:03:56 INFO  : file1.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/15 04:03:56 INFO  : file3.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/15 04:03:56 INFO  : file2.txt: Copied (new)
2025/10/15 04:03:56 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/10/15 04:03:56 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/15 04:03:56 INFO  : file4.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir/subdirA: Making directory with metadata
2025/10/15 04:03:56 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/10/15 04:03:56 DEBUG : subdir/subdirB: Making directory with metadata
2025/10/15 04:03:56 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/10/15 04:03:56 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/10/15 04:03:56 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/10/15 04:03:56 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/15 04:03:56 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:03:56 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdir-not/file20.txt.e2b8d4dd.partial: renamed to: subdir-not/file20.txt
2025/10/15 04:03:56 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/15 04:03:56 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:03:56 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/10/15 04:03:56 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdir/subdirB/file30.txt.ca7c4d29.partial: renamed to: subdir/subdirB/file30.txt
2025/10/15 04:03:56 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:56 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdirX/file20.txt.0c4add8a.partial: renamed to: subdirX/file20.txt
2025/10/15 04:03:56 INFO  : subdirX/file20.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/file30.txt.0106cff7.partial: renamed to: subdirX/subdirX1/file30.txt
2025/10/15 04:03:56 DEBUG : subdir/subdirB/RCLONE_TEST.37da2e99.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 DEBUG : Local file system at /tmp/040352xo/check_access_filters/path1: Waiting for checks to finish
2025/10/15 04:03:56 DEBUG : subdirX/subdirX1/RCLONE_TEST.09a85483.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 DEBUG : Local file system at /tmp/040352xo/check_access_filters/path1: Waiting for transfers to finish
2025/10/15 04:03:56 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 DEBUG : subdir/subdirA/file30.txt.03485624.partial: renamed to: subdir/subdirA/file30.txt
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:03:56 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/file30.txt.e99a79ee.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/10/15 04:03:56 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdir/subdirA/RCLONE_TEST.b780822c.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:03:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.51791ac5.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/10/15 04:03:56 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/15 04:03:56 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:03:56 NOTICE: checking Path1 Local file system at /tmp/040352xo/check_access_filters/path1
2025/10/15 04:03:57 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 INFO  : subdir-not: Making directory
2025/10/15 04:03:57 INFO  : subdir: Making directory
2025/10/15 04:03:57 INFO  : subdirX: Making directory
2025/10/15 04:03:57 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 INFO  : subdirX/subdirX1: Making directory
2025/10/15 04:03:57 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 INFO  : subdir/subdirA: Making directory
2025/10/15 04:03:57 INFO  : subdir/subdirB: Making directory
2025/10/15 04:03:57 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 INFO  : subdir-not/subdir-not2: Making directory
2025/10/15 04:03:57 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:57 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Waiting for checks to finish
2025/10/15 04:03:57 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Waiting for transfers to finish
2025/10/15 04:03:57 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:57 INFO  : file3.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : file1.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : file2.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : file4.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : subdirX/file20.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : subdir-not/file20.txt: Copied (new)
2025/10/15 04:03:58 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:58 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/15 04:03:58 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:58 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/10/15 04:03:59 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:59 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/15 04:03:59 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:59 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:03:59 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:03:59 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/15 04:03:59 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:59 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/10/15 04:03:59 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:03:59 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/10/15 04:03:59 NOTICE: checking path2 B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2
2025/10/15 04:03:59 NOTICE: (01)  : test check-access-filters
2025/10/15 04:03:59 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/10/15 04:03:59 NOTICE: (03)  : copy-file /tmp/040352xo/datadir/test_check_access_filters-duxekus8/exclude-other-filtersfile.txt /tmp/040352xo/workdir/
2025/10/15 04:03:59 DEBUG : copyFile "/tmp/040352xo/datadir/test_check_access_filters-duxekus8/exclude-other-filtersfile.txt" to "/tmp/040352xo/workdir/" as ""
2025/10/15 04:03:59 DEBUG : Creating backend with remote "/tmp/040352xo/datadir/test_check_access_filters-duxekus8/exclude-other-filtersfile.txt"
2025/10/15 04:03:59 DEBUG : Creating backend with remote "/tmp/040352xo/workdir/"
2025/10/15 04:03:59 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/040352xo/workdir" as "exclude-other-filtersfile.txt"
2025/10/15 04:03:59 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/15 04:03:59 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/10/15 04:03:59 DEBUG : exclude-other-filtersfile.txt.955454a7.partial: renamed to: exclude-other-filtersfile.txt
2025/10/15 04:03:59 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/10/15 04:03:59 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/10/15 04:03:59 NOTICE: (05)  : bisync resync filters-file=/tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:03 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:04:03 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:04:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/15 04:04:03 INFO  : Synching Path1 "/tmp/040352xo/check_access_filters/path1/" with Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:03 INFO  : Using filters file /tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:03 INFO  : Storing filters file hash to /tmp/040352xo/workdir/exclude-other-filtersfile.txt.md5
2025/10/15 04:04:03 INFO  : Copying Path2 files to Path1
2025/10/15 04:04:03 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:04:04 INFO  : There was nothing to transfer
2025/10/15 04:04:04 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:04:04 INFO  : There was nothing to transfer
2025/10/15 04:04:04 INFO  : Resync updating listings
2025/10/15 04:04:04 INFO  : Validating listings for Path1 "/tmp/040352xo/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:04 INFO  : Bisync successful
2025/10/15 04:04:04 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/10/15 04:04:04 NOTICE: (07)  : bisync check-access filters-file=/tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:08 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:04:08 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:04:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/15 04:04:08 INFO  : Synching Path1 "/tmp/040352xo/check_access_filters/path1/" with Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:08 INFO  : Using filters file /tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:08 INFO  : Building Path1 and Path2 listings
2025/10/15 04:04:08 INFO  : Path1 checking for diffs
2025/10/15 04:04:08 INFO  : Path2 checking for diffs
2025/10/15 04:04:08 INFO  : Checking access health
2025/10/15 04:04:08 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/10/15 04:04:08 INFO  : No changes found
2025/10/15 04:04:08 INFO  : Updating listings
2025/10/15 04:04:08 INFO  : Validating listings for Path1 "/tmp/040352xo/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:08 INFO  : Bisync successful
2025/10/15 04:04:08 NOTICE: (08)  : copy-listings exclude-initial
2025/10/15 04:04:08 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2025/10/15 04:04:08 NOTICE: (10)  : delete-file TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/10/15 04:04:08 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir/subdirA/"
2025/10/15 04:04:09 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir/subdirA/" to be canonical "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir/subdirA"
2025/10/15 04:04:10 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:10 NOTICE: (11)  : delete-file /tmp/040352xo/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/10/15 04:04:10 DEBUG : Creating backend with remote "/tmp/040352xo/check_access_filters/path1/subdir-not/"
2025/10/15 04:04:10 DEBUG : fs cache: renaming cache item "/tmp/040352xo/check_access_filters/path1/subdir-not/" to be canonical "/tmp/040352xo/check_access_filters/path1/subdir-not"
2025/10/15 04:04:10 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:10 NOTICE: (12)  : delete-file TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/10/15 04:04:10 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir-not/subdir-not2/"
2025/10/15 04:04:11 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/subdir-not/subdir-not2"
2025/10/15 04:04:12 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:12 NOTICE: (13)  : delete-file /tmp/040352xo/check_access_filters/path1/subdirX/RCLONE_TEST
2025/10/15 04:04:12 DEBUG : Creating backend with remote "/tmp/040352xo/check_access_filters/path1/subdirX/"
2025/10/15 04:04:12 DEBUG : fs cache: renaming cache item "/tmp/040352xo/check_access_filters/path1/subdirX/" to be canonical "/tmp/040352xo/check_access_filters/path1/subdirX"
2025/10/15 04:04:12 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:12 NOTICE: (14)  : test EXCLUDE - test should PASS
2025/10/15 04:04:12 NOTICE: (15)  : bisync check-access filters-file=/tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:04:16 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:16 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:04:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/15 04:04:16 INFO  : Synching Path1 "/tmp/040352xo/check_access_filters/path1/" with Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:16 INFO  : Using filters file /tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:16 INFO  : Building Path1 and Path2 listings
2025/10/15 04:04:17 INFO  : Path1 checking for diffs
2025/10/15 04:04:17 INFO  : Path2 checking for diffs
2025/10/15 04:04:17 INFO  : Checking access health
2025/10/15 04:04:17 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/10/15 04:04:17 INFO  : No changes found
2025/10/15 04:04:17 INFO  : Updating listings
2025/10/15 04:04:17 INFO  : Validating listings for Path1 "/tmp/040352xo/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:17 INFO  : Bisync successful
2025/10/15 04:04:17 NOTICE: (16)  : copy-listings exclude-pass-run
2025/10/15 04:04:17 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2025/10/15 04:04:17 NOTICE: (18)  : delete-file TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/RCLONE_TEST
2025/10/15 04:04:17 DEBUG : Creating backend with remote "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:18 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/" to be canonical "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2"
2025/10/15 04:04:18 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:18 NOTICE: (19)  : delete-file /tmp/040352xo/check_access_filters/path1/subdir/RCLONE_TEST
2025/10/15 04:04:18 DEBUG : Creating backend with remote "/tmp/040352xo/check_access_filters/path1/subdir/"
2025/10/15 04:04:18 DEBUG : fs cache: renaming cache item "/tmp/040352xo/check_access_filters/path1/subdir/" to be canonical "/tmp/040352xo/check_access_filters/path1/subdir"
2025/10/15 04:04:18 INFO  : RCLONE_TEST: Deleted
2025/10/15 04:04:18 NOTICE: (20)  : test EXCLUDE - test should ABORT
2025/10/15 04:04:18 NOTICE: (21)  : bisync check-access filters-file=/tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:22 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:04:22 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:04:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/15 04:04:22 INFO  : Synching Path1 "/tmp/040352xo/check_access_filters/path1/" with Path2 "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/"
2025/10/15 04:04:22 INFO  : Using filters file /tmp/040352xo/workdir/exclude-other-filtersfile.txt
2025/10/15 04:04:22 INFO  : Building Path1 and Path2 listings
2025/10/15 04:04:23 INFO  : Path1 checking for diffs
2025/10/15 04:04:23 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/10/15 04:04:23 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/15 04:04:23 INFO  : Path2 checking for diffs
2025/10/15 04:04:23 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/10/15 04:04:23 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/10/15 04:04:23 INFO  : Checking access health
2025/10/15 04:04:23 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/10/15 04:04:23 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/10/15 04:04:23 ERROR : Bisync critical error: check file check failed
2025/10/15 04:04:23 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/15 04:04:23 NOTICE: Bisync error: bisync aborted
2025/10/15 04:04:23 NOTICE: (22)  : move-listings exclude-error-run
2025/10/15 04:04:23 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2025/10/15 04:04:23 NOTICE: (24)  : test reset to the initial state
2025/10/15 04:04:23 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial /tmp/040352xo/check_access_filters/path1/
2025/10/15 04:04:23 DEBUG : Creating backend with remote "/tmp/040352xo/check_access_filters/path1/"
2025/10/15 04:04:23 DEBUG : fs cache: renaming cache item "/tmp/040352xo/check_access_filters/path1/" to be canonical "/tmp/040352xo/check_access_filters/path1"
2025/10/15 04:04:23 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file1.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file2.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : file3.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/10/15 04:04:23 DEBUG : file4.txt: Unchanged skipping
2025/10/15 04:04:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/15 04:04:23 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/10/15 04:04:23 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:23 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/10/15 04:04:23 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:23 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:23 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/10/15 04:04:23 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/10/15 04:04:23 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/10/15 04:04:23 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:04:23 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir-not/RCLONE_TEST.16d95000.partial: renamed to: subdir-not/RCLONE_TEST
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:04:23 DEBUG : subdirX/RCLONE_TEST.fd5b40a2.partial: renamed to: subdirX/RCLONE_TEST
2025/10/15 04:04:23 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/10/15 04:04:23 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/10/15 04:04:23 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : Local file system at /tmp/040352xo/check_access_filters/path1: Waiting for checks to finish
2025/10/15 04:04:23 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : Local file system at /tmp/040352xo/check_access_filters/path1: Waiting for transfers to finish
2025/10/15 04:04:23 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:04:23 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST
2025/10/15 04:04:23 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/10/15 04:04:23 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:04:23 NOTICE: (26)  : sync-dir /tmp/040352xo/check_access_filters/path1/ TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/
2025/10/15 04:04:23 DEBUG : fs cache: switching user supplied name "/tmp/040352xo/check_access_filters/path1/" for canonical name "/tmp/040352xo/check_access_filters/path1"
2025/10/15 04:04:23 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2/" for canonical name "TestB2:rclone-test-juvoxir2zumo/040352xo/check_access_filters/path2"
2025/10/15 04:04:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:23 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : file1.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : file2.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : file3.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : file4.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdir/file20.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:23 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/10/15 04:04:24 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:24 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/10/15 04:04:24 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:24 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/10/15 04:04:24 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:24 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/15 04:04:24 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Waiting for checks to finish
2025/10/15 04:04:24 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/10/15 04:04:24 DEBUG : B2 bucket rclone-test-juvoxir2zumo path 040352xo/check_access_filters/path2: Waiting for transfers to finish
2025/10/15 04:04:24 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:04:24 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:04:24 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:04:24 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/10/15 04:04:24 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:04:24 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/10/15 04:04:24 DEBUG : Waiting for deletions to finish
2025/10/15 04:04:24 NOTICE: (27)  : copy-file /tmp/040352xo/datadir/test_check_access_filters-duxekus8/include-other-filtersfile.txt /tmp/040352xo/workdir/
2025/10/15 04:04:24 DEBUG : copyFile "/tmp/040352xo/datadir/test_check_access_filters-duxekus8/include-other-filtersfile.txt" to "/tmp/040352xo/workdir/" as ""
2025/10/15 04:04:24 DEBUG : Creating backend with remote "/tmp/040352xo/datadir/test_check_access_filters-duxekus8/include-other-filtersfile.txt"
2025/10/15 04:04:24 DEBUG : Creating backend with remote "/tmp/040352xo/workdir/"
2025/10/15 04:04:24 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/040352xo/workdir" as "include-other-filtersfile.txt"
2025/10/15 04:04:24 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:24 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/10/15 04:04:24 DEBUG : include-other-filtersfile.txt.8126a844.partial: renamed to: include-other-filtersfile.txt
2025/10/15 04:04:24 INFO  : include-other-filtersfile.txt: Copied (new)
2025/10/15 04:04:24 NOTICE: (28)  : bisync resync filters-file=/tmp/040352xo/workdir/include-other-filtersfile.txt
2025/10/15 04:04:24 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1120-16.backblaze.com/b2api/v1/b2_upload_file/7f02edd3009deb6b9f980e1b/c001_v0001120_t0022": EOF
2025/10/15 04:04:24 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1120-16.backblaze.com/b2api/v1/b2_upload_file/7f02edd3009deb6b9f980e1b/c001_v0001120_t0022": EOF)
2025/10/15 04:04:24 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-1120-16.backblaze.com/b2api/v1/b2_upload_file/7f02edd3009deb6b9f980e1b/c001_v0001120_t0022": EOF
        	Test:       	TestBisyncLocalRemote/check_access_filters
2025/10/15 04:04:24 DEBUG : B2 bucket rclone-test-juvoxir2zumo: Purge remote
2025/10/15 04:04:24 INFO  : B2 bucket rclone-test-juvoxir2zumo: cleaning bucket "rclone-test-juvoxir2zumo" of all files
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111c2f5a1941ecdf_d20251015_m040423_c001_v0001044_t0041_u01760501063912")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f405b1185a1224375_d20251015_m040418_c001_v7007000_t0000_u01760501058824")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f11644634e527bedf_d20251015_m040358_c001_v0001120_t0047_u01760501038216")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/file1.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111c2f5a1941ec9d_d20251015_m040357_c001_v0001044_t0039_u01760501037657")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/file2.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe347_d20251015_m040358_c001_v0001183_t0057_u01760501038039")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/file3.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898a6_d20251015_m040357_c001_v0001182_t0003_u01760501037839")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/file4.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898ac_d20251015_m040358_c001_v0001182_t0023_u01760501038051")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f405b1185a1224395_d20251015_m040422_c001_v7007000_t0000_u01760501062594")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1127ef5883bd01b7_d20251015_m040421_c001_v0001106_t0059_u01760501061177")
2025/10/15 04:04:25 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f11644634e527bf2f_d20251015_m040419_c001_v0001120_t0011_u01760501059367")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f405b1185a1224366_d20251015_m040416_c001_v7007000_t0000_u01760501056828")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f118dea2735ad10e3_d20251015_m040415_c001_v0001115_t0018_u01760501055233")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a9489936_d20251015_m040413_c001_v0001182_t0031_u01760501053111")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f415e124fbb5ec41b_d20251015_m040407_c001_v7007000_t0000_u01760501047953")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111876b89ad813be_d20251015_m040406_c001_v0001153_t0016_u01760501046517")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe399_d20251015_m040405_c001_v0001183_t0042_u01760501045117")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f415e124fbb5ec418_d20251015_m040403_c001_v7007000_t0000_u01760501043467")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f11380a40b941dcad_d20251015_m040402_c001_v0001098_t0031_u01760501042092")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/modtime_write_test: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111c2f5a1941ecad_d20251015_m040359_c001_v0001044_t0052_u01760501039731")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f11644634e527bee3_d20251015_m040358_c001_v0001120_t0035_u01760501038649")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/file20.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe356_d20251015_m040358_c001_v0001183_t0006_u01760501038673")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe473_d20251015_m040424_c001_v0001183_t0050_u01760501064447")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f4131931c2e60550a_d20251015_m040412_c001_v7007000_t0000_u01760501052566")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe35d_d20251015_m040359_c001_v0001183_t0058_u01760501039091")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir-not/subdir-not2/file30.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898c0_d20251015_m040359_c001_v0001182_t0037_u01760501039172")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898b4_d20251015_m040358_c001_v0001182_t0041_u01760501038512")
2025/10/15 04:04:26 DEBUG : 040352xo/check_access_filters/path2/subdir/file20.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111c2f5a1941eca7_d20251015_m040358_c001_v0001044_t0045_u01760501038602")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a9489996_d20251015_m040424_c001_v0001182_t0001_u01760501064609")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f4182b62e22d59288_d20251015_m040410_c001_v7007000_t0000_u01760501050546")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898bc_d20251015_m040358_c001_v0001182_t0048_u01760501038977")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirA/file30.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f11644634e527bee7_d20251015_m040359_c001_v0001120_t0059_u01760501039067")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirB/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898b6_d20251015_m040358_c001_v0001182_t0020_u01760501038783")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdir/subdirB/file30.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe359_d20251015_m040358_c001_v0001183_t0035_u01760501038874")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdirX/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f111c2f5a1941eca5_d20251015_m040358_c001_v0001044_t0003_u01760501038171")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdirX/file20.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe34b_d20251015_m040358_c001_v0001183_t0001_u01760501038234")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198b105a94898b2_d20251015_m040358_c001_v0001182_t0057_u01760501038297")
2025/10/15 04:04:27 DEBUG : 040352xo/check_access_filters/path2/subdirX/subdirX1/file30.txt: Deleting (id "4_z7f02edd3009deb6b9f980e1b_f1198d25c1debe34f_d20251015_m040358_c001_v0001183_t0031_u01760501038467")
--- FAIL: TestBisyncLocalRemote (36.09s)
    --- FAIL: TestBisyncLocalRemote/check_access_filters (32.10s)
=== RUN   TestBisyncRemoteRemote
2025/10/15 04:04:28 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho"
2025/10/15 04:04:28 NOTICE: remote: TestB2:rclone-test-feyonin0xeho
=== RUN   TestBisyncRemoteRemote/resync
2025/10/15 04:04:28 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa"
2025/10/15 04:04:29 INFO  : path1: Making directory
2025/10/15 04:04:30 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1"
2025/10/15 04:04:31 INFO  : path2: Making directory
2025/10/15 04:04:31 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2"
2025/10/15 04:04:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/10/15 04:04:33 DEBUG : Creating backend with remote "/tmp/040428sa/initdir/test_resync-ripazed7"
2025/10/15 04:04:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : Local file system at /tmp/040428sa/initdir/test_resync-ripazed7: Waiting for checks to finish
2025/10/15 04:04:33 DEBUG : Local file system at /tmp/040428sa/initdir/test_resync-ripazed7: Waiting for transfers to finish
2025/10/15 04:04:33 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:04:33 INFO  : file1.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/15 04:04:33 INFO  : file2.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/15 04:04:33 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/15 04:04:33 INFO  : file5.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/15 04:04:33 INFO  : file4.txt: Copied (new)
2025/10/15 04:04:33 INFO  : file3.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:04:33 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:04:33 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:04:33 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/15 04:04:33 INFO  : file6.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:04:33 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/15 04:04:33 INFO  : file7.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/10/15 04:04:33 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4"
2025/10/15 04:04:33 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:33 DEBUG : Local file system at /tmp/040428sa/datadir/test_resync-fexonan4: Waiting for checks to finish
2025/10/15 04:04:33 DEBUG : Local file system at /tmp/040428sa/datadir/test_resync-fexonan4: Waiting for transfers to finish
2025/10/15 04:04:33 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:04:33 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/10/15 04:04:33 INFO  : fileA.txt: Copied (new)
2025/10/15 04:04:33 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:04:33 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/10/15 04:04:33 INFO  : fileB.txt: Copied (new)
2025/10/15 04:04:33 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:04:33 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:04:34 NOTICE: checking initFs Local file system at /tmp/040428sa/initdir/test_resync-ripazed7
2025/10/15 04:04:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:35 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Waiting for checks to finish
2025/10/15 04:04:35 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Waiting for transfers to finish
2025/10/15 04:04:36 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file3.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file1.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file5.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file4.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:04:36 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:04:36 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file6.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file2.txt: Copied (new)
2025/10/15 04:04:36 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:36 INFO  : file7.txt: Copied (new)
2025/10/15 04:04:36 NOTICE: checking Path1 B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1
2025/10/15 04:04:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:04:37 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Waiting for checks to finish
2025/10/15 04:04:37 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Waiting for transfers to finish
2025/10/15 04:04:38 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file3.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file1.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file4.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:04:38 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:04:38 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file2.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file6.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file7.txt: Copied (new)
2025/10/15 04:04:38 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:04:38 INFO  : file5.txt: Copied (new)
2025/10/15 04:04:38 NOTICE: checking path2 B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2
2025/10/15 04:04:39 NOTICE: (01)  : test resync
2025/10/15 04:04:39 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/10/15 04:04:39 NOTICE: (03)  : purge-children TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/
2025/10/15 04:04:39 DEBUG : RCLONE_TEST: Remove file
2025/10/15 04:04:39 DEBUG : file1.txt: Remove file
2025/10/15 04:04:39 DEBUG : file2.txt: Remove file
2025/10/15 04:04:39 DEBUG : file3.txt: Remove file
2025/10/15 04:04:39 DEBUG : file4.txt: Remove file
2025/10/15 04:04:40 DEBUG : file5.txt: Remove file
2025/10/15 04:04:40 DEBUG : file6.txt: Remove file
2025/10/15 04:04:40 DEBUG : file7.txt: Remove file
2025/10/15 04:04:40 NOTICE: (04)  : bisync resync
2025/10/15 04:04:48 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:48 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:04:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:04: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/10/15 04:04:48 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:04:48 INFO  : Copying Path2 files to Path1
2025/10/15 04:04:48 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:04:48 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file3.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file2.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file4.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file5.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file6.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : file7.txt: Copied (server-side copy)
2025/10/15 04:04:49 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:04:49 INFO  : Resync updating listings
2025/10/15 04:04:49 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:04:49 INFO  : Bisync successful
2025/10/15 04:04:49 NOTICE: (05)  : move-listings empty-path1
2025/10/15 04:04:49 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/10/15 04:04:49 NOTICE: (07)  : purge-children TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/
2025/10/15 04:04:50 DEBUG : RCLONE_TEST: Remove file
2025/10/15 04:04:50 DEBUG : file1.txt: Remove file
2025/10/15 04:04:50 DEBUG : file2.txt: Remove file
2025/10/15 04:04:50 DEBUG : file3.txt: Remove file
2025/10/15 04:04:50 DEBUG : file4.txt: Remove file
2025/10/15 04:04:51 DEBUG : file5.txt: Remove file
2025/10/15 04:04:51 DEBUG : file6.txt: Remove file
2025/10/15 04:04:51 DEBUG : file7.txt: Remove file
2025/10/15 04:04:51 NOTICE: (08)  : bisync resync
2025/10/15 04:05:00 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:00 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:05:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:05:00 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:00 INFO  : Copying Path2 files to Path1
2025/10/15 04:05:00 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:05:00 INFO  : There was nothing to transfer
2025/10/15 04:05:00 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:05:01 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/15 04:05:01 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:05:01 INFO  : file3.txt: Copied (server-side copy)
2025/10/15 04:05:01 INFO  : file5.txt: Copied (server-side copy)
2025/10/15 04:05:01 INFO  : file2.txt: Copied (server-side copy)
2025/10/15 04:05:01 INFO  : file4.txt: Copied (server-side copy)
2025/10/15 04:05:02 INFO  : file6.txt: Copied (server-side copy)
2025/10/15 04:05:02 INFO  : file7.txt: Copied (server-side copy)
2025/10/15 04:05:02 INFO  : Resync updating listings
2025/10/15 04:05:02 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:02 INFO  : Bisync successful
2025/10/15 04:05:02 NOTICE: (09)  : move-listings empty-path2
2025/10/15 04:05:02 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/10/15 04:05:02 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/040428sa/datadir/test_resync-fexonan4/ fileA.txt
2025/10/15 04:05:02 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/"
2025/10/15 04:05:02 DEBUG : fs cache: renaming cache item "/tmp/040428sa/datadir/test_resync-fexonan4/" to be canonical "/tmp/040428sa/datadir/test_resync-fexonan4"
2025/10/15 04:05:02 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/10/15 04:05:02 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/040428sa/datadir/test_resync-fexonan4/ fileB.txt
2025/10/15 04:05:02 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync-fexonan4/" for canonical name "/tmp/040428sa/datadir/test_resync-fexonan4"
2025/10/15 04:05:02 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/10/15 04:05:02 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/10/15 04:05:02 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/10/15 04:05:02 NOTICE: (15)  : delete-file TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/file1.txt
2025/10/15 04:05:02 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:03 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/" to be canonical "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2"
2025/10/15 04:05:04 INFO  : file1.txt: Deleted
2025/10/15 04:05:04 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/10/15 04:05:04 NOTICE: (17)  : delete-file TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/file2.txt
2025/10/15 04:05:04 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/"
2025/10/15 04:05:05 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" to be canonical "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1"
2025/10/15 04:05:05 INFO  : file2.txt: Deleted
2025/10/15 04:05:05 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/10/15 04:05:05 NOTICE: (19)  : copy-as /tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/ file3.txt
2025/10/15 04:05:05 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/" as "file3.txt"
2025/10/15 04:05:05 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt"
2025/10/15 04:05:05 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:07 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2" as "file3.txt"
2025/10/15 04:05:07 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/15 04:05:08 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/15 04:05:08 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/10/15 04:05:08 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/10/15 04:05:08 NOTICE: (21)  : delete-file TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/file4.txt
2025/10/15 04:05:08 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" for canonical name "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1"
2025/10/15 04:05:09 INFO  : file4.txt: Deleted
2025/10/15 04:05:09 NOTICE: (22)  : copy-as /tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/ file4.txt
2025/10/15 04:05:09 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/" as "file4.txt"
2025/10/15 04:05:09 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt"
2025/10/15 04:05:09 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:10 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2" as "file4.txt"
2025/10/15 04:05:10 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/15 04:05:11 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/15 04:05:11 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/10/15 04:05:11 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/10/15 04:05:11 NOTICE: (24)  : copy-as /tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/ file5.txt
2025/10/15 04:05:11 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/" as "file5.txt"
2025/10/15 04:05:11 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt"
2025/10/15 04:05:11 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:12 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2" as "file5.txt"
2025/10/15 04:05:13 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/10/15 04:05:15 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/15 04:05:15 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/10/15 04:05:15 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/10/15 04:05:15 NOTICE: (26)  : copy-as /tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/ file6.txt
2025/10/15 04:05:15 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" as "file6.txt"
2025/10/15 04:05:15 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/fileB.txt"
2025/10/15 04:05:15 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/"
2025/10/15 04:05:16 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1" as "file6.txt"
2025/10/15 04:05:16 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/10/15 04:05:18 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/15 04:05:18 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/10/15 04:05:18 NOTICE: (27)  : copy-as /tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/ file6.txt
2025/10/15 04:05:18 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/" as "file6.txt"
2025/10/15 04:05:18 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync-fexonan4/fileA.txt"
2025/10/15 04:05:18 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:19 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2" as "file6.txt"
2025/10/15 04:05:19 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/15 04:05:20 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/15 04:05:20 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/10/15 04:05:20 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/10/15 04:05:20 NOTICE: (29)  : test run bisync with resync
2025/10/15 04:05:20 NOTICE: (30)  : bisync resync
2025/10/15 04:05:29 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:29 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:05:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:05:29 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:29 INFO  : Copying Path2 files to Path1
2025/10/15 04:05:29 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:05:29 INFO  : file2.txt: Copied (server-side copy)
2025/10/15 04:05:30 INFO  : file4.txt: Copied (server-side copy)
2025/10/15 04:05:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:05:30 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:05:31 INFO  : file3.txt: Copied (server-side copy)
2025/10/15 04:05:31 INFO  : file5.txt: Copied (server-side copy)
2025/10/15 04:05:31 INFO  : file6.txt: Updated modification time in destination
2025/10/15 04:05:31 INFO  : Resync updating listings
2025/10/15 04:05:31 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:31 INFO  : Bisync successful
2025/10/15 04:05:31 NOTICE: (31)  : copy-listings mixed-diffs
2025/10/15 04:05:31 NOTICE: (32)  : test run normal bisync
2025/10/15 04:05:31 NOTICE: (33)  : bisync
2025/10/15 04:05:38 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:38 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:05:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:05:38 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:38 INFO  : Building Path1 and Path2 listings
2025/10/15 04:05:38 INFO  : Path1 checking for diffs
2025/10/15 04:05:38 INFO  : Path2 checking for diffs
2025/10/15 04:05:38 INFO  : No changes found
2025/10/15 04:05:38 INFO  : Updating listings
2025/10/15 04:05:38 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:38 INFO  : Bisync successful
2025/10/15 04:05:38 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/10/15 04:05:38 NOTICE: (35)  : purge-children TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/
2025/10/15 04:05:39 DEBUG : RCLONE_TEST: Remove file
2025/10/15 04:05:39 DEBUG : file1.txt: Remove file
2025/10/15 04:05:39 DEBUG : file2.txt: Remove file
2025/10/15 04:05:39 DEBUG : file3.txt: Remove file
2025/10/15 04:05:39 DEBUG : file4.txt: Remove file
2025/10/15 04:05:40 DEBUG : file5.txt: Remove file
2025/10/15 04:05:40 DEBUG : file6.txt: Remove file
2025/10/15 04:05:40 DEBUG : file7.txt: Remove file
2025/10/15 04:05:40 NOTICE: (36)  : bisync
2025/10/15 04:05:49 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:49 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:05:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:05:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:05:49 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync/path2/"
2025/10/15 04:05:49 INFO  : Building Path1 and Path2 listings
2025/10/15 04:05:49 INFO  : Path1 checking for diffs
2025/10/15 04:05:49 INFO  : Path2 checking for diffs
2025/10/15 04:05:49 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/040428sa/workdir/TestB2_rclone-test-feyonin0xeho_040428sa_resync_path1..TestB2_rclone-test-feyonin0xeho_040428sa_resync_path2.path2.lst-new
2025/10/15 04:05:49 ERROR : Bisync critical error: empty current Path2 listing: /tmp/040428sa/workdir/TestB2_rclone-test-feyonin0xeho_040428sa_resync_path1..TestB2_rclone-test-feyonin0xeho_040428sa_resync_path2.path2.lst-new
2025/10/15 04:05:49 ERROR : Bisync aborted. Must run --resync to recover.
2025/10/15 04:05:49 NOTICE: Bisync error: bisync aborted
2025/10/15 04:05:49 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:05:49 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1021a87ed0fafc91_d20251015_m040448_c001_v0001183_t0046_u01760501088654")
2025/10/15 04:05:49 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec5fc_d20251015_m040439_c001_v7007000_t0000_u01760501079294")
2025/10/15 04:05:49 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111c2f5a1941ed08_d20251015_m040436_c001_v0001044_t0039_u01760501076242")
2025/10/15 04:05:49 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f101429c8384366de_d20251015_m040448_c001_v0001044_t0040_u01760501088974")
2025/10/15 04:05:49 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec600_d20251015_m040439_c001_v7007000_t0000_u01760501079475")
2025/10/15 04:05:49 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119deb63921a510f_d20251015_m040436_c001_v0001181_t0012_u01760501076052")
2025/10/15 04:05:49 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f114d0e24ff34728e_d20251015_m040529_c001_v0001165_t0050_u01760501129609")
2025/10/15 04:05:49 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e9af_d20251015_m040505_c001_v7007000_t0000_u01760501105820")
2025/10/15 04:05:49 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f117d061639ea27a3_d20251015_m040449_c001_v0001130_t0039_u01760501089042")
2025/10/15 04:05:49 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec606_d20251015_m040439_c001_v7007000_t0000_u01760501079655")
2025/10/15 04:05:49 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1196ce7482dcb17e_d20251015_m040435_c001_v0001144_t0012_u01760501075867")
2025/10/15 04:05:49 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f101fff31e7b741e8_d20251015_m040449_c001_v0001029_t0001_u01760501089032")
2025/10/15 04:05:49 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec608_d20251015_m040439_c001_v7007000_t0000_u01760501079844")
2025/10/15 04:05:50 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103f5143f454ca3e_d20251015_m040435_c001_v0001136_t0048_u01760501075730")
2025/10/15 04:05:50 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1146ae1d8c8fa087_d20251015_m040529_c001_v0001183_t0011_u01760501129894")
2025/10/15 04:05:50 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e9e3_d20251015_m040508_c001_v7007000_t0000_u01760501108998")
2025/10/15 04:05:50 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10595a898b1a5db8_d20251015_m040449_c001_v0001144_t0029_u01760501089046")
2025/10/15 04:05:50 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec609_d20251015_m040440_c001_v7007000_t0000_u01760501080025")
2025/10/15 04:05:50 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103f5143f454ca40_d20251015_m040436_c001_v0001136_t0016_u01760501076148")
2025/10/15 04:05:50 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1066e132ce1d42f7_d20251015_m040449_c001_v0001176_t0003_u01760501089407")
2025/10/15 04:05:50 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec60b_d20251015_m040440_c001_v7007000_t0000_u01760501080206")
2025/10/15 04:05:50 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119deb63921a5111_d20251015_m040436_c001_v0001181_t0038_u01760501076265")
2025/10/15 04:05:50 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115f0ba16f292bb1_d20251015_m040517_c001_v0001113_t0003_u01760501117737")
2025/10/15 04:05:50 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115a1aac96f7f9e4_d20251015_m040449_c001_v0001183_t0028_u01760501089482")
2025/10/15 04:05:50 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec60c_d20251015_m040440_c001_v7007000_t0000_u01760501080388")
2025/10/15 04:05:50 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119deb63921a5113_d20251015_m040436_c001_v0001181_t0043_u01760501076491")
2025/10/15 04:05:50 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f100ddf63639b61cb_d20251015_m040449_c001_v0001176_t0013_u01760501089576")
2025/10/15 04:05:50 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec60d_d20251015_m040440_c001_v7007000_t0000_u01760501080569")
2025/10/15 04:05:50 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103f5143f454ca42_d20251015_m040436_c001_v0001136_t0054_u01760501076606")
2025/10/15 04:05:50 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067eb24_d20251015_m040544_c001_v7007000_t0000_u01760501144321")
2025/10/15 04:05:50 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1181926bff86853d_d20251015_m040543_c001_v0001165_t0019_u01760501143136")
2025/10/15 04:05:50 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111c2f5a1941edc6_d20251015_m040541_c001_v0001044_t0021_u01760501141075")
2025/10/15 04:05:50 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067eafe_d20251015_m040534_c001_v7007000_t0000_u01760501134917")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f107e808e6e1d5656_d20251015_m040533_c001_v0001130_t0021_u01760501133556")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103f5143f454cc49_d20251015_m040531_c001_v0001136_t0009_u01760501131606")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067ea9e_d20251015_m040525_c001_v7007000_t0000_u01760501125200")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f104301fa1bdb1f98_d20251015_m040524_c001_v0001179_t0011_u01760501123999")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1196ce7482dcb2b0_d20251015_m040521_c001_v0001144_t0030_u01760501121346")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec6a4_d20251015_m040456_c001_v7007000_t0000_u01760501096082")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f109e181f767cdac9_d20251015_m040453_c001_v0001144_t0045_u01760501093874")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119deb63921a515e_d20251015_m040452_c001_v0001181_t0036_u01760501092061")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ec61a_d20251015_m040443_c001_v7007000_t0000_u01760501083985")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1035ac2cdeb961c2_d20251015_m040442_c001_v0001183_t0006_u01760501082778")
2025/10/15 04:05:51 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111c2f5a1941ed18_d20251015_m040440_c001_v0001044_t0028_u01760501080752")
2025/10/15 04:05:52 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:05:52 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245fa_d20251015_m040539_c001_v7007000_t0000_u01760501139254")
2025/10/15 04:05:52 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10601403b57c0c64_d20251015_m040500_c001_v0001105_t0040_u01760501100770")
2025/10/15 04:05:52 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e93f_d20251015_m040450_c001_v7007000_t0000_u01760501090221")
2025/10/15 04:05:52 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105439e708aacdd0_d20251015_m040438_c001_v0001181_t0047_u01760501078424")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245fb_d20251015_m040539_c001_v7007000_t0000_u01760501139435")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f100ddf63639b64ca_d20251015_m040530_c001_v0001176_t0002_u01760501130321")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f41185a1fa5e75dbd_d20251015_m040504_c001_v7007000_t0000_u01760501104004")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f104301fa1bdb1edc_d20251015_m040501_c001_v0001179_t0015_u01760501101148")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e940_d20251015_m040450_c001_v7007000_t0000_u01760501090405")
2025/10/15 04:05:52 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1038644e94a9d760_d20251015_m040437_c001_v0001099_t0031_u01760501077855")
2025/10/15 04:05:52 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245fc_d20251015_m040539_c001_v7007000_t0000_u01760501139617")
2025/10/15 04:05:52 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f101fc2e7fc850165_d20251015_m040501_c001_v0001115_t0003_u01760501101155")
2025/10/15 04:05:52 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e941_d20251015_m040450_c001_v7007000_t0000_u01760501090604")
2025/10/15 04:05:52 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f102070dc66c329d3_d20251015_m040438_c001_v0001117_t0038_u01760501078042")
2025/10/15 04:05:52 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245fd_d20251015_m040539_c001_v7007000_t0000_u01760501139799")
2025/10/15 04:05:53 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f118a415cc41b2ad4_d20251015_m040530_c001_v0001099_t0054_u01760501130727")
2025/10/15 04:05:53 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1092bff3472208f9_d20251015_m040508_c001_v0001183_t0019_u01760501108616")
2025/10/15 04:05:53 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113809e26b0ebdca_d20251015_m040501_c001_v0001043_t0052_u01760501101164")
2025/10/15 04:05:53 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e943_d20251015_m040450_c001_v7007000_t0000_u01760501090785")
2025/10/15 04:05:53 DEBUG : file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113b4ab61f48f8c0_d20251015_m040438_c001_v0001182_t0005_u01760501078237")
2025/10/15 04:05:53 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245fe_d20251015_m040539_c001_v7007000_t0000_u01760501139984")
2025/10/15 04:05:53 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f100d32ac40e004aa_d20251015_m040511_c001_v0001184_t0031_u01760501111755")
2025/10/15 04:05:53 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f116504c3799c75f7_d20251015_m040501_c001_v0001172_t0047_u01760501101216")
2025/10/15 04:05:53 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e946_d20251015_m040450_c001_v7007000_t0000_u01760501090966")
2025/10/15 04:05:53 DEBUG : file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113b4ab61f48f8c2_d20251015_m040438_c001_v0001182_t0022_u01760501078443")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245ff_d20251015_m040540_c001_v7007000_t0000_u01760501140167")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1063f35ecfcc3471_d20251015_m040530_c001_v0001136_t0018_u01760501130548")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105b6baf584ee244_d20251015_m040514_c001_v0001106_t0019_u01760501114520")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11873fa2d29a070e_d20251015_m040501_c001_v0001183_t0037_u01760501101415")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e948_d20251015_m040451_c001_v7007000_t0000_u01760501091148")
2025/10/15 04:05:53 DEBUG : file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1038644e94a9d764_d20251015_m040438_c001_v0001099_t0056_u01760501078488")
2025/10/15 04:05:53 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a1224600_d20251015_m040540_c001_v7007000_t0000_u01760501140348")
2025/10/15 04:05:53 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f109ee0c645297880_d20251015_m040530_c001_v0001040_t0043_u01760501130696")
2025/10/15 04:05:53 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f118dbacca8e65269_d20251015_m040520_c001_v0001172_t0032_u01760501120649")
2025/10/15 04:05:53 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11676a921d5e5115_d20251015_m040501_c001_v0001093_t0005_u01760501101674")
2025/10/15 04:05:54 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e94a_d20251015_m040451_c001_v7007000_t0000_u01760501091331")
2025/10/15 04:05:54 DEBUG : file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113b4ab61f48f8c4_d20251015_m040438_c001_v0001182_t0007_u01760501078645")
2025/10/15 04:05:54 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a1224601_d20251015_m040540_c001_v7007000_t0000_u01760501140530")
2025/10/15 04:05:54 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10992f9b0ec777c8_d20251015_m040501_c001_v0001105_t0005_u01760501101693")
2025/10/15 04:05:54 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e94c_d20251015_m040451_c001_v7007000_t0000_u01760501091513")
2025/10/15 04:05:54 DEBUG : file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105439e708aacdd4_d20251015_m040438_c001_v0001181_t0025_u01760501078664")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f4103909a6942bc50_d20251015_m040548_c001_v7007000_t0000_u01760501148954")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f118552e4b77b6288_d20251015_m040547_c001_v0001184_t0054_u01760501147759")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f102070dc66c32b04_d20251015_m040544_c001_v0001117_t0008_u01760501144872")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f405b1185a12245f7_d20251015_m040538_c001_v7007000_t0000_u01760501138697")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103a1afd27cc02ac_d20251015_m040537_c001_v0001176_t0043_u01760501137467")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1038644e94a9d85f_d20251015_m040535_c001_v0001099_t0050_u01760501135465")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f418b4b763a7160da_d20251015_m040529_c001_v7007000_t0000_u01760501129228")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10829bf658e5a768_d20251015_m040527_c001_v0001100_t0048_u01760501127869")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105439e708aacf2d_d20251015_m040525_c001_v0001181_t0047_u01760501125747")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f409a344462bab1a0_d20251015_m040500_c001_v7007000_t0000_u01760501100197")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f106c2b3685adce9d_d20251015_m040458_c001_v0001101_t0038_u01760501098747")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113b4ab61f48f987_d20251015_m040456_c001_v0001182_t0053_u01760501096629")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f42182d912067e93e_d20251015_m040448_c001_v7007000_t0000_u01760501088263")
2025/10/15 04:05:54 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f117d5c466bd99a11_d20251015_m040447_c001_v0001183_t0043_u01760501087052")
2025/10/15 04:05:55 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f102070dc66c329f0_d20251015_m040444_c001_v0001117_t0018_u01760501084529")
    bisync_test.go:608: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/10/15 04:05:55 INFO  : path1: Making directory
2025/10/15 04:05:55 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1"
2025/10/15 04:05:56 INFO  : path2: Making directory
2025/10/15 04:05:56 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2"
2025/10/15 04:05:57 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/10/15 04:05:57 DEBUG : Creating backend with remote "/tmp/040428sa/initdir/test_resync_modes-zekutib4"
2025/10/15 04:05:57 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : Local file system at /tmp/040428sa/initdir/test_resync_modes-zekutib4: Waiting for checks to finish
2025/10/15 04:05:57 DEBUG : Local file system at /tmp/040428sa/initdir/test_resync_modes-zekutib4: Waiting for transfers to finish
2025/10/15 04:05:57 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:05:57 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:05:57 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/15 04:05:57 INFO  : file2.txt: Copied (new)
2025/10/15 04:05:57 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:05:57 INFO  : file1.txt: Copied (new)
2025/10/15 04:05:57 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:05:57 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:05:57 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:05:57 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/10/15 04:05:57 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:05:57 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:57 DEBUG : Local file system at /tmp/040428sa/datadir/test_resync_modes-hatayul6: Waiting for checks to finish
2025/10/15 04:05:57 DEBUG : Local file system at /tmp/040428sa/datadir/test_resync_modes-hatayul6: Waiting for transfers to finish
2025/10/15 04:05:57 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/10/15 04:05:57 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/10/15 04:05:57 INFO  : file1R.txt: Copied (new)
2025/10/15 04:05:57 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/10/15 04:05:57 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/10/15 04:05:57 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/10/15 04:05:57 INFO  : file2.txt: Copied (new)
2025/10/15 04:05:57 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/10/15 04:05:57 INFO  : file1L.txt: Copied (new)
2025/10/15 04:05:57 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:05:58 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:05:59 NOTICE: checking initFs Local file system at /tmp/040428sa/initdir/test_resync_modes-zekutib4
2025/10/15 04:05:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:05:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:59 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:05:59 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Waiting for checks to finish
2025/10/15 04:05:59 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Waiting for transfers to finish
2025/10/15 04:06:00 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:06:00 INFO  : file1.txt: Copied (new)
2025/10/15 04:06:00 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:06:00 INFO  : file2.txt: Copied (new)
2025/10/15 04:06:00 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:06:00 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:06:00 NOTICE: checking Path1 B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1
2025/10/15 04:06:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:06:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:06:01 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:06:01 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Waiting for checks to finish
2025/10/15 04:06:01 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Waiting for transfers to finish
2025/10/15 04:06:02 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:06:02 INFO  : file1.txt: Copied (new)
2025/10/15 04:06:02 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:06:02 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:06:02 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:06:02 INFO  : file2.txt: Copied (new)
2025/10/15 04:06:02 NOTICE: checking path2 B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2
2025/10/15 04:06:03 NOTICE: (01)  : test resync-mode
2025/10/15 04:06:03 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:06:03 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:06:03 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/"
2025/10/15 04:06:03 DEBUG : fs cache: renaming cache item "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" to be canonical "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:03 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/10/15 04:06:03 NOTICE: (04)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:06:03 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:06:03 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:06:03 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:04 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:06:04 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/15 04:06:05 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:06:05 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:06:05 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:06:05 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:05 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/10/15 04:06:05 NOTICE: (06)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:06:05 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:06:05 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:06:05 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:06:07 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:06:07 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/15 04:06:08 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:06:08 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:06:08 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/10/15 04:06:08 NOTICE: (08)  : bisync resync resync-mode=newer
2025/10/15 04:06:17 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:17 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:06:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:06:17 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:17 INFO  : Copying Path2 files to Path1
2025/10/15 04:06:17 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:06:17 INFO  : There was nothing to transfer
2025/10/15 04:06:17 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:06:18 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:06:18 INFO  : Resync updating listings
2025/10/15 04:06:18 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:18 INFO  : Bisync successful
2025/10/15 04:06:18 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:06:18 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:06:18 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:18 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/10/15 04:06:18 NOTICE: (11)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:06:18 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:06:18 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:06:18 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:19 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:06:19 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/15 04:06:21 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:06:21 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:06:21 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:06:21 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:21 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/10/15 04:06:21 NOTICE: (13)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:06:21 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:06:21 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:06:21 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:06:22 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:06:22 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/10/15 04:06:22 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:06:23 INFO  : file1L.txt: Updated modification time in destination
2025/10/15 04:06:23 DEBUG : file1L.txt: Unchanged skipping
2025/10/15 04:06:23 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/10/15 04:06:23 NOTICE: (15)  : bisync resync resync-mode=path2
2025/10/15 04:06:32 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:32 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:06:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:06:32 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:32 INFO  : Copying Path2 files to Path1
2025/10/15 04:06:32 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:06:32 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:06:32 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:06:32 INFO  : Resync updating listings
2025/10/15 04:06:32 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:32 INFO  : Bisync successful
2025/10/15 04:06:32 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:06:32 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:06:32 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:32 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/10/15 04:06:32 NOTICE: (18)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:06:32 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:06:32 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:06:32 DEBUG : Config file has changed externally - reloading
2025/10/15 04:06:32 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:33 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:06:34 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/10/15 04:06:34 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:06:35 INFO  : file1R.txt: Updated modification time in destination
2025/10/15 04:06:35 DEBUG : file1R.txt: Unchanged skipping
2025/10/15 04:06:35 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:06:35 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:35 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/10/15 04:06:35 NOTICE: (20)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:06:35 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:06:35 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:06:35 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:06:36 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:06:36 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/15 04:06:38 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:06:38 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:06:38 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/10/15 04:06:38 NOTICE: (22)  : bisync resync resync-mode=larger
2025/10/15 04:06:47 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:47 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:06:47 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:06:47 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:06:47 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:47 INFO  : Copying Path2 files to Path1
2025/10/15 04:06:47 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:06:47 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/10/15 04:06:47 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:06:47 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:06:47 INFO  : Resync updating listings
2025/10/15 04:06:47 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:47 INFO  : Bisync successful
2025/10/15 04:06:47 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:06:47 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:06:47 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:47 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/10/15 04:06:47 NOTICE: (25)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:06:47 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:06:47 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:06:47 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:06:48 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:06:49 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/10/15 04:06:49 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:06:50 INFO  : file1R.txt: Updated modification time in destination
2025/10/15 04:06:50 DEBUG : file1R.txt: Unchanged skipping
2025/10/15 04:06:50 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:06:50 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:06:50 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/10/15 04:06:50 NOTICE: (27)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:06:50 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:06:50 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:06:50 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:06:51 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:06:51 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/15 04:06:53 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:06:53 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:06:53 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/10/15 04:06:53 NOTICE: (29)  : bisync resync resync-mode=older
2025/10/15 04:07:02 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:02 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:02 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:07:02 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:07:02 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:02 INFO  : Copying Path2 files to Path1
2025/10/15 04:07:02 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:07:02 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/10/15 04:07:02 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:07:02 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:07:03 INFO  : Resync updating listings
2025/10/15 04:07:03 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:03 INFO  : Bisync successful
2025/10/15 04:07:03 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:07:03 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:07:03 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:03 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/10/15 04:07:03 NOTICE: (32)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:07:03 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:07:03 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:07:03 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:04 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:07:04 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/10/15 04:07:04 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:07:05 INFO  : file1R.txt: Updated modification time in destination
2025/10/15 04:07:05 DEBUG : file1R.txt: Unchanged skipping
2025/10/15 04:07:05 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:07:05 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:05 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/10/15 04:07:05 NOTICE: (34)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:07:05 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:07:05 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:07:05 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:07:06 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:07:06 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/15 04:07:08 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:07:08 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:07:08 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/10/15 04:07:08 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/10/15 04:07:18 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:18 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:07:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:07:18 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:18 INFO  : Copying Path2 files to Path1
2025/10/15 04:07:18 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:07:19 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/15 04:07:19 INFO  : There was nothing to transfer
2025/10/15 04:07:19 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:07:19 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/15 04:07:19 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:07:19 INFO  : Resync updating listings
2025/10/15 04:07:19 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:19 INFO  : Bisync successful
2025/10/15 04:07:19 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/15 04:07:19 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:07:19 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:19 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/10/15 04:07:19 NOTICE: (39)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:07:19 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:07:19 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:07:19 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:20 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:07:20 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/15 04:07:22 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:07:22 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:07:22 NOTICE: (40)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file2.txt
2025/10/15 04:07:22 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file2.txt"
2025/10/15 04:07:22 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:07:22 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:07:23 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file2.txt"
2025/10/15 04:07:23 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/15 04:07:25 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:07:25 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/10/15 04:07:25 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:07:25 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:25 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/10/15 04:07:25 NOTICE: (42)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:07:25 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:07:25 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:07:25 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:07:26 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:07:26 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/10/15 04:07:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:07:28 INFO  : file1L.txt: Updated modification time in destination
2025/10/15 04:07:28 DEBUG : file1L.txt: Unchanged skipping
2025/10/15 04:07:28 NOTICE: (43)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file2.txt
2025/10/15 04:07:28 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file2.txt"
2025/10/15 04:07:28 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:07:28 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:29 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file2.txt"
2025/10/15 04:07:29 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/15 04:07:30 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:07:30 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/15 04:07:30 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/10/15 04:07:30 NOTICE: (45)  : bisync resync resync-mode=path1
2025/10/15 04:07:39 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:39 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:07:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:07:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:07:39 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:39 INFO  : Copying Path2 files to Path1
2025/10/15 04:07:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:07:40 INFO  : There was nothing to transfer
2025/10/15 04:07:40 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:07:40 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:07:41 INFO  : file2.txt: Copied (server-side copy)
2025/10/15 04:07:41 INFO  : Resync updating listings
2025/10/15 04:07:41 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:41 INFO  : Bisync successful
2025/10/15 04:07:41 NOTICE: (46)  : test no winner
2025/10/15 04:07:41 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1R.txt
2025/10/15 04:07:41 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:41 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/10/15 04:07:41 NOTICE: (48)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file1.txt
2025/10/15 04:07:41 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file1.txt"
2025/10/15 04:07:41 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:07:41 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:42 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file1.txt"
2025/10/15 04:07:42 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/15 04:07:44 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:07:44 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/15 04:07:44 NOTICE: (49)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file2.txt
2025/10/15 04:07:44 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file2.txt"
2025/10/15 04:07:44 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1R.txt"
2025/10/15 04:07:44 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:07:45 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file2.txt"
2025/10/15 04:07:45 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/10/15 04:07:45 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/15 04:07:46 INFO  : file1R.txt: Updated modification time in destination
2025/10/15 04:07:46 DEBUG : file1R.txt: Unchanged skipping
2025/10/15 04:07:46 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/040428sa/datadir/test_resync_modes-hatayul6/ file1L.txt
2025/10/15 04:07:46 DEBUG : fs cache: switching user supplied name "/tmp/040428sa/datadir/test_resync_modes-hatayul6/" for canonical name "/tmp/040428sa/datadir/test_resync_modes-hatayul6"
2025/10/15 04:07:46 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/10/15 04:07:46 NOTICE: (51)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/ file1.txt
2025/10/15 04:07:46 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" as "file1.txt"
2025/10/15 04:07:46 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:07:46 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/"
2025/10/15 04:07:47 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1" as "file1.txt"
2025/10/15 04:07:48 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/10/15 04:07:48 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:07:49 INFO  : file1L.txt: Updated modification time in destination
2025/10/15 04:07:49 DEBUG : file1L.txt: Unchanged skipping
2025/10/15 04:07:49 NOTICE: (52)  : copy-as /tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/ file2.txt
2025/10/15 04:07:49 DEBUG : copyFile "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt" to "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/" as "file2.txt"
2025/10/15 04:07:49 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_resync_modes-hatayul6/file1L.txt"
2025/10/15 04:07:49 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:07:50 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2" as "file2.txt"
2025/10/15 04:07:50 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/15 04:07:51 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/15 04:07:51 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/15 04:07:51 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/10/15 04:07:51 NOTICE: (54)  : bisync resync resync-mode=newer
2025/10/15 04:08:00 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:08:00 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/15 04:08:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/15 04:08:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/15 04:08:00 INFO  : Synching Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" with Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:08:00 INFO  : Copying Path2 files to Path1
2025/10/15 04:08:00 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/15 04:08:01 INFO  : file1.txt: Copied (server-side copy)
2025/10/15 04:08:01 INFO  : file2.txt: Copied (server-side copy)
2025/10/15 04:08:01 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/15 04:08:02 INFO  : Resync updating listings
2025/10/15 04:08:02 INFO  : Validating listings for Path1 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-feyonin0xeho/040428sa/resync_modes/path2/"
2025/10/15 04:08:02 INFO  : Bisync successful
2025/10/15 04:08:02 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:02 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f112c577fad397e0f_d20251015_m040600_c001_v0001105_t0025_u01760501160647")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103d5c222bc39b94_d20251015_m040801_c001_v0001182_t0005_u01760501281126")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115598e7f061c257_d20251015_m040749_c001_v0001181_t0014_u01760501269070")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f114ba2a4bce0a1e2_d20251015_m040727_c001_v0001144_t0038_u01760501247537")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1168a6219a9f1f21_d20251015_m040708_c001_v0001029_t0026_u01760501228356")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11694ab811c34960_d20251015_m040702_c001_v0001157_t0012_u01760501222490")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10044f275accb510_d20251015_m040653_c001_v0001106_t0022_u01760501213072")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1064fc19337ac09a_d20251015_m040647_c001_v0001181_t0000_u01760501207423")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f102dfa8a3cf4a720_d20251015_m040638_c001_v0001181_t0026_u01760501198227")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f107452db2f5e81c5_d20251015_m040632_c001_v0001184_t0022_u01760501192497")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11214c95ef6908c8_d20251015_m040623_c001_v0001165_t0054_u01760501183805")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1142f75aebf69b6e_d20251015_m040608_c001_v0001105_t0000_u01760501168558")
2025/10/15 04:08:02 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11784d851c38c2ad_d20251015_m040600_c001_v0001029_t0035_u01760501160269")
2025/10/15 04:08:02 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113a50c320067d6e_d20251015_m040801_c001_v0001040_t0032_u01760501281353")
2025/10/15 04:08:03 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105ce719a0b4d5a3_d20251015_m040746_c001_v0001182_t0035_u01760501266658")
2025/10/15 04:08:03 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111bd208dc11c410_d20251015_m040725_c001_v0001182_t0038_u01760501245095")
2025/10/15 04:08:03 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1192d43130e2ddf4_d20251015_m040600_c001_v0001029_t0015_u01760501160461")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f422b06bf1c5b2e63_d20251015_m040756_c001_v7007000_t0000_u01760501276715")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10826ed030ba69bc_d20251015_m040755_c001_v0001099_t0046_u01760501275087")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11784d851c38c417_d20251015_m040752_c001_v0001029_t0041_u01760501272403")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f4192e54f0fb5cc37_d20251015_m040735_c001_v7007000_t0000_u01760501255376")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103954c2985e3fd8_d20251015_m040733_c001_v0001101_t0042_u01760501253834")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f112c577fad398028_d20251015_m040731_c001_v0001105_t0039_u01760501251344")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f40290cfc928e8f2d_d20251015_m040713_c001_v7007000_t0000_u01760501233413")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11453a3d5563c572_d20251015_m040711_c001_v0001101_t0021_u01760501231862")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1192d43130e2de84_d20251015_m040709_c001_v0001029_t0037_u01760501229367")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f4231b7539e5882e3_d20251015_m040657_c001_v7007000_t0000_u01760501217713")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1150d42350072293_d20251015_m040656_c001_v0001178_t0036_u01760501216485")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11784d851c38c31d_d20251015_m040653_c001_v0001029_t0033_u01760501213920")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f419401ec449e589d_d20251015_m040642_c001_v7007000_t0000_u01760501202465")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f116c2da5a48a648b_d20251015_m040641_c001_v0001113_t0008_u01760501201137")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f112c577fad397f0e_d20251015_m040638_c001_v0001105_t0024_u01760501198801")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f40290cfc928e8dc4_d20251015_m040627_c001_v7007000_t0000_u01760501187902")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1052db1c3faf316c_d20251015_m040626_c001_v0001179_t0029_u01760501186684")
2025/10/15 04:08:03 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1192d43130e2de44_d20251015_m040624_c001_v0001029_t0014_u01760501184387")
2025/10/15 04:08:04 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f40338a7a290616b5_d20251015_m040613_c001_v7007000_t0000_u01760501173284")
2025/10/15 04:08:04 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115281da53d0cf33_d20251015_m040611_c001_v0001098_t0054_u01760501171899")
2025/10/15 04:08:04 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11784d851c38c2c1_d20251015_m040609_c001_v0001029_t0042_u01760501169345")
2025/10/15 04:08:04 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/resync_modes/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:04 DEBUG : RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10058a0e7ae464ef_d20251015_m040601_c001_v0001136_t0012_u01760501161980")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f101261b16a6decec_d20251015_m040743_c001_v0001098_t0038_u01760501263924")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1063f35ecfcc3859_d20251015_m040740_c001_v0001136_t0056_u01760501260341")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1064fc19337ac1a9_d20251015_m040722_c001_v0001181_t0017_u01760501242303")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1038730691fbf8d1_d20251015_m040719_c001_v0001130_t0000_u01760501239364")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f109ee0c645297978_d20251015_m040705_c001_v0001040_t0024_u01760501225354")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111a553804cbf702_d20251015_m040650_c001_v0001090_t0046_u01760501210037")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103f5143f454cf8a_d20251015_m040635_c001_v0001136_t0011_u01760501195215")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f111a99f780e21e0c_d20251015_m040620_c001_v0001038_t0012_u01760501180900")
2025/10/15 04:08:04 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103e2d7d1a78d975_d20251015_m040617_c001_v0001113_t0023_u01760501177934")
2025/10/15 04:08:05 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1062129768620781_d20251015_m040605_c001_v0001177_t0015_u01760501165760")
2025/10/15 04:08:05 DEBUG : file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f108176341e93a738_d20251015_m040602_c001_v0001183_t0056_u01760501162168")
2025/10/15 04:08:05 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1046fff118967245_d20251015_m040751_c001_v0001182_t0034_u01760501271828")
2025/10/15 04:08:05 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1013906d5e06fb84_d20251015_m040740_c001_v0001040_t0018_u01760501260701")
2025/10/15 04:08:05 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f100d32ac40e00b00_d20251015_m040730_c001_v0001184_t0011_u01760501250777")
2025/10/15 04:08:05 DEBUG : file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119a99f15e6f353f_d20251015_m040602_c001_v0001038_t0025_u01760501162364")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f415e124fbb5ecc40_d20251015_m040800_c001_v7007000_t0000_u01760501280740")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f105071fd13666456_d20251015_m040759_c001_v0001092_t0057_u01760501279403")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f108176341e93ace7_d20251015_m040757_c001_v0001183_t0008_u01760501277261")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f40478fe37c6cdce3_d20251015_m040739_c001_v7007000_t0000_u01760501259767")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10546f19781c6a24_d20251015_m040738_c001_v0001038_t0002_u01760501258240")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119a99f15e6f3687_d20251015_m040735_c001_v0001038_t0058_u01760501255915")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f4131931c2e605966_d20251015_m040718_c001_v7007000_t0000_u01760501238268")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11463cc2563d6cd9_d20251015_m040716_c001_v0001120_t0028_u01760501236706")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10058a0e7ae466b4_d20251015_m040713_c001_v0001136_t0049_u01760501233956")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f409a344462bac3f2_d20251015_m040702_c001_v7007000_t0000_u01760501222107")
2025/10/15 04:08:05 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f104a2d552c40d7c8_d20251015_m040700_c001_v0001040_t0001_u01760501220398")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f108176341e93a9d7_d20251015_m040658_c001_v0001183_t0007_u01760501218254")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f422b06bf1c5b2d55_d20251015_m040647_c001_v7007000_t0000_u01760501207038")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f11754abd941de792_d20251015_m040645_c001_v0001157_t0020_u01760501205664")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f119a99f15e6f35b3_d20251015_m040643_c001_v0001038_t0043_u01760501203009")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f409a344462bac359_d20251015_m040632_c001_v7007000_t0000_u01760501192041")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10555e481649583c_d20251015_m040630_c001_v0001171_t0036_u01760501190729")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10058a0e7ae465b6_d20251015_m040628_c001_v0001136_t0020_u01760501188450")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f418b4b763a716228_d20251015_m040617_c001_v7007000_t0000_u01760501177373")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f10004991a2b57146_d20251015_m040615_c001_v0001098_t0026_u01760501175961")
2025/10/15 04:08:06 DEBUG : modtime_write_test: Deleting (id "4_z4f527da300adeb6b9f980e1b_f108176341e93a7dc_d20251015_m040613_c001_v0001183_t0041_u01760501173829")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/10/15 04:08:06 INFO  : path1: Making directory
2025/10/15 04:08:06 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/rmdirs/path1"
2025/10/15 04:08:08 INFO  : path2: Making directory
2025/10/15 04:08:08 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/rmdirs/path2"
2025/10/15 04:08:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/10/15 04:08:09 DEBUG : Creating backend with remote "/tmp/040428sa/initdir/test_rmdirs-naxeyib2"
2025/10/15 04:08:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : subdir: Making directory with metadata
2025/10/15 04:08:09 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/10/15 04:08:09 INFO  : file1.copy2.txt: Copied (new)
2025/10/15 04:08:09 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/10/15 04:08:09 INFO  : file1.copy3.txt: Copied (new)
2025/10/15 04:08:09 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/15 04:08:09 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/10/15 04:08:09 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/15 04:08:09 INFO  : file1.copy4.txt: Copied (new)
2025/10/15 04:08:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:08:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : Local file system at /tmp/040428sa/initdir/test_rmdirs-naxeyib2: Waiting for checks to finish
2025/10/15 04:08:09 DEBUG : Local file system at /tmp/040428sa/initdir/test_rmdirs-naxeyib2: Waiting for transfers to finish
2025/10/15 04:08:09 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/10/15 04:08:09 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:08:09 INFO  : file1.copy5.txt: Copied (new)
2025/10/15 04:08:09 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:09 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/15 04:08:09 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:08:09 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/10/15 04:08:09 INFO  : file1.copy1.txt: Copied (new)
2025/10/15 04:08:09 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:09 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:08:09 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:09 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/15 04:08:09 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/10/15 04:08:09 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_rmdirs-jepogur9"
2025/10/15 04:08:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:09 DEBUG : Local file system at /tmp/040428sa/datadir/test_rmdirs-jepogur9: Waiting for checks to finish
2025/10/15 04:08:09 DEBUG : Local file system at /tmp/040428sa/datadir/test_rmdirs-jepogur9: Waiting for transfers to finish
2025/10/15 04:08:09 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:09 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/10/15 04:08:09 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:09 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:09 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:10 NOTICE: checking initFs Local file system at /tmp/040428sa/initdir/test_rmdirs-naxeyib2
2025/10/15 04:08:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 INFO  : subdir: Making directory
2025/10/15 04:08:11 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:11 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path1: Waiting for checks to finish
2025/10/15 04:08:11 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path1: Waiting for transfers to finish
2025/10/15 04:08:12 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.copy1.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.copy3.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.copy2.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.copy4.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:08:12 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:12 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.copy5.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:12 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:12 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:08:12 NOTICE: checking Path1 B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path1
2025/10/15 04:08:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 INFO  : subdir: Making directory
2025/10/15 04:08:13 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:13 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path2: Waiting for checks to finish
2025/10/15 04:08:13 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path2: Waiting for transfers to finish
2025/10/15 04:08:14 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.copy2.txt: Copied (new)
2025/10/15 04:08:14 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.copy1.txt: Copied (new)
2025/10/15 04:08:14 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.copy3.txt: Copied (new)
2025/10/15 04:08:14 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.copy5.txt: Copied (new)
2025/10/15 04:08:14 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.copy4.txt: Copied (new)
2025/10/15 04:08:14 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:08:14 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:14 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:14 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:15 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:15 INFO  : subdir/file20.txt: Copied (new)
2025/10/15 04:08:15 NOTICE: checking path2 B2 bucket rclone-test-feyonin0xeho path 040428sa/rmdirs/path2
2025/10/15 04:08:15 NOTICE: (01)  : test rmdirs
2025/10/15 04:08:15 NOTICE: (02)  : test initial bisync
2025/10/15 04:08:15 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/10/15 04:08:15 INFO  : path1: Making directory
2025/10/15 04:08:15 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/volatile/path1"
2025/10/15 04:08:16 INFO  : path2: Making directory
2025/10/15 04:08:16 DEBUG : Creating backend with remote "TestB2:rclone-test-feyonin0xeho/040428sa/volatile/path2"
2025/10/15 04:08:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/10/15 04:08:17 DEBUG : Creating backend with remote "/tmp/040428sa/initdir/test_volatile-ninipex0"
2025/10/15 04:08:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : Local file system at /tmp/040428sa/initdir/test_volatile-ninipex0: Waiting for checks to finish
2025/10/15 04:08:17 DEBUG : Local file system at /tmp/040428sa/initdir/test_volatile-ninipex0: Waiting for transfers to finish
2025/10/15 04:08:17 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/15 04:08:17 INFO  : file2.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/15 04:08:17 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/15 04:08:17 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/15 04:08:17 INFO  : file3.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/15 04:08:17 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:17 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/15 04:08:17 INFO  : file5.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/15 04:08:17 INFO  : file4.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/15 04:08:17 INFO  : file6.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/15 04:08:17 INFO  : file7.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/10/15 04:08:17 INFO  : file8.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/10/15 04:08:17 DEBUG : Creating backend with remote "/tmp/040428sa/datadir/test_volatile-papisod5"
2025/10/15 04:08:17 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:17 DEBUG : Local file system at /tmp/040428sa/datadir/test_volatile-papisod5: Waiting for checks to finish
2025/10/15 04:08:17 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/15 04:08:17 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/10/15 04:08:17 INFO  : dummy.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : Local file system at /tmp/040428sa/datadir/test_volatile-papisod5: Waiting for transfers to finish
2025/10/15 04:08:17 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:17 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:17 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/10/15 04:08:17 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/10/15 04:08:17 INFO  : file10.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:17 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/10/15 04:08:17 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/10/15 04:08:17 INFO  : file11.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/10/15 04:08:17 INFO  : file2.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/10/15 04:08:17 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/10/15 04:08:17 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/10/15 04:08:17 INFO  : file5L.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/10/15 04:08:17 INFO  : file5R.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:17 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/15 04:08:17 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/10/15 04:08:17 INFO  : file7.txt: Copied (new)
2025/10/15 04:08:17 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/10/15 04:08:17 INFO  : file6.txt: Copied (new)
2025/10/15 04:08:17 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path1: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:18 INFO  : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path2: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:19 NOTICE: checking initFs Local file system at /tmp/040428sa/initdir/test_volatile-ninipex0
2025/10/15 04:08:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:19 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path1: Waiting for checks to finish
2025/10/15 04:08:19 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path1: Waiting for transfers to finish
2025/10/15 04:08:20 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:20 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:20 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:20 INFO  : file4.txt: Copied (new)
2025/10/15 04:08:20 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:20 INFO  : file5.txt: Copied (new)
2025/10/15 04:08:20 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:20 INFO  : file2.txt: Copied (new)
2025/10/15 04:08:21 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:21 INFO  : file3.txt: Copied (new)
2025/10/15 04:08:21 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:21 INFO  : file6.txt: Copied (new)
2025/10/15 04:08:21 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:08:21 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:21 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:21 INFO  : file7.txt: Copied (new)
2025/10/15 04:08:21 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:21 INFO  : file8.txt: Copied (new)
2025/10/15 04:08:21 NOTICE: checking Path1 B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path1
2025/10/15 04:08:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/15 04:08:21 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path2: Waiting for checks to finish
2025/10/15 04:08:21 DEBUG : B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path2: Waiting for transfers to finish
2025/10/15 04:08:22 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:22 INFO  : file1.txt: Copied (new)
2025/10/15 04:08:22 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:22 INFO  : file2.txt: Copied (new)
2025/10/15 04:08:22 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:22 INFO  : file4.txt: Copied (new)
2025/10/15 04:08:22 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:22 INFO  : file3.txt: Copied (new)
2025/10/15 04:08:23 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:23 INFO  : file6.txt: Copied (new)
2025/10/15 04:08:23 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:23 INFO  : file5.txt: Copied (new)
2025/10/15 04:08:23 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/15 04:08:23 INFO  : RCLONE_TEST: Copied (new)
2025/10/15 04:08:23 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:23 INFO  : file7.txt: Copied (new)
2025/10/15 04:08:23 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/15 04:08:23 INFO  : file8.txt: Copied (new)
2025/10/15 04:08:23 NOTICE: checking path2 B2 bucket rclone-test-feyonin0xeho path 040428sa/volatile/path2
2025/10/15 04:08:23 NOTICE: (01)  : test volatile
2025/10/15 04:08:23 NOTICE: (02)  : test initial bisync
2025/10/15 04:08:23 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/10/15 04:08:23 DEBUG : B2 bucket rclone-test-feyonin0xeho: Purge remote
2025/10/15 04:08:23 INFO  : B2 bucket rclone-test-feyonin0xeho: cleaning bucket "rclone-test-feyonin0xeho" of all files
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1028685100842bc1_d20251015_m040812_c001_v0001101_t0006_u01760501292396")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1175f0888f18ae9f_d20251015_m040811_c001_v0001090_t0009_u01760501291832")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115d7e30f207e53d_d20251015_m040812_c001_v0001105_t0002_u01760501292211")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f117b62a5896c12d1_d20251015_m040812_c001_v0001171_t0046_u01760501292019")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1175f0888f18aea1_d20251015_m040812_c001_v0001090_t0024_u01760501292222")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f117b62a5896c12d3_d20251015_m040812_c001_v0001171_t0011_u01760501292456")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115d7e30f207e541_d20251015_m040812_c001_v0001105_t0023_u01760501292527")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1175f0888f18aea3_d20251015_m040812_c001_v0001090_t0021_u01760501292607")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f116e5e7ad5f54e84_d20251015_m040814_c001_v0001116_t0021_u01760501294367")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113af7efb921364e_d20251015_m040813_c001_v0001042_t0040_u01760501293803")
2025/10/15 04:08:24 DEBUG : 040428sa/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f112ee9fa3b6c0a3b_d20251015_m040813_c001_v0001157_t0028_u01760501293989")
2025/10/15 04:08:25 DEBUG : 040428sa/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f106f8ee25aa776c4_d20251015_m040814_c001_v0001029_t0012_u01760501294182")
2025/10/15 04:08:25 DEBUG : 040428sa/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f112ee9fa3b6c0a3f_d20251015_m040814_c001_v0001157_t0014_u01760501294259")
2025/10/15 04:08:25 DEBUG : 040428sa/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113af7efb9213652_d20251015_m040814_c001_v0001042_t0033_u01760501294319")
2025/10/15 04:08:25 DEBUG : 040428sa/rmdirs/path2/file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f106f8ee25aa776c8_d20251015_m040814_c001_v0001029_t0014_u01760501294619")
2025/10/15 04:08:25 DEBUG : 040428sa/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113af7efb9213658_d20251015_m040814_c001_v0001042_t0041_u01760501294680")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f115580d1c8ea1b54_d20251015_m040820_c001_v0001130_t0003_u01760501300834")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f110b8e4f1ec3d33e_d20251015_m040820_c001_v0001178_t0025_u01760501300266")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1149d23fe07e5283_d20251015_m040820_c001_v0001131_t0045_u01760501300454")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f107f0b8d0e45fce8_d20251015_m040820_c001_v0001146_t0040_u01760501300646")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f110b8e4f1ec3d340_d20251015_m040820_c001_v0001178_t0019_u01760501300490")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f110b8e4f1ec3d344_d20251015_m040820_c001_v0001178_t0021_u01760501300703")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f110b8e4f1ec3d346_d20251015_m040820_c001_v0001178_t0014_u01760501300912")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1149d23fe07e528d_d20251015_m040820_c001_v0001131_t0023_u01760501300921")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path1/file8.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f107f0b8d0e45fcee_d20251015_m040821_c001_v0001146_t0029_u01760501301112")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path2/RCLONE_TEST: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1191062647ba1b55_d20251015_m040822_c001_v0001146_t0054_u01760501302932")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path2/file1.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113a8bb675e529ee_d20251015_m040822_c001_v0001178_t0003_u01760501302361")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path2/file2.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1082e8d64da3c4a9_d20251015_m040822_c001_v0001171_t0056_u01760501302550")
2025/10/15 04:08:25 DEBUG : 040428sa/volatile/path2/file3.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103064cf78fe3dda_d20251015_m040822_c001_v0001157_t0017_u01760501302737")
2025/10/15 04:08:26 DEBUG : 040428sa/volatile/path2/file4.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113a8bb675e529f4_d20251015_m040822_c001_v0001178_t0045_u01760501302718")
2025/10/15 04:08:26 DEBUG : 040428sa/volatile/path2/file5.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f1082e8d64da3c4ad_d20251015_m040822_c001_v0001171_t0056_u01760501302870")
2025/10/15 04:08:26 DEBUG : 040428sa/volatile/path2/file6.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113a8bb675e529f6_d20251015_m040822_c001_v0001178_t0048_u01760501302985")
2025/10/15 04:08:26 DEBUG : 040428sa/volatile/path2/file7.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f103064cf78fe3ddc_d20251015_m040823_c001_v0001157_t0008_u01760501303038")
2025/10/15 04:08:26 DEBUG : 040428sa/volatile/path2/file8.txt: Deleting (id "4_z4f527da300adeb6b9f980e1b_f113a8bb675e529fa_d20251015_m040823_c001_v0001178_t0049_u01760501303215")
--- PASS: TestBisyncRemoteRemote (240.84s)
    --- PASS: TestBisyncRemoteRemote/resync (86.58s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (131.51s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.43s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.28s)
FAIL
2025/10/15 04:08:29 DEBUG : B2 bucket rclone-test-pegukej4fahe: Purge remote
2025/10/15 04:08:29 INFO  : B2 bucket rclone-test-pegukej4fahe: cleaning bucket "rclone-test-pegukej4fahe" of all files
2025/10/15 04:08:29 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 4m38.295586017s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/check_access_filters]