"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(check_access_filters|resync)$|^TestBisyncRemoteRemote$/^(resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/08/31 02:02:47 DEBUG : Creating backend with remote "TestB2:rclone-test-pulufeg9vihu"
2025/08/31 02:02:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/31 02:02:48 DEBUG : Creating backend with remote "/tmp/rclone1842076681"
=== RUN   TestBisyncLocalRemote
2025/08/31 02:02:48 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo"
2025/08/31 02:02:48 NOTICE: remote: TestB2:rclone-test-namuyuc1mevo
=== RUN   TestBisyncLocalRemote/check_access_filters
2025/08/31 02:02:48 DEBUG : Creating backend with remote "/tmp/020248ge"
2025/08/31 02:02:48 DEBUG : Creating backend with remote "/tmp/020248ge/check_access_filters/path1"
2025/08/31 02:02:48 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge"
2025/08/31 02:02:49 INFO  : path2: Making directory
2025/08/31 02:02:53 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2"
2025/08/31 02:02:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial"
2025/08/31 02:02:55 DEBUG : Creating backend with remote "/tmp/020248ge/initdir/test_check_access_filters-katasib8"
2025/08/31 02:02:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir-not: Making directory with metadata
2025/08/31 02:02:55 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/08/31 02:02:55 DEBUG : subdir: Making directory with metadata
2025/08/31 02:02:55 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/31 02:02:55 DEBUG : subdirX: Making directory with metadata
2025/08/31 02:02:55 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/08/31 02:02:55 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/08/31 02:02:55 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir/subdirA: Making directory with metadata
2025/08/31 02:02:55 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:02:55 INFO  : file1.txt: Copied (new)
2025/08/31 02:02:55 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/08/31 02:02:55 DEBUG : subdir/subdirB: Making directory with metadata
2025/08/31 02:02:55 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/08/31 02:02:55 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/08/31 02:02:55 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/31 02:02:55 INFO  : file3.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:55 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/08/31 02:02:55 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:02:55 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 INFO  : file2.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/31 02:02:55 INFO  : file4.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : Local file system at /tmp/020248ge/initdir/test_check_access_filters-katasib8: Waiting for checks to finish
2025/08/31 02:02:55 DEBUG : Local file system at /tmp/020248ge/initdir/test_check_access_filters-katasib8: Waiting for transfers to finish
2025/08/31 02:02:55 DEBUG : subdirX/file20.txt.5028a97.partial: renamed to: subdirX/file20.txt
2025/08/31 02:02:55 INFO  : subdirX/file20.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdirX/subdirX1/file30.txt.5028a97.partial: renamed to: subdirX/subdirX1/file30.txt
2025/08/31 02:02:55 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/31 02:02:55 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir-not/file20.txt.5028a97.partial: renamed to: subdir-not/file20.txt
2025/08/31 02:02:55 INFO  : subdir-not/file20.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:02:55 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir/subdirB/file30.txt.5028a97.partial: renamed to: subdir/subdirB/file30.txt
2025/08/31 02:02:55 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir/subdirA/file30.txt.5028a97.partial: renamed to: subdir/subdirA/file30.txt
2025/08/31 02:02:55 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdir/subdirA/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:55 DEBUG : subdir/subdirB/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/08/31 02:02:55 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:55 DEBUG : subdir-not/subdir-not2/file30.txt.5028a97.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/08/31 02:02:55 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/08/31 02:02:55 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:02:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/modfiles"
2025/08/31 02:02:55 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_check_access_filters-xojisur4"
2025/08/31 02:02:55 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:55 DEBUG : Local file system at /tmp/020248ge/datadir/test_check_access_filters-xojisur4: Waiting for checks to finish
2025/08/31 02:02:55 DEBUG : Local file system at /tmp/020248ge/datadir/test_check_access_filters-xojisur4: Waiting for transfers to finish
2025/08/31 02:02:55 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/08/31 02:02:55 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/08/31 02:02:55 DEBUG : include-other-filtersfile.txt.5fb1cd78.partial: renamed to: include-other-filtersfile.txt
2025/08/31 02:02:55 DEBUG : exclude-other-filtersfile.txt.9180e6d6.partial: renamed to: exclude-other-filtersfile.txt
2025/08/31 02:02:55 INFO  : include-other-filtersfile.txt: Copied (new)
2025/08/31 02:02:55 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/08/31 02:02:55 DEBUG : Waiting for deletions to finish
2025/08/31 02:02:55 ERROR : error listing: directory not found
2025/08/31 02:02:55 ERROR : Local file system at /tmp/020248ge/check_access_filters/path1: Failed to list "": directory not found
2025/08/31 02:02:55 DEBUG : removing 1 level 0 directories
2025/08/31 02:02:55 INFO  : Local file system at /tmp/020248ge/check_access_filters/path1: Removing directory
2025/08/31 02:02:55 ERROR : Failed to rmdir: stat /tmp/020248ge/check_access_filters/path1: no such file or directory
2025/08/31 02:02:55 INFO  : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: cleaning bucket "rclone-test-namuyuc1mevo" of all files
2025/08/31 02:02:55 ERROR : error listing: directory not found
2025/08/31 02:02:55 NOTICE: checking initFs Local file system at /tmp/020248ge/initdir/test_check_access_filters-katasib8
2025/08/31 02:02:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not: Making directory with metadata
2025/08/31 02:02:56 INFO  : subdir-not: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/08/31 02:02:56 DEBUG : subdir: Making directory with metadata
2025/08/31 02:02:56 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:02:56 INFO  : file2.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/31 02:02:56 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:02:56 INFO  : file1.txt: Copied (new)
2025/08/31 02:02:56 INFO  : file3.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/31 02:02:56 DEBUG : subdirX: Making directory with metadata
2025/08/31 02:02:56 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/31 02:02:56 INFO  : subdirX: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 INFO  : file4.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/08/31 02:02:56 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:02:56 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1: Making directory with metadata
2025/08/31 02:02:56 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2: Making directory with metadata
2025/08/31 02:02:56 INFO  : subdirX/subdirX1: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/08/31 02:02:56 INFO  : subdir-not/subdir-not2: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/subdirA: Making directory with metadata
2025/08/31 02:02:56 INFO  : subdir/subdirA: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/08/31 02:02:56 DEBUG : subdir/subdirB: Making directory with metadata
2025/08/31 02:02:56 INFO  : subdir/subdirB: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:02:56 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/08/31 02:02:56 DEBUG : subdirX/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdirX/file20.txt.5028a97.partial: renamed to: subdirX/file20.txt
2025/08/31 02:02:56 INFO  : subdirX/file20.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/08/31 02:02:56 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir-not/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/file30.txt.5028a97.partial: renamed to: subdirX/subdirX1/file30.txt
2025/08/31 02:02:56 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir-not/file20.txt.5028a97.partial: renamed to: subdir-not/file20.txt
2025/08/31 02:02:56 INFO  : subdir-not/file20.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : Local file system at /tmp/020248ge/check_access_filters/path1: Waiting for checks to finish
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/file30.txt.5028a97.partial: renamed to: subdir-not/subdir-not2/file30.txt
2025/08/31 02:02:56 DEBUG : Local file system at /tmp/020248ge/check_access_filters/path1: Waiting for transfers to finish
2025/08/31 02:02:56 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/subdirX1/RCLONE_TEST
2025/08/31 02:02:56 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/08/31 02:02:56 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/31 02:02:56 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/subdirB/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdir/subdirB/file30.txt.5028a97.partial: renamed to: subdir/subdirB/file30.txt
2025/08/31 02:02:56 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir/subdirB/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/08/31 02:02:56 DEBUG : subdir/subdirB/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirB/RCLONE_TEST
2025/08/31 02:02:56 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/subdirA/file30.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:02:56 DEBUG : subdir/subdirA/file30.txt.5028a97.partial: renamed to: subdir/subdirA/file30.txt
2025/08/31 02:02:56 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir/subdirA/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir/subdirA/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/subdirA/RCLONE_TEST
2025/08/31 02:02:56 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/subdir-not2/RCLONE_TEST
2025/08/31 02:02:56 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/08/31 02:02:56 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:02:56 NOTICE: checking Path1 Local file system at /tmp/020248ge/check_access_filters/path1
2025/08/31 02:02:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 INFO  : subdir-not: Making directory
2025/08/31 02:02:56 INFO  : subdir: Making directory
2025/08/31 02:02:56 INFO  : subdirX: Making directory
2025/08/31 02:02:56 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdirX/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 INFO  : subdirX/subdirX1: Making directory
2025/08/31 02:02:56 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 INFO  : subdir-not/subdir-not2: Making directory
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdirX/subdirX1/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir-not/subdir-not2/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 INFO  : subdir/subdirA: Making directory
2025/08/31 02:02:56 INFO  : subdir/subdirB: Making directory
2025/08/31 02:02:56 DEBUG : subdir/subdirB/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/subdirB/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : subdir/subdirA/file30.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:56 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Waiting for checks to finish
2025/08/31 02:02:56 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Waiting for transfers to finish
2025/08/31 02:02:57 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : file1.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : file2.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : file3.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : subdirX/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:57 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/08/31 02:02:57 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : file4.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : subdir-not/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:57 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/08/31 02:02:57 DEBUG : subdirX/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : subdirX/file20.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:57 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:02:57 DEBUG : subdir-not/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : subdir-not/file20.txt: Copied (new)
2025/08/31 02:02:57 DEBUG : subdirX/subdirX1/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:57 INFO  : subdirX/subdirX1/RCLONE_TEST: Copied (new)
2025/08/31 02:02:57 DEBUG : subdirX/subdirX1/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:57 INFO  : subdirX/subdirX1/file30.txt: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:58 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:58 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:58 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/subdirB/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:58 INFO  : subdir/subdirB/RCLONE_TEST: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir-not/subdir-not2/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:58 INFO  : subdir-not/subdir-not2/file30.txt: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:02:58 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/subdirB/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:58 INFO  : subdir/subdirB/file30.txt: Copied (new)
2025/08/31 02:02:58 DEBUG : subdir/subdirA/file30.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:02:58 INFO  : subdir/subdirA/file30.txt: Copied (new)
2025/08/31 02:02:58 NOTICE: checking path2 B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2
2025/08/31 02:02:58 NOTICE: (01)  : test check-access-filters
2025/08/31 02:02:58 NOTICE: (02)  : test EXCLUDE - OTHER TESTS
2025/08/31 02:02:58 NOTICE: (03)  : copy-file /tmp/020248ge/datadir/test_check_access_filters-xojisur4/exclude-other-filtersfile.txt /tmp/020248ge/workdir/
2025/08/31 02:02:58 DEBUG : copyFile "/tmp/020248ge/datadir/test_check_access_filters-xojisur4/exclude-other-filtersfile.txt" to "/tmp/020248ge/workdir/" as ""
2025/08/31 02:02:58 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_check_access_filters-xojisur4/exclude-other-filtersfile.txt"
2025/08/31 02:02:58 DEBUG : Creating backend with remote "/tmp/020248ge/workdir/"
2025/08/31 02:02:58 DEBUG : operations.CopyFile "exclude-other-filtersfile.txt" to "Local file system at /tmp/020248ge/workdir" as "exclude-other-filtersfile.txt"
2025/08/31 02:02:58 DEBUG : exclude-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/08/31 02:02:58 DEBUG : exclude-other-filtersfile.txt: md5 = 5fcc6205d7df1c2e9ed3a15a1356b345 OK
2025/08/31 02:02:58 DEBUG : exclude-other-filtersfile.txt.9180e6d6.partial: renamed to: exclude-other-filtersfile.txt
2025/08/31 02:02:58 INFO  : exclude-other-filtersfile.txt: Copied (new)
2025/08/31 02:02:58 NOTICE: (04)  : test resync to get the filters file md5 built.
2025/08/31 02:02:58 NOTICE: (05)  : bisync resync filters-file=/tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:02 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:03:02 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:02 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:02 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:02 INFO  : Synching Path1 "/tmp/020248ge/check_access_filters/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:02 INFO  : Using filters file /tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:02 INFO  : Storing filters file hash to /tmp/020248ge/workdir/exclude-other-filtersfile.txt.md5
2025/08/31 02:03:02 INFO  : Copying Path2 files to Path1
2025/08/31 02:03:02 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:03:02 INFO  : There was nothing to transfer
2025/08/31 02:03:02 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:03:03 INFO  : There was nothing to transfer
2025/08/31 02:03:03 INFO  : Resync updating listings
2025/08/31 02:03:03 INFO  : Validating listings for Path1 "/tmp/020248ge/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:03 INFO  : Bisync successful
2025/08/31 02:03:03 NOTICE: (06)  : test EXCLUDE - test filters for check access
2025/08/31 02:03:03 NOTICE: (07)  : bisync check-access filters-file=/tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:06 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:03:06 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:06 INFO  : Synching Path1 "/tmp/020248ge/check_access_filters/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:06 INFO  : Using filters file /tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:06 INFO  : Building Path1 and Path2 listings
2025/08/31 02:03:07 INFO  : Path1 checking for diffs
2025/08/31 02:03:07 INFO  : Path2 checking for diffs
2025/08/31 02:03:07 INFO  : Checking access health
2025/08/31 02:03:07 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/08/31 02:03:07 INFO  : No changes found
2025/08/31 02:03:07 INFO  : Updating listings
2025/08/31 02:03:07 INFO  : Validating listings for Path1 "/tmp/020248ge/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:07 INFO  : Bisync successful
2025/08/31 02:03:07 NOTICE: (08)  : copy-listings exclude-initial
2025/08/31 02:03:07 NOTICE: (09)  : test EXCLUDE - delete RCLONE_TEST files in excluded directories
2025/08/31 02:03:07 NOTICE: (10)  : delete-file TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir/subdirA/RCLONE_TEST
2025/08/31 02:03:07 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir/subdirA/"
2025/08/31 02:03:08 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir/subdirA/" to be canonical "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir/subdirA"
2025/08/31 02:03:09 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:09 NOTICE: (11)  : delete-file /tmp/020248ge/check_access_filters/path1/subdir-not/RCLONE_TEST
2025/08/31 02:03:09 DEBUG : Creating backend with remote "/tmp/020248ge/check_access_filters/path1/subdir-not/"
2025/08/31 02:03:09 DEBUG : fs cache: renaming cache item "/tmp/020248ge/check_access_filters/path1/subdir-not/" to be canonical "/tmp/020248ge/check_access_filters/path1/subdir-not"
2025/08/31 02:03:09 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:09 NOTICE: (12)  : delete-file TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST
2025/08/31 02:03:09 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir-not/subdir-not2/"
2025/08/31 02:03:10 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir-not/subdir-not2/" to be canonical "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/subdir-not/subdir-not2"
2025/08/31 02:03:11 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:11 NOTICE: (13)  : delete-file /tmp/020248ge/check_access_filters/path1/subdirX/RCLONE_TEST
2025/08/31 02:03:11 DEBUG : Creating backend with remote "/tmp/020248ge/check_access_filters/path1/subdirX/"
2025/08/31 02:03:11 DEBUG : fs cache: renaming cache item "/tmp/020248ge/check_access_filters/path1/subdirX/" to be canonical "/tmp/020248ge/check_access_filters/path1/subdirX"
2025/08/31 02:03:11 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:11 NOTICE: (14)  : test EXCLUDE - test should PASS
2025/08/31 02:03:11 NOTICE: (15)  : bisync check-access filters-file=/tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:15 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:03:15 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:15 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:15 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:15 INFO  : Synching Path1 "/tmp/020248ge/check_access_filters/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:15 INFO  : Using filters file /tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:15 INFO  : Building Path1 and Path2 listings
2025/08/31 02:03:15 INFO  : Path1 checking for diffs
2025/08/31 02:03:15 INFO  : Path2 checking for diffs
2025/08/31 02:03:15 INFO  : Checking access health
2025/08/31 02:03:15 INFO  : Found 3 matching "RCLONE_TEST" files on both paths
2025/08/31 02:03:15 INFO  : No changes found
2025/08/31 02:03:15 INFO  : Updating listings
2025/08/31 02:03:15 INFO  : Validating listings for Path1 "/tmp/020248ge/check_access_filters/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:15 INFO  : Bisync successful
2025/08/31 02:03:15 NOTICE: (16)  : copy-listings exclude-pass-run
2025/08/31 02:03:15 NOTICE: (17)  : test EXCLUDE - delete RCLONE_TEST files in included directories
2025/08/31 02:03:15 NOTICE: (18)  : delete-file TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/RCLONE_TEST
2025/08/31 02:03:15 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:17 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/" to be canonical "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2"
2025/08/31 02:03:17 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:17 NOTICE: (19)  : delete-file /tmp/020248ge/check_access_filters/path1/subdir/RCLONE_TEST
2025/08/31 02:03:17 DEBUG : Creating backend with remote "/tmp/020248ge/check_access_filters/path1/subdir/"
2025/08/31 02:03:17 DEBUG : fs cache: renaming cache item "/tmp/020248ge/check_access_filters/path1/subdir/" to be canonical "/tmp/020248ge/check_access_filters/path1/subdir"
2025/08/31 02:03:17 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:03:17 NOTICE: (20)  : test EXCLUDE - test should ABORT
2025/08/31 02:03:17 NOTICE: (21)  : bisync check-access filters-file=/tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:20 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:03:20 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:20 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:20 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:20 INFO  : Synching Path1 "/tmp/020248ge/check_access_filters/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/"
2025/08/31 02:03:20 INFO  : Using filters file /tmp/020248ge/workdir/exclude-other-filtersfile.txt
2025/08/31 02:03:20 INFO  : Building Path1 and Path2 listings
2025/08/31 02:03:21 INFO  : Path1 checking for diffs
2025/08/31 02:03:21 INFO  : - Path1    File was deleted          - subdir/RCLONE_TEST
2025/08/31 02:03:21 INFO  : Path1:    1 changes:    0 new,    0 modified,    1 deleted
2025/08/31 02:03:21 INFO  : Path2 checking for diffs
2025/08/31 02:03:21 INFO  : - Path2    File was deleted          - RCLONE_TEST
2025/08/31 02:03:21 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/08/31 02:03:21 INFO  : Checking access health
2025/08/31 02:03:21 ERROR : -          Access test failed: Path1 file not found in Path2 - RCLONE_TEST
2025/08/31 02:03:21 ERROR : -          Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST
2025/08/31 02:03:21 ERROR : Bisync critical error: check file check failed
2025/08/31 02:03:21 ERROR : Bisync aborted. Must run --resync to recover.
2025/08/31 02:03:21 NOTICE: Bisync error: bisync aborted
2025/08/31 02:03:21 NOTICE: (22)  : move-listings exclude-error-run
2025/08/31 02:03:21 NOTICE: (23)  : test INCLUDE - OTHER TESTS
2025/08/31 02:03:21 NOTICE: (24)  : test reset to the initial state
2025/08/31 02:03:21 NOTICE: (25)  : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial /tmp/020248ge/check_access_filters/path1/
2025/08/31 02:03:21 DEBUG : Creating backend with remote "/tmp/020248ge/check_access_filters/path1/"
2025/08/31 02:03:21 DEBUG : fs cache: renaming cache item "/tmp/020248ge/check_access_filters/path1/" to be canonical "/tmp/020248ge/check_access_filters/path1"
2025/08/31 02:03:21 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : file1.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : file2.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdir-not", newDst=subdir-not
2025/08/31 02:03:21 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/31 02:03:21 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdirX", newDst=subdirX
2025/08/31 02:03:21 DEBUG : file3.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : file4.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:21 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:21 INFO  : subdirX/subdirX1: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdirX/subdirX1", newDst=subdirX/subdirX1
2025/08/31 02:03:21 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir-not/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:21 INFO  : subdir-not/subdir-not2: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdir-not/subdir-not2", newDst=subdir-not/subdir-not2
2025/08/31 02:03:21 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir/file20.txt: Unchanged skipping
2025/08/31 02:03:21 INFO  : subdir/subdirA: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdir/subdirA", newDst=subdir/subdirA
2025/08/31 02:03:21 INFO  : subdir/subdirB: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 DEBUG : Added delayed dir = "subdir/subdirB", newDst=subdir/subdirB
2025/08/31 02:03:21 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir/subdirA/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir/subdirA/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:03:21 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ns)
2025/08/31 02:03:21 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/RCLONE_TEST.cf9a93ac.partial: renamed to: subdirX/RCLONE_TEST
2025/08/31 02:03:21 INFO  : subdirX/RCLONE_TEST: Copied (new)
2025/08/31 02:03:21 DEBUG : Local file system at /tmp/020248ge/check_access_filters/path1: Waiting for checks to finish
2025/08/31 02:03:21 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : Local file system at /tmp/020248ge/check_access_filters/path1: Waiting for transfers to finish
2025/08/31 02:03:21 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:03:21 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/08/31 02:03:21 DEBUG : subdir-not/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:03:21 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/08/31 02:03:21 DEBUG : subdir-not/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir-not/RCLONE_TEST
2025/08/31 02:03:21 INFO  : subdir-not/RCLONE_TEST: Copied (new)
2025/08/31 02:03:21 INFO  : subdirX: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 INFO  : subdir-not: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:03:21 NOTICE: (26)  : sync-dir /tmp/020248ge/check_access_filters/path1/ TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/
2025/08/31 02:03:21 DEBUG : fs cache: switching user supplied name "/tmp/020248ge/check_access_filters/path1/" for canonical name "/tmp/020248ge/check_access_filters/path1"
2025/08/31 02:03:21 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2/" for canonical name "TestB2:rclone-test-namuyuc1mevo/020248ge/check_access_filters/path2"
2025/08/31 02:03:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:21 DEBUG : file1.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : file1.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file2.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : file2.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file3.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : file3.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : file4.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : file4.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : subdirX/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdirX/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : subdirX/file20.txt: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir-not/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : subdir-not/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:21 DEBUG : subdir-not/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:21 DEBUG : subdir-not/file20.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdir/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdir/file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir/file20.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdirX/subdirX1/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdirX/subdirX1/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdirX/subdirX1/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdirX/subdirX1/file30.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:22 DEBUG : subdir-not/subdir-not2/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir-not/subdir-not2/file30.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdir/subdirB/RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir/subdirB/RCLONE_TEST: Unchanged skipping
2025/08/31 02:03:22 DEBUG : subdir/subdirB/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir/subdirB/file30.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:03:22 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:22 DEBUG : subdir/subdirA/RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:22 DEBUG : subdir/subdirA/file30.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/08/31 02:03:22 DEBUG : subdir/subdirA/file30.txt: Unchanged skipping
2025/08/31 02:03:22 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Waiting for checks to finish
2025/08/31 02:03:22 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/check_access_filters/path2: Waiting for transfers to finish
2025/08/31 02:03:22 DEBUG : subdir-not/subdir-not2/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:03:22 INFO  : subdir-not/subdir-not2/RCLONE_TEST: Copied (new)
2025/08/31 02:03:22 DEBUG : subdir/subdirA/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:03:22 INFO  : subdir/subdirA/RCLONE_TEST: Copied (new)
2025/08/31 02:03:22 DEBUG : Waiting for deletions to finish
2025/08/31 02:03:22 NOTICE: (27)  : copy-file /tmp/020248ge/datadir/test_check_access_filters-xojisur4/include-other-filtersfile.txt /tmp/020248ge/workdir/
2025/08/31 02:03:22 DEBUG : copyFile "/tmp/020248ge/datadir/test_check_access_filters-xojisur4/include-other-filtersfile.txt" to "/tmp/020248ge/workdir/" as ""
2025/08/31 02:03:22 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_check_access_filters-xojisur4/include-other-filtersfile.txt"
2025/08/31 02:03:22 DEBUG : Creating backend with remote "/tmp/020248ge/workdir/"
2025/08/31 02:03:23 DEBUG : operations.CopyFile "include-other-filtersfile.txt" to "Local file system at /tmp/020248ge/workdir" as "include-other-filtersfile.txt"
2025/08/31 02:03:23 DEBUG : include-other-filtersfile.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:23 DEBUG : include-other-filtersfile.txt: md5 = 2ec13b2813141ed088e5978ef5a47b0e OK
2025/08/31 02:03:23 DEBUG : include-other-filtersfile.txt.5fb1cd78.partial: renamed to: include-other-filtersfile.txt
2025/08/31 02:03:23 INFO  : include-other-filtersfile.txt: Copied (new)
2025/08/31 02:03:23 NOTICE: (28)  : bisync resync filters-file=/tmp/020248ge/workdir/include-other-filtersfile.txt
2025/08/31 02:03:23 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1179-16.backblaze.com/b2api/v1/b2_upload_file/cff2eda3d0cdfbdb9f880e1b/c001_v0001179_t0051": EOF
2025/08/31 02:03:23 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1179-16.backblaze.com/b2api/v1/b2_upload_file/cff2eda3d0cdfbdb9f880e1b/c001_v0001179_t0051": EOF)
2025/08/31 02:03:23 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    bisync_test.go:1013: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1013
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1029
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1094
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:777
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:531
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:394
        	Error:      	Received unexpected error:
        	            	Post "https://pod-000-1179-16.backblaze.com/b2api/v1/b2_upload_file/cff2eda3d0cdfbdb9f880e1b/c001_v0001179_t0051": EOF
        	Test:       	TestBisyncLocalRemote/check_access_filters
=== RUN   TestBisyncLocalRemote/resync
2025/08/31 02:03:23 DEBUG : Creating backend with remote "/tmp/020248ge/resync/path1"
2025/08/31 02:03:23 INFO  : path2: Making directory
2025/08/31 02:03:23 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2"
2025/08/31 02:03:24 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/08/31 02:03:24 DEBUG : Creating backend with remote "/tmp/020248ge/initdir/test_resync-rovosut8"
2025/08/31 02:03:24 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : Local file system at /tmp/020248ge/initdir/test_resync-rovosut8: Waiting for checks to finish
2025/08/31 02:03:24 DEBUG : Local file system at /tmp/020248ge/initdir/test_resync-rovosut8: Waiting for transfers to finish
2025/08/31 02:03:24 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:03:24 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/31 02:03:24 INFO  : file3.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/31 02:03:24 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/31 02:03:24 INFO  : file5.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:03:24 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/31 02:03:24 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 INFO  : file6.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/31 02:03:24 INFO  : file7.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:03:24 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:24 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:24 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:03:24 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/08/31 02:03:24 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1"
2025/08/31 02:03:24 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:24 DEBUG : Local file system at /tmp/020248ge/datadir/test_resync-jiqojop1: Waiting for checks to finish
2025/08/31 02:03:24 DEBUG : Local file system at /tmp/020248ge/datadir/test_resync-jiqojop1: Waiting for transfers to finish
2025/08/31 02:03:24 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:03:24 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:03:24 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt
2025/08/31 02:03:24 INFO  : fileB.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt
2025/08/31 02:03:24 INFO  : fileA.txt: Copied (new)
2025/08/31 02:03:24 DEBUG : Waiting for deletions to finish
2025/08/31 02:03:24 ERROR : error listing: directory not found
2025/08/31 02:03:24 ERROR : Local file system at /tmp/020248ge/resync/path1: Failed to list "": directory not found
2025/08/31 02:03:24 DEBUG : removing 1 level 0 directories
2025/08/31 02:03:24 INFO  : Local file system at /tmp/020248ge/resync/path1: Removing directory
2025/08/31 02:03:24 ERROR : Failed to rmdir: stat /tmp/020248ge/resync/path1: no such file or directory
2025/08/31 02:03:24 INFO  : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: cleaning bucket "rclone-test-namuyuc1mevo" of all files
2025/08/31 02:03:24 ERROR : error listing: directory not found
2025/08/31 02:03:25 NOTICE: checking initFs Local file system at /tmp/020248ge/initdir/test_resync-rovosut8
2025/08/31 02:03:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : Local file system at /tmp/020248ge/resync/path1: Waiting for checks to finish
2025/08/31 02:03:25 DEBUG : Local file system at /tmp/020248ge/resync/path1: Waiting for transfers to finish
2025/08/31 02:03:25 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:03:25 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:03:25 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:03:25 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/31 02:03:25 INFO  : file3.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:03:25 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:25 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/31 02:03:25 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/31 02:03:25 INFO  : file5.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:03:25 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/31 02:03:25 INFO  : file6.txt: Copied (new)
2025/08/31 02:03:25 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/31 02:03:25 INFO  : file7.txt: Copied (new)
2025/08/31 02:03:25 NOTICE: checking Path1 Local file system at /tmp/020248ge/resync/path1
2025/08/31 02:03:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:03:25 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Waiting for checks to finish
2025/08/31 02:03:25 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Waiting for transfers to finish
2025/08/31 02:03:26 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file5.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:03:26 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:26 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file3.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file7.txt: Copied (new)
2025/08/31 02:03:26 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:03:26 INFO  : file6.txt: Copied (new)
2025/08/31 02:03:26 NOTICE: checking path2 B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2
2025/08/31 02:03:27 NOTICE: (01)  : test resync
2025/08/31 02:03:27 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/08/31 02:03:27 NOTICE: (03)  : purge-children /tmp/020248ge/resync/path1/
2025/08/31 02:03:27 DEBUG : file6.txt: Remove file
2025/08/31 02:03:27 DEBUG : file4.txt: Remove file
2025/08/31 02:03:27 DEBUG : file1.txt: Remove file
2025/08/31 02:03:27 DEBUG : file3.txt: Remove file
2025/08/31 02:03:27 DEBUG : RCLONE_TEST: Remove file
2025/08/31 02:03:27 DEBUG : file5.txt: Remove file
2025/08/31 02:03:27 DEBUG : file7.txt: Remove file
2025/08/31 02:03:27 DEBUG : file2.txt: Remove file
2025/08/31 02:03:27 NOTICE: (04)  : bisync resync
2025/08/31 02:03:29 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:29 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:29 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:29 INFO  : Synching Path1 "/tmp/020248ge/resync/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:29 INFO  : Copying Path2 files to Path1
2025/08/31 02:03:29 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:03:30 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:30 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file3.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file5.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file6.txt: Copied (new)
2025/08/31 02:03:30 INFO  : file7.txt: Copied (new)
2025/08/31 02:03:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:03:31 INFO  : Resync updating listings
2025/08/31 02:03:31 INFO  : Validating listings for Path1 "/tmp/020248ge/resync/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:31 INFO  : Bisync successful
2025/08/31 02:03:31 NOTICE: (05)  : move-listings empty-path1
2025/08/31 02:03:31 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/08/31 02:03:31 NOTICE: (07)  : purge-children TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/
2025/08/31 02:03:31 DEBUG : RCLONE_TEST: Remove file
2025/08/31 02:03:31 DEBUG : file1.txt: Remove file
2025/08/31 02:03:31 DEBUG : file2.txt: Remove file
2025/08/31 02:03:31 DEBUG : file3.txt: Remove file
2025/08/31 02:03:32 DEBUG : file4.txt: Remove file
2025/08/31 02:03:32 DEBUG : file5.txt: Remove file
2025/08/31 02:03:32 DEBUG : file6.txt: Remove file
2025/08/31 02:03:32 DEBUG : file7.txt: Remove file
2025/08/31 02:03:32 NOTICE: (08)  : bisync resync
2025/08/31 02:03:36 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:36 INFO  : Synching Path1 "/tmp/020248ge/resync/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:36 INFO  : Copying Path2 files to Path1
2025/08/31 02:03:36 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:03:36 INFO  : There was nothing to transfer
2025/08/31 02:03:36 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:03:37 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:37 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:37 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:03:37 INFO  : file3.txt: Copied (new)
2025/08/31 02:03:37 INFO  : file5.txt: Copied (new)
2025/08/31 02:03:37 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:37 INFO  : file6.txt: Copied (new)
2025/08/31 02:03:37 INFO  : file7.txt: Copied (new)
2025/08/31 02:03:37 INFO  : Resync updating listings
2025/08/31 02:03:37 INFO  : Validating listings for Path1 "/tmp/020248ge/resync/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:37 INFO  : Bisync successful
2025/08/31 02:03:37 NOTICE: (09)  : move-listings empty-path2
2025/08/31 02:03:37 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/08/31 02:03:37 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/020248ge/datadir/test_resync-jiqojop1/ fileA.txt
2025/08/31 02:03:37 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/"
2025/08/31 02:03:37 DEBUG : fs cache: renaming cache item "/tmp/020248ge/datadir/test_resync-jiqojop1/" to be canonical "/tmp/020248ge/datadir/test_resync-jiqojop1"
2025/08/31 02:03:37 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/08/31 02:03:37 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/020248ge/datadir/test_resync-jiqojop1/ fileB.txt
2025/08/31 02:03:37 DEBUG : fs cache: switching user supplied name "/tmp/020248ge/datadir/test_resync-jiqojop1/" for canonical name "/tmp/020248ge/datadir/test_resync-jiqojop1"
2025/08/31 02:03:37 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/08/31 02:03:37 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/08/31 02:03:37 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/08/31 02:03:37 NOTICE: (15)  : delete-file TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/file1.txt
2025/08/31 02:03:37 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:39 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/" to be canonical "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2"
2025/08/31 02:03:39 INFO  : file1.txt: Deleted
2025/08/31 02:03:39 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/08/31 02:03:39 NOTICE: (17)  : delete-file /tmp/020248ge/resync/path1/file2.txt
2025/08/31 02:03:39 DEBUG : Creating backend with remote "/tmp/020248ge/resync/path1/"
2025/08/31 02:03:39 DEBUG : fs cache: renaming cache item "/tmp/020248ge/resync/path1/" to be canonical "/tmp/020248ge/resync/path1"
2025/08/31 02:03:39 INFO  : file2.txt: Deleted
2025/08/31 02:03:39 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/08/31 02:03:39 NOTICE: (19)  : copy-as /tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/ file3.txt
2025/08/31 02:03:39 DEBUG : copyFile "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt" to "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/" as "file3.txt"
2025/08/31 02:03:39 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt"
2025/08/31 02:03:39 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:40 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2" as "file3.txt"
2025/08/31 02:03:40 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/31 02:03:42 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/31 02:03:42 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/08/31 02:03:42 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/08/31 02:03:42 NOTICE: (21)  : delete-file /tmp/020248ge/resync/path1/file4.txt
2025/08/31 02:03:42 DEBUG : fs cache: switching user supplied name "/tmp/020248ge/resync/path1/" for canonical name "/tmp/020248ge/resync/path1"
2025/08/31 02:03:42 INFO  : file4.txt: Deleted
2025/08/31 02:03:42 NOTICE: (22)  : copy-as /tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/ file4.txt
2025/08/31 02:03:42 DEBUG : copyFile "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt" to "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/" as "file4.txt"
2025/08/31 02:03:42 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt"
2025/08/31 02:03:42 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:43 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2" as "file4.txt"
2025/08/31 02:03:43 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/31 02:03:45 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/31 02:03:45 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/08/31 02:03:45 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/08/31 02:03:45 NOTICE: (24)  : copy-as /tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/ file5.txt
2025/08/31 02:03:45 DEBUG : copyFile "/tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt" to "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/" as "file5.txt"
2025/08/31 02:03:45 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt"
2025/08/31 02:03:45 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:46 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2" as "file5.txt"
2025/08/31 02:03:46 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/31 02:03:48 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/31 02:03:48 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/08/31 02:03:48 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/08/31 02:03:48 NOTICE: (26)  : copy-as /tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt /tmp/020248ge/resync/path1/ file6.txt
2025/08/31 02:03:48 DEBUG : copyFile "/tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt" to "/tmp/020248ge/resync/path1/" as "file6.txt"
2025/08/31 02:03:48 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/fileB.txt"
2025/08/31 02:03:48 DEBUG : Creating backend with remote "/tmp/020248ge/resync/path1/"
2025/08/31 02:03:48 DEBUG : operations.CopyFile "fileB.txt" to "Local file system at /tmp/020248ge/resync/path1" as "file6.txt"
2025/08/31 02:03:48 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/31 02:03:48 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:03:48 DEBUG : file6.txt.f0a2c6af.partial: renamed to: file6.txt
2025/08/31 02:03:48 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/08/31 02:03:48 NOTICE: (27)  : copy-as /tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/ file6.txt
2025/08/31 02:03:48 DEBUG : copyFile "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt" to "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/" as "file6.txt"
2025/08/31 02:03:48 DEBUG : Creating backend with remote "/tmp/020248ge/datadir/test_resync-jiqojop1/fileA.txt"
2025/08/31 02:03:48 DEBUG : Creating backend with remote "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:49 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2" as "file6.txt"
2025/08/31 02:03:49 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/31 02:03:50 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/31 02:03:50 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/08/31 02:03:50 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/08/31 02:03:50 NOTICE: (29)  : test run bisync with resync
2025/08/31 02:03:50 NOTICE: (30)  : bisync resync
2025/08/31 02:03:55 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:03:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:03:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:03:55 INFO  : Synching Path1 "/tmp/020248ge/resync/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:55 INFO  : Copying Path2 files to Path1
2025/08/31 02:03:55 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:03:55 INFO  : file4.txt: Copied (new)
2025/08/31 02:03:55 INFO  : file2.txt: Copied (new)
2025/08/31 02:03:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:03:56 INFO  : file3.txt: Copied (replaced existing)
2025/08/31 02:03:56 INFO  : file5.txt: Copied (replaced existing)
2025/08/31 02:03:56 INFO  : file1.txt: Copied (new)
2025/08/31 02:03:56 INFO  : file6.txt: Updated modification time in destination
2025/08/31 02:03:56 INFO  : Resync updating listings
2025/08/31 02:03:56 INFO  : Validating listings for Path1 "/tmp/020248ge/resync/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:03:56 INFO  : Bisync successful
2025/08/31 02:03:56 NOTICE: (31)  : copy-listings mixed-diffs
2025/08/31 02:03:56 NOTICE: (32)  : test run normal bisync
2025/08/31 02:03:56 NOTICE: (33)  : bisync
2025/08/31 02:04:00 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:04:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:04:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:04:00 INFO  : Synching Path1 "/tmp/020248ge/resync/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:04:00 INFO  : Building Path1 and Path2 listings
2025/08/31 02:04:00 INFO  : Path1 checking for diffs
2025/08/31 02:04:00 INFO  : Path2 checking for diffs
2025/08/31 02:04:00 INFO  : No changes found
2025/08/31 02:04:00 INFO  : Updating listings
2025/08/31 02:04:00 INFO  : Validating listings for Path1 "/tmp/020248ge/resync/path1/" vs Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:04:00 INFO  : Bisync successful
2025/08/31 02:04:00 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/08/31 02:04:00 NOTICE: (35)  : purge-children TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/
2025/08/31 02:04:00 DEBUG : RCLONE_TEST: Remove file
2025/08/31 02:04:00 DEBUG : file1.txt: Remove file
2025/08/31 02:04:00 DEBUG : file2.txt: Remove file
2025/08/31 02:04:01 DEBUG : file3.txt: Remove file
2025/08/31 02:04:01 DEBUG : file4.txt: Remove file
2025/08/31 02:04:01 DEBUG : file5.txt: Remove file
2025/08/31 02:04:01 DEBUG : file6.txt: Remove file
2025/08/31 02:04:01 DEBUG : file7.txt: Remove file
2025/08/31 02:04:01 NOTICE: (36)  : bisync
2025/08/31 02:04:05 DEBUG : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:04:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:04:05 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/31 02:04:05 INFO  : Synching Path1 "/tmp/020248ge/resync/path1/" with Path2 "TestB2:rclone-test-namuyuc1mevo/020248ge/resync/path2/"
2025/08/31 02:04:05 INFO  : Building Path1 and Path2 listings
2025/08/31 02:04:05 INFO  : Path1 checking for diffs
2025/08/31 02:04:05 INFO  : Path2 checking for diffs
2025/08/31 02:04:05 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/020248ge/workdir/tmp_020248ge_resync_path1..TestB2_rclone-test-namuyuc1mevo_020248ge_resync_path2.path2.lst-new
2025/08/31 02:04:05 ERROR : Bisync critical error: empty current Path2 listing: /tmp/020248ge/workdir/tmp_020248ge_resync_path1..TestB2_rclone-test-namuyuc1mevo_020248ge_resync_path2.path2.lst-new
2025/08/31 02:04:05 ERROR : Bisync aborted. Must run --resync to recover.
2025/08/31 02:04:05 NOTICE: Bisync error: bisync aborted
2025/08/31 02:04:05 DEBUG : Waiting for deletions to finish
2025/08/31 02:04:05 INFO  : RCLONE_TEST: Deleted
2025/08/31 02:04:05 INFO  : file2.txt: Deleted
2025/08/31 02:04:05 INFO  : file7.txt: Deleted
2025/08/31 02:04:05 INFO  : file3.txt: Deleted
2025/08/31 02:04:05 INFO  : file6.txt: Deleted
2025/08/31 02:04:05 INFO  : file1.txt: Deleted
2025/08/31 02:04:05 INFO  : file4.txt: Deleted
2025/08/31 02:04:05 INFO  : file5.txt: Deleted
2025/08/31 02:04:05 DEBUG : removing 1 level 0 directories
2025/08/31 02:04:05 INFO  : Local file system at /tmp/020248ge/resync/path1: Removing directory
2025/08/31 02:04:05 INFO  : B2 bucket rclone-test-namuyuc1mevo path 020248ge/resync/path2: cleaning bucket "rclone-test-namuyuc1mevo" of all files
2025/08/31 02:04:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b40d_d20250831_m020400_c001_v7007000_t0000_u01756605840547")
2025/08/31 02:04:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce928e_d20250831_m020337_c001_v0001181_t0057_u01756605817408")
2025/08/31 02:04:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da03d_d20250831_m020331_c001_v7007000_t0000_u01756605811379")
2025/08/31 02:04:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce927e_d20250831_m020326_c001_v0001181_t0045_u01756605806495")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b412_d20250831_m020400_c001_v7007000_t0000_u01756605840729")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f109bbb2635d0df83_d20250831_m020356_c001_v0001032_t0038_u01756605836102")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da064_d20250831_m020339_c001_v7007000_t0000_u01756605819342")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce82196_d20250831_m020337_c001_v0001184_t0023_u01756605817392")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da03e_d20250831_m020331_c001_v7007000_t0000_u01756605811562")
2025/08/31 02:04:06 DEBUG : file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce82164_d20250831_m020325_c001_v0001184_t0012_u01756605805932")
2025/08/31 02:04:06 DEBUG : file2.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b415_d20250831_m020400_c001_v7007000_t0000_u01756605840912")
2025/08/31 02:04:06 DEBUG : file2.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f109bbb2635d0df73_d20250831_m020337_c001_v0001032_t0025_u01756605817059")
2025/08/31 02:04:06 DEBUG : file2.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da03f_d20250831_m020331_c001_v7007000_t0000_u01756605811745")
2025/08/31 02:04:06 DEBUG : file2.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104677d165e01513_d20250831_m020326_c001_v0001172_t0052_u01756605806108")
2025/08/31 02:04:06 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b417_d20250831_m020401_c001_v7007000_t0000_u01756605841095")
2025/08/31 02:04:06 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce92a6_d20250831_m020356_c001_v0001181_t0041_u01756605836103")
2025/08/31 02:04:06 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1170b4a01496b9a4_d20250831_m020342_c001_v0001091_t0006_u01756605822125")
2025/08/31 02:04:06 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104677d165e01549_d20250831_m020337_c001_v0001172_t0001_u01756605817398")
2025/08/31 02:04:06 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da040_d20250831_m020331_c001_v7007000_t0000_u01756605811928")
2025/08/31 02:04:07 DEBUG : file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f109bbb2635d0df69_d20250831_m020326_c001_v0001032_t0002_u01756605806301")
2025/08/31 02:04:07 DEBUG : file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b418_d20250831_m020401_c001_v7007000_t0000_u01756605841277")
2025/08/31 02:04:07 DEBUG : file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f100af858f63665c0_d20250831_m020345_c001_v0001134_t0052_u01756605825036")
2025/08/31 02:04:07 DEBUG : file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f109bbb2635d0df75_d20250831_m020337_c001_v0001032_t0002_u01756605817442")
2025/08/31 02:04:07 DEBUG : file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da041_d20250831_m020332_c001_v7007000_t0000_u01756605812124")
2025/08/31 02:04:07 DEBUG : file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce82166_d20250831_m020326_c001_v0001184_t0039_u01756605806166")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b419_d20250831_m020401_c001_v7007000_t0000_u01756605841458")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104677d165e0157d_d20250831_m020356_c001_v0001172_t0029_u01756605836131")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f101833da63f84288_d20250831_m020347_c001_v0001183_t0026_u01756605827938")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce8219a_d20250831_m020337_c001_v0001184_t0026_u01756605817601")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da042_d20250831_m020332_c001_v7007000_t0000_u01756605812307")
2025/08/31 02:04:07 DEBUG : file5.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce8216a_d20250831_m020326_c001_v0001184_t0028_u01756605806367")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b41b_d20250831_m020401_c001_v7007000_t0000_u01756605841639")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1180e3a7b3407c0c_d20250831_m020355_c001_v0001115_t0010_u01756605835983")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104834ef72243e6d_d20250831_m020350_c001_v0001134_t0044_u01756605830759")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce9290_d20250831_m020337_c001_v0001181_t0011_u01756605817624")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da044_d20250831_m020332_c001_v7007000_t0000_u01756605812515")
2025/08/31 02:04:07 DEBUG : file6.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104677d165e01517_d20250831_m020326_c001_v0001172_t0042_u01756605806547")
2025/08/31 02:04:07 DEBUG : file7.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b41d_d20250831_m020401_c001_v7007000_t0000_u01756605841824")
2025/08/31 02:04:07 DEBUG : file7.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f104677d165e0154b_d20250831_m020337_c001_v0001172_t0019_u01756605817672")
2025/08/31 02:04:08 DEBUG : file7.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da045_d20250831_m020332_c001_v7007000_t0000_u01756605812698")
2025/08/31 02:04:08 DEBUG : file7.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce8216c_d20250831_m020326_c001_v0001184_t0057_u01756605806621")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b439_d20250831_m020405_c001_v7007000_t0000_u01756605845530")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f119a42f0f7e50a8d_d20250831_m020404_c001_v0001181_t0012_u01756605844323")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce92b6_d20250831_m020402_c001_v0001181_t0022_u01756605842367")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b40c_d20250831_m020359_c001_v7007000_t0000_u01756605839987")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11007537c9d7b2e6_d20250831_m020358_c001_v0001177_t0011_u01756605838780")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce82204_d20250831_m020357_c001_v0001184_t0006_u01756605837319")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f414275531220b3f6_d20250831_m020354_c001_v7007000_t0000_u01756605834978")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f114ceb26b9170b26_d20250831_m020353_c001_v0001106_t0054_u01756605833228")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113a1d0d9ce821d4_d20250831_m020351_c001_v0001184_t0025_u01756605831421")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da057_d20250831_m020336_c001_v7007000_t0000_u01756605816477")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f103fb42cdabfd144_d20250831_m020335_c001_v0001040_t0053_u01756605815064")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f109bbb2635d0df71_d20250831_m020333_c001_v0001032_t0018_u01756605813241")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f420768a9af4da03c_d20250831_m020329_c001_v7007000_t0000_u01756605809861")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11195d33788cab97_d20250831_m020328_c001_v0001184_t0001_u01756605808581")
2025/08/31 02:04:08 DEBUG : modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f113189a273ce9280_d20250831_m020327_c001_v0001181_t0057_u01756605807155")
    bisync_test.go:597: TEST resync PASSED
2025/08/31 02:04:09 DEBUG : B2 bucket rclone-test-namuyuc1mevo: Purge remote
2025/08/31 02:04:09 INFO  : B2 bucket rclone-test-namuyuc1mevo: cleaning bucket "rclone-test-namuyuc1mevo" of all files
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbedc_d20250831_m020322_c001_v0001098_t0032_u01756605802073")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4023ff6b64eb76f8_d20250831_m020317_c001_v7007000_t0000_u01756605797346")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11624c9c2aadd214_d20250831_m020257_c001_v0001179_t0026_u01756605777482")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/file1.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbeaa_d20250831_m020256_c001_v0001098_t0038_u01756605776925")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/file2.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b63833_d20250831_m020257_c001_v0001183_t0012_u01756605777111")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/file3.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a5622_d20250831_m020257_c001_v0001182_t0012_u01756605777297")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/file4.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbeac_d20250831_m020257_c001_v0001098_t0024_u01756605777232")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4023ff6b64eb7705_d20250831_m020320_c001_v7007000_t0000_u01756605800755")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1042dde3d4c4e30b_d20250831_m020319_c001_v0001157_t0017_u01756605799345")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11624c9c2aadd236_d20250831_m020317_c001_v0001179_t0010_u01756605797898")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4023ff6b64eb76f1_d20250831_m020315_c001_v7007000_t0000_u01756605795318")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1162bcded2cd69f6_d20250831_m020313_c001_v0001032_t0025_u01756605793898")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a566f_d20250831_m020311_c001_v0001182_t0029_u01756605791764")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4078226740642bec_d20250831_m020306_c001_v7007000_t0000_u01756605786609")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11805dff5ccefd2c_d20250831_m020305_c001_v0001171_t0032_u01756605785298")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b63855_d20250831_m020303_c001_v0001183_t0043_u01756605783837")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4078226740642bea_d20250831_m020302_c001_v7007000_t0000_u01756605782193")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f100a6ee60acc1769_d20250831_m020300_c001_v0001091_t0025_u01756605780690")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/modtime_write_test: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbeba_d20250831_m020258_c001_v0001098_t0039_u01756605778735")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/subdir-not/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b63837_d20250831_m020257_c001_v0001183_t0022_u01756605777616")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/subdir-not/file20.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbeae_d20250831_m020257_c001_v0001098_t0037_u01756605777646")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b638a1_d20250831_m020322_c001_v0001183_t0042_u01756605802606")
2025/08/31 02:04:10 DEBUG : 020248ge/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f4101143ce8e0d39b_d20250831_m020311_c001_v7007000_t0000_u01756605791213")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11624c9c2aadd216_d20250831_m020257_c001_v0001179_t0049_u01756605777894")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir-not/subdir-not2/file30.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1082d1f142efbeb0_d20250831_m020257_c001_v0001098_t0028_u01756605777958")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b6383b_d20250831_m020258_c001_v0001183_t0055_u01756605778024")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/file20.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a5628_d20250831_m020258_c001_v0001182_t0058_u01756605778036")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a56ad_d20250831_m020322_c001_v0001182_t0043_u01756605802870")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f421ff5b6ef72aaec_d20250831_m020309_c001_v7007000_t0000_u01756605789183")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirA/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b6383d_d20250831_m020258_c001_v0001183_t0015_u01756605778228")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirA/file30.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11624c9c2aadd21a_d20250831_m020258_c001_v0001179_t0033_u01756605778299")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirB/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f11624c9c2aadd218_d20250831_m020258_c001_v0001179_t0029_u01756605778099")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdir/subdirB/file30.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a562c_d20250831_m020258_c001_v0001182_t0000_u01756605778256")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdirX/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b63835_d20250831_m020257_c001_v0001183_t0029_u01756605777308")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdirX/file20.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a5624_d20250831_m020257_c001_v0001182_t0023_u01756605777618")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdirX/subdirX1/RCLONE_TEST: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f110e624c11b63839_d20250831_m020257_c001_v0001183_t0017_u01756605777818")
2025/08/31 02:04:11 DEBUG : 020248ge/check_access_filters/path2/subdirX/subdirX1/file30.txt: Deleting (id "4_zcff2eda3d0cdfbdb9f880e1b_f1125114d762a5626_d20250831_m020257_c001_v0001182_t0015_u01756605777830")
--- FAIL: TestBisyncLocalRemote (86.40s)
    --- FAIL: TestBisyncLocalRemote/check_access_filters (34.22s)
    --- PASS: TestBisyncLocalRemote/resync (46.17s)
=== RUN   TestBisyncRemoteRemote
2025/08/31 02:04:14 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho"
2025/08/31 02:04:14 DEBUG : Config file has changed externally - reloading
2025/08/31 02:04:15 NOTICE: remote: TestB2:rclone-test-joqiraj5raho
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/08/31 02:04:15 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko"
2025/08/31 02:04:16 INFO  : path1: Making directory
2025/08/31 02:04:17 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1"
2025/08/31 02:04:18 INFO  : path2: Making directory
2025/08/31 02:04:18 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2"
2025/08/31 02:04:19 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/08/31 02:04:19 DEBUG : Creating backend with remote "/tmp/020415ko/initdir/test_resync_modes-gujajiv5"
2025/08/31 02:04:19 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:04:19 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:04:19 INFO  : file1.txt: Copied (new)
2025/08/31 02:04:19 DEBUG : Local file system at /tmp/020415ko/initdir/test_resync_modes-gujajiv5: Waiting for checks to finish
2025/08/31 02:04:19 DEBUG : Local file system at /tmp/020415ko/initdir/test_resync_modes-gujajiv5: Waiting for transfers to finish
2025/08/31 02:04:19 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:04:19 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:04:19 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:04:19 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:04:19 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:04:19 INFO  : file2.txt: Copied (new)
2025/08/31 02:04:19 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/08/31 02:04:19 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:04:19 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:19 DEBUG : Local file system at /tmp/020415ko/datadir/test_resync_modes-covotum1: Waiting for checks to finish
2025/08/31 02:04:19 DEBUG : Local file system at /tmp/020415ko/datadir/test_resync_modes-covotum1: Waiting for transfers to finish
2025/08/31 02:04:19 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/08/31 02:04:19 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2025/08/31 02:04:19 INFO  : file2.txt: Copied (new)
2025/08/31 02:04:19 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/08/31 02:04:19 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2025/08/31 02:04:19 INFO  : file1R.txt: Copied (new)
2025/08/31 02:04:19 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/08/31 02:04:19 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2025/08/31 02:04:19 INFO  : file1L.txt: Copied (new)
2025/08/31 02:04:19 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:04:20 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:04:21 NOTICE: checking initFs Local file system at /tmp/020415ko/initdir/test_resync_modes-gujajiv5
2025/08/31 02:04:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:04:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:21 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Waiting for checks to finish
2025/08/31 02:04:21 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Waiting for transfers to finish
2025/08/31 02:04:22 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:04:22 INFO  : file1.txt: Copied (new)
2025/08/31 02:04:22 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:04:22 INFO  : file2.txt: Copied (new)
2025/08/31 02:04:22 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:04:22 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:04:22 NOTICE: checking Path1 B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1
2025/08/31 02:04:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:04:23 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:04:23 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Waiting for checks to finish
2025/08/31 02:04:23 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Waiting for transfers to finish
2025/08/31 02:04:24 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:04:24 INFO  : file1.txt: Copied (new)
2025/08/31 02:04:24 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:04:24 INFO  : file2.txt: Copied (new)
2025/08/31 02:04:24 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:04:24 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:04:24 NOTICE: checking path2 B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2
2025/08/31 02:04:24 NOTICE: (01)  : test resync-mode
2025/08/31 02:04:24 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:04:24 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:04:24 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/"
2025/08/31 02:04:24 DEBUG : fs cache: renaming cache item "/tmp/020415ko/datadir/test_resync_modes-covotum1/" to be canonical "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:04:24 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/08/31 02:04:24 NOTICE: (04)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:04:24 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:04:24 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:04:24 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:04:25 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:04:25 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/31 02:04:27 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:04:27 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:04:27 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:04:27 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:04:27 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/08/31 02:04:27 NOTICE: (06)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:04:27 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:04:27 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:04:27 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:04:28 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:04:28 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/31 02:04:30 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:04:30 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:04:30 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/08/31 02:04:30 NOTICE: (08)  : bisync resync resync-mode=newer
2025/08/31 02:05:09 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:09 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:05:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:05:09 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:09 INFO  : Copying Path2 files to Path1
2025/08/31 02:05:09 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:05:10 INFO  : There was nothing to transfer
2025/08/31 02:05:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:05:10 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:05:10 INFO  : Resync updating listings
2025/08/31 02:05:10 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:10 INFO  : Bisync successful
2025/08/31 02:05:10 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:05:10 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:05:10 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:10 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/08/31 02:05:10 NOTICE: (11)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:05:10 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:05:10 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:05:10 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:11 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:05:12 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/31 02:05:13 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:05:13 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:05:13 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:05:13 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:13 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/08/31 02:05:13 NOTICE: (13)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:05:13 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:05:13 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:05:13 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:05:14 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:05:15 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/08/31 02:05:15 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:05:16 INFO  : file1L.txt: Updated modification time in destination
2025/08/31 02:05:16 DEBUG : file1L.txt: Unchanged skipping
2025/08/31 02:05:16 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/08/31 02:05:16 NOTICE: (15)  : bisync resync resync-mode=path2
2025/08/31 02:05:25 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:25 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:05:25 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:05:25 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:25 INFO  : Copying Path2 files to Path1
2025/08/31 02:05:25 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:05:26 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:05:26 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:05:26 INFO  : Resync updating listings
2025/08/31 02:05:26 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:26 INFO  : Bisync successful
2025/08/31 02:05:26 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:05:26 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:05:26 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:26 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/08/31 02:05:26 NOTICE: (18)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:05:26 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:05:26 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:05:26 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:28 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:05:28 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/08/31 02:05:28 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:05:29 INFO  : file1R.txt: Updated modification time in destination
2025/08/31 02:05:29 DEBUG : file1R.txt: Unchanged skipping
2025/08/31 02:05:29 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:05:29 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:29 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/08/31 02:05:29 NOTICE: (20)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:05:29 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:05:29 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:05:29 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:05:30 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:05:30 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/31 02:05:32 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:05:32 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:05:32 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/08/31 02:05:32 NOTICE: (22)  : bisync resync resync-mode=larger
2025/08/31 02:05:40 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:40 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:40 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:05:40 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:05:40 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:40 INFO  : Copying Path2 files to Path1
2025/08/31 02:05:40 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:05:40 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/08/31 02:05:40 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:05:40 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:05:41 INFO  : Resync updating listings
2025/08/31 02:05:41 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:41 INFO  : Bisync successful
2025/08/31 02:05:41 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:05:41 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:05:41 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:41 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/08/31 02:05:41 NOTICE: (25)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:05:41 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:05:41 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:05:41 DEBUG : Config file has changed externally - reloading
2025/08/31 02:05:41 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:42 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:05:42 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/08/31 02:05:42 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:05:43 INFO  : file1R.txt: Updated modification time in destination
2025/08/31 02:05:43 DEBUG : file1R.txt: Unchanged skipping
2025/08/31 02:05:43 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:05:43 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:43 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/08/31 02:05:43 NOTICE: (27)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:05:43 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:05:43 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:05:43 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:05:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:05:44 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/31 02:05:46 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:05:46 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:05:46 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/08/31 02:05:46 NOTICE: (29)  : bisync resync resync-mode=older
2025/08/31 02:05:54 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:54 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:05:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:05:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:05:54 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:54 INFO  : Copying Path2 files to Path1
2025/08/31 02:05:54 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:05:55 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/08/31 02:05:55 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:05:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:05:55 INFO  : Resync updating listings
2025/08/31 02:05:55 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:55 INFO  : Bisync successful
2025/08/31 02:05:55 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:05:55 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:05:55 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:55 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/08/31 02:05:55 NOTICE: (32)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:05:55 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:05:55 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:05:55 DEBUG : Config file has changed externally - reloading
2025/08/31 02:05:55 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:05:56 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:05:56 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/08/31 02:05:56 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:05:58 INFO  : file1R.txt: Updated modification time in destination
2025/08/31 02:05:58 DEBUG : file1R.txt: Unchanged skipping
2025/08/31 02:05:58 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:05:58 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:05:58 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/08/31 02:05:58 NOTICE: (34)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:05:58 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:05:58 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:05:58 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:05:59 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:05:59 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/31 02:06:01 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:06:01 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:06:01 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/08/31 02:06:01 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/08/31 02:06:10 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:10 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:10 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:06:10 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:06:10 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:10 INFO  : Copying Path2 files to Path1
2025/08/31 02:06:10 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:06:10 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/31 02:06:10 INFO  : There was nothing to transfer
2025/08/31 02:06:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:06:10 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/31 02:06:11 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:06:11 INFO  : Resync updating listings
2025/08/31 02:06:11 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:11 INFO  : Bisync successful
2025/08/31 02:06:11 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/31 02:06:11 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:06:11 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:06:11 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/08/31 02:06:11 NOTICE: (39)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:06:11 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:06:11 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:06:11 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:12 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:06:12 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/31 02:06:14 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:06:14 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:06:14 NOTICE: (40)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file2.txt
2025/08/31 02:06:14 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file2.txt"
2025/08/31 02:06:14 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:06:14 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:06:15 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file2.txt"
2025/08/31 02:06:15 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/31 02:06:16 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:06:16 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/08/31 02:06:16 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:06:16 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:06:16 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/08/31 02:06:16 NOTICE: (42)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:06:16 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:06:16 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:06:16 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:06:17 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:06:18 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/08/31 02:06:18 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:06:19 INFO  : file1L.txt: Updated modification time in destination
2025/08/31 02:06:19 DEBUG : file1L.txt: Unchanged skipping
2025/08/31 02:06:19 NOTICE: (43)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file2.txt
2025/08/31 02:06:19 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file2.txt"
2025/08/31 02:06:19 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:06:19 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:20 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file2.txt"
2025/08/31 02:06:20 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/31 02:06:22 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:06:22 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/31 02:06:22 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/08/31 02:06:22 NOTICE: (45)  : bisync resync resync-mode=path1
2025/08/31 02:06:30 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:30 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:30 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:06:30 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:06:30 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:30 INFO  : Copying Path2 files to Path1
2025/08/31 02:06:30 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:06:30 INFO  : There was nothing to transfer
2025/08/31 02:06:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:06:31 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:06:31 INFO  : file2.txt: Copied (server-side copy)
2025/08/31 02:06:31 INFO  : Resync updating listings
2025/08/31 02:06:31 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:31 INFO  : Bisync successful
2025/08/31 02:06:31 NOTICE: (46)  : test no winner
2025/08/31 02:06:31 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1R.txt
2025/08/31 02:06:31 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:06:31 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/08/31 02:06:31 NOTICE: (48)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file1.txt
2025/08/31 02:06:31 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file1.txt"
2025/08/31 02:06:31 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:06:31 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:32 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file1.txt"
2025/08/31 02:06:32 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/31 02:06:34 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:06:34 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/31 02:06:34 NOTICE: (49)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file2.txt
2025/08/31 02:06:34 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file2.txt"
2025/08/31 02:06:34 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1R.txt"
2025/08/31 02:06:34 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:06:35 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file2.txt"
2025/08/31 02:06:35 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/08/31 02:06:35 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/31 02:06:36 INFO  : file1R.txt: Updated modification time in destination
2025/08/31 02:06:36 DEBUG : file1R.txt: Unchanged skipping
2025/08/31 02:06:36 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/020415ko/datadir/test_resync_modes-covotum1/ file1L.txt
2025/08/31 02:06:36 DEBUG : fs cache: switching user supplied name "/tmp/020415ko/datadir/test_resync_modes-covotum1/" for canonical name "/tmp/020415ko/datadir/test_resync_modes-covotum1"
2025/08/31 02:06:36 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/08/31 02:06:36 NOTICE: (51)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/ file1.txt
2025/08/31 02:06:36 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" as "file1.txt"
2025/08/31 02:06:36 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:06:36 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/"
2025/08/31 02:06:37 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1" as "file1.txt"
2025/08/31 02:06:38 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/08/31 02:06:38 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:06:39 INFO  : file1L.txt: Updated modification time in destination
2025/08/31 02:06:39 DEBUG : file1L.txt: Unchanged skipping
2025/08/31 02:06:39 NOTICE: (52)  : copy-as /tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/ file2.txt
2025/08/31 02:06:39 DEBUG : copyFile "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt" to "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/" as "file2.txt"
2025/08/31 02:06:39 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_resync_modes-covotum1/file1L.txt"
2025/08/31 02:06:39 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:41 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2" as "file2.txt"
2025/08/31 02:06:41 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/31 02:06:42 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/31 02:06:42 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/31 02:06:42 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/08/31 02:06:42 NOTICE: (54)  : bisync resync resync-mode=newer
2025/08/31 02:06:51 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:51 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/31 02:06:51 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/31 02:06:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/31 02:06:51 INFO  : Synching Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" with Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:51 INFO  : Copying Path2 files to Path1
2025/08/31 02:06:51 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/31 02:06:51 INFO  : file1.txt: Copied (server-side copy)
2025/08/31 02:06:52 INFO  : file2.txt: Copied (server-side copy)
2025/08/31 02:06:52 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/31 02:06:52 INFO  : Resync updating listings
2025/08/31 02:06:52 INFO  : Validating listings for Path1 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path1/" vs Path2 "TestB2:rclone-test-joqiraj5raho/020415ko/resync_modes/path2/"
2025/08/31 02:06:52 INFO  : Bisync successful
2025/08/31 02:06:52 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path1: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:06:53 DEBUG : RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106d375eba680b37_d20250831_m020422_c001_v0001157_t0043_u01756605862564")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f108c6d04977652c6_d20250831_m020651_c001_v0001183_t0039_u01756606011765")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1150e8311c550f9b_d20250831_m020639_c001_v0001116_t0035_u01756605999206")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f115c80e853aa5b8a_d20250831_m020619_c001_v0001183_t0035_u01756605979110")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1149ab2927c943b7_d20250831_m020601_c001_v0001172_t0044_u01756605961205")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1179e3a869e65013_d20250831_m020555_c001_v0001178_t0045_u01756605955283")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f113273b98134dcd8_d20250831_m020546_c001_v0001179_t0000_u01756605946246")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10071c7b57a59107_d20250831_m020540_c001_v0001130_t0048_u01756605940670")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1056ba48c617d986_d20250831_m020532_c001_v0001171_t0013_u01756605932120")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1068125a55b4283a_d20250831_m020526_c001_v0001182_t0049_u01756605926667")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1106cd72e4426e5f_d20250831_m020516_c001_v0001040_t0000_u01756605916073")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f11847231e26a3503_d20250831_m020430_c001_v0001150_t0043_u01756605870250")
2025/08/31 02:06:53 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106960293397ffde_d20250831_m020422_c001_v0001100_t0019_u01756605862185")
2025/08/31 02:06:53 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f114ca443c2b4ec15_d20250831_m020652_c001_v0001115_t0037_u01756606012005")
2025/08/31 02:06:53 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f115790cbf8e447f9_d20250831_m020636_c001_v0001092_t0029_u01756605996591")
2025/08/31 02:06:53 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1067779f04cb789b_d20250831_m020616_c001_v0001180_t0047_u01756605976666")
2025/08/31 02:06:53 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f109299ff5a2fc325_d20250831_m020422_c001_v0001117_t0049_u01756605862381")
2025/08/31 02:06:53 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f4136650219d8115a_d20250831_m020647_c001_v7007000_t0000_u01756606007053")
2025/08/31 02:06:53 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10470525b42caa8a_d20250831_m020645_c001_v0001101_t0047_u01756606005731")
2025/08/31 02:06:53 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10696029339801fd_d20250831_m020643_c001_v0001100_t0043_u01756606003227")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f4155448b322753ed_d20250831_m020626_c001_v7007000_t0000_u01756605986226")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1145d45d3dd4981d_d20250831_m020625_c001_v0001184_t0055_u01756605985027")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106d375eba680d45_d20250831_m020622_c001_v0001157_t0017_u01756605982457")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f4055ecea950447a4_d20250831_m020606_c001_v7007000_t0000_u01756605966128")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10706593dbd7e241_d20250831_m020604_c001_v0001120_t0049_u01756605964673")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f109299ff5a2fc43f_d20250831_m020601_c001_v0001117_t0009_u01756605961950")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f403da38b8dbd6d7f_d20250831_m020550_c001_v7007000_t0000_u01756605950588")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1162bcded2cd6c25_d20250831_m020549_c001_v0001032_t0043_u01756605949201")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10696029339800cd_d20250831_m020546_c001_v0001100_t0051_u01756605946833")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f408ee6942fbe891b_d20250831_m020536_c001_v7007000_t0000_u01756605936321")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f11853d87c021f328_d20250831_m020535_c001_v0001179_t0059_u01756605935065")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106d375eba680c33_d20250831_m020532_c001_v0001157_t0035_u01756605932762")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f41260f41d99b24c5_d20250831_m020520_c001_v7007000_t0000_u01756605920922")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f10312587fd2ea2ef_d20250831_m020519_c001_v0001100_t0027_u01756605919517")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f109299ff5a2fc37d_d20250831_m020516_c001_v0001117_t0009_u01756605916873")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f41970b34c6f3d1da_d20250831_m020434_c001_v7007000_t0000_u01756605874366")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f11509459407d036d_d20250831_m020433_c001_v0001180_t0000_u01756605873133")
2025/08/31 02:06:54 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106960293397fff0_d20250831_m020430_c001_v0001100_t0043_u01756605870843")
2025/08/31 02:06:55 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/resync_modes/path2: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:06:55 DEBUG : RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f104887b9f9f45769_d20250831_m020424_c001_v0001178_t0046_u01756605864282")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f11131a84e098e16f_d20250831_m020634_c001_v0001182_t0000_u01756605994111")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f109031b5aaafc00c_d20250831_m020630_c001_v0001042_t0038_u01756605990784")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1043c3f321cbf104_d20250831_m020613_c001_v0001093_t0035_u01756605973758")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1145969914690c2e_d20250831_m020610_c001_v0001182_t0059_u01756605970940")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106c890891a169e9_d20250831_m020558_c001_v0001136_t0008_u01756605958069")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f118ad02e769de360_d20250831_m020543_c001_v0001182_t0052_u01756605943410")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1002e566c7d01f69_d20250831_m020529_c001_v0001180_t0006_u01756605929301")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1170eb2f120dfac7_d20250831_m020513_c001_v0001130_t0014_u01756605913420")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106f7e0b0455a3e9_d20250831_m020510_c001_v0001098_t0011_u01756605910422")
2025/08/31 02:06:55 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1171a2cb23fad830_d20250831_m020427_c001_v0001171_t0021_u01756605867283")
2025/08/31 02:06:56 DEBUG : file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1100663819289e25_d20250831_m020423_c001_v0001161_t0023_u01756605863901")
2025/08/31 02:06:56 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f107ef3a50e6aa630_d20250831_m020642_c001_v0001182_t0012_u01756606002606")
2025/08/31 02:06:56 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f102b265025f9d0b6_d20250831_m020631_c001_v0001093_t0038_u01756605991144")
2025/08/31 02:06:56 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f103f3c03c4dbdd30_d20250831_m020621_c001_v0001177_t0040_u01756605981875")
2025/08/31 02:06:56 DEBUG : file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106dca2053d18d12_d20250831_m020424_c001_v0001145_t0014_u01756605864099")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f4023ff6b64eb7967_d20250831_m020651_c001_v7007000_t0000_u01756606011372")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f103b343bf859020f_d20250831_m020650_c001_v0001153_t0014_u01756606010028")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f110066381928a10f_d20250831_m020647_c001_v0001161_t0009_u01756606007603")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f4055ecea95044836_d20250831_m020630_c001_v7007000_t0000_u01756605990191")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1178117c987f57ca_d20250831_m020628_c001_v0001184_t0037_u01756605988977")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f104887b9f9f459e9_d20250831_m020626_c001_v0001178_t0040_u01756605986789")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f41889eba56759be7_d20250831_m020610_c001_v7007000_t0000_u01756605970366")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1040f74b39fadcc7_d20250831_m020608_c001_v0001042_t0031_u01756605968968")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106dca2053d18e61_d20250831_m020606_c001_v0001145_t0010_u01756605966673")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f418e1fb304bcebdd_d20250831_m020554_c001_v7007000_t0000_u01756605954804")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106bd71d5cdf6ece_d20250831_m020553_c001_v0001130_t0053_u01756605953511")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1100663819289fdd_d20250831_m020551_c001_v0001161_t0022_u01756605951137")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f400d106f340c6b1b_d20250831_m020540_c001_v7007000_t0000_u01756605940268")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f11131a84e098de2f_d20250831_m020539_c001_v0001182_t0040_u01756605939011")
2025/08/31 02:06:56 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f104887b9f9f4589a_d20250831_m020536_c001_v0001178_t0031_u01756605936865")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f420768a9af4da11e_d20250831_m020525_c001_v7007000_t0000_u01756605925827")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f112335726f5ffb28_d20250831_m020524_c001_v0001116_t0055_u01756605924011")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106dca2053d18d88_d20250831_m020521_c001_v0001145_t0018_u01756605921474")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f414275531220b4ce_d20250831_m020509_c001_v7007000_t0000_u01756605909474")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1043c3f321cbefdd_d20250831_m020506_c001_v0001093_t0034_u01756605877221")
2025/08/31 02:06:57 DEBUG : modtime_write_test: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1100663819289e41_d20250831_m020434_c001_v0001161_t0017_u01756605874915")
    bisync_test.go:597: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/08/31 02:06:57 INFO  : path1: Making directory
2025/08/31 02:06:57 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/rmdirs/path1"
2025/08/31 02:06:58 INFO  : path2: Making directory
2025/08/31 02:06:58 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/rmdirs/path2"
2025/08/31 02:06:59 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/08/31 02:06:59 DEBUG : Creating backend with remote "/tmp/020415ko/initdir/test_rmdirs-hicahib7"
2025/08/31 02:06:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : subdir: Making directory with metadata
2025/08/31 02:06:59 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/31 02:06:59 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/31 02:06:59 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/31 02:06:59 INFO  : file1.copy1.txt: Copied (new)
2025/08/31 02:06:59 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/31 02:06:59 INFO  : file1.copy3.txt: Copied (new)
2025/08/31 02:06:59 DEBUG : Local file system at /tmp/020415ko/initdir/test_rmdirs-hicahib7: Waiting for checks to finish
2025/08/31 02:06:59 DEBUG : Local file system at /tmp/020415ko/initdir/test_rmdirs-hicahib7: Waiting for transfers to finish
2025/08/31 02:06:59 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/31 02:06:59 INFO  : file1.copy4.txt: Copied (new)
2025/08/31 02:06:59 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/31 02:06:59 INFO  : file1.copy5.txt: Copied (new)
2025/08/31 02:06:59 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:06:59 INFO  : file1.txt: Copied (new)
2025/08/31 02:06:59 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:06:59 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:06:59 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/31 02:06:59 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:06:59 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:06:59 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:06:59 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/31 02:06:59 INFO  : file1.copy2.txt: Copied (new)
2025/08/31 02:06:59 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/31 02:06:59 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/08/31 02:06:59 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_rmdirs-vumarig9"
2025/08/31 02:06:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:06:59 DEBUG : Local file system at /tmp/020415ko/datadir/test_rmdirs-vumarig9: Waiting for checks to finish
2025/08/31 02:06:59 DEBUG : Local file system at /tmp/020415ko/datadir/test_rmdirs-vumarig9: Waiting for transfers to finish
2025/08/31 02:06:59 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:06:59 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/08/31 02:06:59 INFO  : file1.txt: Copied (new)
2025/08/31 02:06:59 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path1: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:07:00 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path2: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:07:01 NOTICE: checking initFs Local file system at /tmp/020415ko/initdir/test_rmdirs-hicahib7
2025/08/31 02:07:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 INFO  : subdir: Making directory
2025/08/31 02:07:01 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:01 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path1: Waiting for checks to finish
2025/08/31 02:07:01 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path1: Waiting for transfers to finish
2025/08/31 02:07:02 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:02 INFO  : file1.copy1.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : file1.copy2.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : file1.copy3.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : file1.copy4.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : file1.copy5.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:03 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:07:03 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:07:03 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:07:03 NOTICE: checking Path1 B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path1
2025/08/31 02:07:04 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 INFO  : subdir: Making directory
2025/08/31 02:07:04 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:04 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path2: Waiting for checks to finish
2025/08/31 02:07:04 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path2: Waiting for transfers to finish
2025/08/31 02:07:05 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.copy2.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.copy1.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.copy3.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:07:05 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:07:05 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.copy4.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : subdir/file20.txt: Copied (new)
2025/08/31 02:07:05 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:05 INFO  : file1.copy5.txt: Copied (new)
2025/08/31 02:07:05 NOTICE: checking path2 B2 bucket rclone-test-joqiraj5raho path 020415ko/rmdirs/path2
2025/08/31 02:07:05 NOTICE: (01)  : test rmdirs
2025/08/31 02:07:05 NOTICE: (02)  : test initial bisync
2025/08/31 02:07:05 NOTICE: (03)  : bisync resync
    bisync_test.go:996: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/08/31 02:07:05 INFO  : path1: Making directory
2025/08/31 02:07:05 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/volatile/path1"
2025/08/31 02:07:06 INFO  : path2: Making directory
2025/08/31 02:07:06 DEBUG : Creating backend with remote "TestB2:rclone-test-joqiraj5raho/020415ko/volatile/path2"
2025/08/31 02:07:08 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/08/31 02:07:08 DEBUG : Creating backend with remote "/tmp/020415ko/initdir/test_volatile-kijenaz6"
2025/08/31 02:07:08 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/31 02:07:08 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : Local file system at /tmp/020415ko/initdir/test_volatile-kijenaz6: Waiting for checks to finish
2025/08/31 02:07:08 DEBUG : Local file system at /tmp/020415ko/initdir/test_volatile-kijenaz6: Waiting for transfers to finish
2025/08/31 02:07:08 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/31 02:07:08 INFO  : file2.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/31 02:07:08 INFO  : file4.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/31 02:07:08 INFO  : file5.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/31 02:07:08 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/31 02:07:08 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:07:08 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/31 02:07:08 INFO  : file7.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/31 02:07:08 INFO  : file6.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/31 02:07:08 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 INFO  : file3.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/08/31 02:07:08 INFO  : file8.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/08/31 02:07:08 DEBUG : Creating backend with remote "/tmp/020415ko/datadir/test_volatile-qipevuq1"
2025/08/31 02:07:08 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/31 02:07:08 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/08/31 02:07:08 INFO  : dummy.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:08 DEBUG : Local file system at /tmp/020415ko/datadir/test_volatile-qipevuq1: Waiting for checks to finish
2025/08/31 02:07:08 DEBUG : Local file system at /tmp/020415ko/datadir/test_volatile-qipevuq1: Waiting for transfers to finish
2025/08/31 02:07:08 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/08/31 02:07:08 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:07:08 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/08/31 02:07:08 INFO  : file2.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/08/31 02:07:08 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:07:08 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/08/31 02:07:08 INFO  : file10.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/08/31 02:07:08 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/08/31 02:07:08 INFO  : file5L.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/08/31 02:07:08 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/08/31 02:07:08 INFO  : file5R.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:07:08 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/08/31 02:07:08 INFO  : file11.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:07:08 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/08/31 02:07:08 INFO  : file6.txt: Copied (new)
2025/08/31 02:07:08 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/31 02:07:08 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/08/31 02:07:08 INFO  : file7.txt: Copied (new)
2025/08/31 02:07:08 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path1: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:07:08 INFO  : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path2: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:07:09 NOTICE: checking initFs Local file system at /tmp/020415ko/initdir/test_volatile-kijenaz6
2025/08/31 02:07:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:10 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path1: Waiting for checks to finish
2025/08/31 02:07:10 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path1: Waiting for transfers to finish
2025/08/31 02:07:11 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file3.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file2.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file4.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:07:11 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:07:11 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file5.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file7.txt: Copied (new)
2025/08/31 02:07:11 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:11 INFO  : file8.txt: Copied (new)
2025/08/31 02:07:12 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:12 INFO  : file6.txt: Copied (new)
2025/08/31 02:07:12 NOTICE: checking Path1 B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path1
2025/08/31 02:07:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/31 02:07:12 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path2: Waiting for checks to finish
2025/08/31 02:07:12 DEBUG : B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path2: Waiting for transfers to finish
2025/08/31 02:07:13 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file2.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file1.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file3.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file6.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file4.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/31 02:07:13 INFO  : RCLONE_TEST: Copied (new)
2025/08/31 02:07:13 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file7.txt: Copied (new)
2025/08/31 02:07:13 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:13 INFO  : file5.txt: Copied (new)
2025/08/31 02:07:14 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/31 02:07:14 INFO  : file8.txt: Copied (new)
2025/08/31 02:07:14 NOTICE: checking path2 B2 bucket rclone-test-joqiraj5raho path 020415ko/volatile/path2
2025/08/31 02:07:14 NOTICE: (01)  : test volatile
2025/08/31 02:07:14 NOTICE: (02)  : test initial bisync
2025/08/31 02:07:14 NOTICE: (03)  : bisync resync
    bisync_test.go:967: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/08/31 02:07:14 DEBUG : B2 bucket rclone-test-joqiraj5raho: Purge remote
2025/08/31 02:07:14 INFO  : B2 bucket rclone-test-joqiraj5raho: cleaning bucket "rclone-test-joqiraj5raho" of all files
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f106f8ee555b79fd7_d20250831_m020703_c001_v0001106_t0043_u01756606023189")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f101078b4b2134000_d20250831_m020702_c001_v0001101_t0003_u01756606022632")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1074c12c24d367fb_d20250831_m020702_c001_v0001161_t0049_u01756606022822")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f101b477bc35e12c9_d20250831_m020703_c001_v0001157_t0053_u01756606023004")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f101078b4b2134004_d20250831_m020703_c001_v0001101_t0016_u01756606023000")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1074c12c24d36803_d20250831_m020703_c001_v0001161_t0018_u01756606023136")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f101b477bc35e12cd_d20250831_m020703_c001_v0001157_t0053_u01756606023301")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f101078b4b2134008_d20250831_m020703_c001_v0001101_t0052_u01756606023455")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f100af858f63668c4_d20250831_m020705_c001_v0001134_t0042_u01756606025221")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f108ed68a17c14a45_d20250831_m020704_c001_v0001113_t0028_u01756606024889")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1075b4584254ceba_d20250831_m020704_c001_v0001144_t0012_u01756606024645")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1052d8ba9ec5d78b_d20250831_m020705_c001_v0001177_t0006_u01756606025032")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1075b4584254cec0_d20250831_m020705_c001_v0001144_t0042_u01756606025170")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f108ed68a17c14a49_d20250831_m020705_c001_v0001113_t0007_u01756606025222")
2025/08/31 02:07:15 DEBUG : 020415ko/rmdirs/path2/file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1052d8ba9ec5d78d_d20250831_m020705_c001_v0001177_t0017_u01756606025247")
2025/08/31 02:07:16 DEBUG : 020415ko/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1052d8ba9ec5d791_d20250831_m020705_c001_v0001177_t0051_u01756606025456")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f110ae84c6622a1d7_d20250831_m020711_c001_v0001171_t0055_u01756606031330")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1019552b4bbde0bf_d20250831_m020710_c001_v0001091_t0041_u01756606030759")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1192761be213bbb7_d20250831_m020710_c001_v0001109_t0035_u01756606030957")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file3.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f107a8b9b15906ba8_d20250831_m020711_c001_v0001172_t0042_u01756606031153")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file4.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1019552b4bbde0c5_d20250831_m020711_c001_v0001091_t0043_u01756606031221")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file5.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f107a8b9b15906bac_d20250831_m020711_c001_v0001172_t0045_u01756606031445")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file6.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1192761be213bbb9_d20250831_m020711_c001_v0001109_t0009_u01756606031455")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file7.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1019552b4bbde0c9_d20250831_m020711_c001_v0001091_t0048_u01756606031572")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path1/file8.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f110ae84c6622a1d9_d20250831_m020711_c001_v0001171_t0019_u01756606031616")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/RCLONE_TEST: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f118a92cd8c7c98b3_d20250831_m020713_c001_v0001161_t0040_u01756606033667")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file1.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1129ce6ee3c88531_d20250831_m020713_c001_v0001044_t0000_u01756606033093")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file2.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f111f5954e1b3047f_d20250831_m020713_c001_v0001105_t0040_u01756606033280")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file3.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f115c80e853aa5dc1_d20250831_m020713_c001_v0001183_t0036_u01756606033473")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file4.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f111f5954e1b30481_d20250831_m020713_c001_v0001105_t0035_u01756606033543")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file5.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f1129ce6ee3c88533_d20250831_m020713_c001_v0001044_t0001_u01756606033558")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file6.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f115c80e853aa5dc3_d20250831_m020713_c001_v0001183_t0011_u01756606033670")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file7.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f115c80e853aa5dc7_d20250831_m020713_c001_v0001183_t0013_u01756606033885")
2025/08/31 02:07:16 DEBUG : 020415ko/volatile/path2/file8.txt: Deleting (id "4_z4f923dc3d0edfbdb9f880e1b_f111f5954e1b30483_d20250831_m020713_c001_v0001105_t0018_u01756606033894")
--- PASS: TestBisyncRemoteRemote (185.22s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (162.49s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.19s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.59s)
FAIL
2025/08/31 02:07:19 DEBUG : B2 bucket rclone-test-pulufeg9vihu: Purge remote
2025/08/31 02:07:19 INFO  : B2 bucket rclone-test-pulufeg9vihu: cleaning bucket "rclone-test-pulufeg9vihu" of all files
2025/08/31 02:07:20 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|resync)$|^TestBisyncRemoteRemote$/^(resync_modes|rmdirs|volatile)$'" - Finished ERROR in 4m33.023571453s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/check_access_filters]