"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_filename$|^TestBisyncRemoteRemote$/^(basic|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/11/17 05:05:02 DEBUG : Creating backend with remote "TestB2:rclone-test-vucivaz6dofi"
2025/11/17 05:05:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/17 05:05:03 DEBUG : Creating backend with remote "/tmp/rclone1887800161"
=== RUN   TestBisyncLocalRemote
2025/11/17 05:05:03 DEBUG : Creating backend with remote "TestB2:rclone-test-padonol3weha"
2025/11/17 05:05:03 NOTICE: remote: TestB2:rclone-test-padonol3weha
=== RUN   TestBisyncLocalRemote/check_filename
2025/11/17 05:05:03 DEBUG : Creating backend with remote "/tmp/050503pi"
2025/11/17 05:05:03 DEBUG : Creating backend with remote "/tmp/050503pi/check_filename/path1"
2025/11/17 05:05:03 DEBUG : Creating backend with remote "TestB2:rclone-test-padonol3weha/050503pi"
2025/11/17 05:05:04 INFO  : path2: Making directory
2025/11/17 05:05:05 DEBUG : Creating backend with remote "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2"
2025/11/17 05:05:06 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/initial"
2025/11/17 05:05:06 DEBUG : Creating backend with remote "/tmp/050503pi/initdir/test_check_filename-yeculuv5"
2025/11/17 05:05:06 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : subdir: Making directory with metadata
2025/11/17 05:05:06 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/17 05:05:06 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/17 05:05:06 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:05:06 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:06 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:05:06 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/17 05:05:06 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:06 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/17 05:05:06 INFO  : file3.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/17 05:05:06 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:06 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/17 05:05:06 INFO  : file4.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/17 05:05:06 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:06 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/17 05:05:06 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : subdir/.chk_file.6dc50fe3.partial: size = 109 OK
2025/11/17 05:05:06 DEBUG : Local file system at /tmp/050503pi/initdir/test_check_filename-yeculuv5: Waiting for checks to finish
2025/11/17 05:05:06 DEBUG : Local file system at /tmp/050503pi/initdir/test_check_filename-yeculuv5: Waiting for transfers to finish
2025/11/17 05:05:06 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:05:06 DEBUG : subdir/.chk_file.6dc50fe3.partial: renamed to: subdir/.chk_file
2025/11/17 05:05:06 INFO  : subdir/.chk_file: Copied (new)
2025/11/17 05:05:06 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/17 05:05:06 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:06 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/17 05:05:06 INFO  : file2.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : .chk_file.b7f3f5bf.partial: size = 109 OK
2025/11/17 05:05:06 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:05:06 DEBUG : .chk_file.b7f3f5bf.partial: renamed to: .chk_file
2025/11/17 05:05:06 INFO  : .chk_file: Copied (new)
2025/11/17 05:05:06 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:06 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_filename/modfiles"
2025/11/17 05:05:06 DEBUG : Creating backend with remote "/tmp/050503pi/datadir/test_check_filename-kemuyod6"
2025/11/17 05:05:06 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:06 DEBUG : Local file system at /tmp/050503pi/datadir/test_check_filename-kemuyod6: Waiting for checks to finish
2025/11/17 05:05:06 DEBUG : Local file system at /tmp/050503pi/datadir/test_check_filename-kemuyod6: Waiting for transfers to finish
2025/11/17 05:05:06 DEBUG : hold.txt.83686dab.partial: size = 59 OK
2025/11/17 05:05:06 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/11/17 05:05:06 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2025/11/17 05:05:06 INFO  : hold.txt: Copied (new)
2025/11/17 05:05:06 DEBUG : Waiting for deletions to finish
2025/11/17 05:05:06 ERROR : error listing: directory not found
2025/11/17 05:05:06 ERROR : Local file system at /tmp/050503pi/check_filename/path1: Failed to list "": directory not found
2025/11/17 05:05:06 DEBUG : removing 1 level 0 directories
2025/11/17 05:05:06 INFO  : Local file system at /tmp/050503pi/check_filename/path1: Removing directory
2025/11/17 05:05:06 ERROR : Failed to rmdir: stat /tmp/050503pi/check_filename/path1: no such file or directory
2025/11/17 05:05:06 INFO  : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: cleaning bucket "rclone-test-padonol3weha" of all files
2025/11/17 05:05:07 ERROR : error listing: directory not found
2025/11/17 05:05:07 NOTICE: checking initFs Local file system at /tmp/050503pi/initdir/test_check_filename-yeculuv5
2025/11/17 05:05:07 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : subdir: Making directory with metadata
2025/11/17 05:05:07 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:05:07 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:07 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/17 05:05:07 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/17 05:05:07 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : .chk_file.b7f3f5bf.partial: size = 109 OK
2025/11/17 05:05:07 DEBUG : .chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:05:07 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/17 05:05:07 DEBUG : .chk_file.b7f3f5bf.partial: renamed to: .chk_file
2025/11/17 05:05:07 INFO  : .chk_file: Copied (new)
2025/11/17 05:05:07 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:05:07 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:07 DEBUG : Local file system at /tmp/050503pi/check_filename/path1: Waiting for checks to finish
2025/11/17 05:05:07 DEBUG : Local file system at /tmp/050503pi/check_filename/path1: Waiting for transfers to finish
2025/11/17 05:05:07 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:07 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/17 05:05:07 INFO  : file2.txt: Copied (new)
2025/11/17 05:05:07 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/17 05:05:07 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/17 05:05:07 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:07 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/17 05:05:07 INFO  : file4.txt: Copied (new)
2025/11/17 05:05:07 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:07 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/17 05:05:07 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:07 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/17 05:05:07 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:07 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/17 05:05:07 INFO  : file3.txt: Copied (new)
2025/11/17 05:05:07 DEBUG : subdir/.chk_file.6dc50fe3.partial: size = 109 OK
2025/11/17 05:05:07 DEBUG : subdir/.chk_file: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:05:07 DEBUG : subdir/.chk_file.6dc50fe3.partial: renamed to: subdir/.chk_file
2025/11/17 05:05:07 INFO  : subdir/.chk_file: Copied (new)
2025/11/17 05:05:07 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:07 NOTICE: checking Path1 Local file system at /tmp/050503pi/check_filename/path1
2025/11/17 05:05:07 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 INFO  : subdir: Making directory
2025/11/17 05:05:07 DEBUG : subdir/.chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:07 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Waiting for checks to finish
2025/11/17 05:05:07 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Waiting for transfers to finish
2025/11/17 05:05:08 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:05:08 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:08 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:08 DEBUG : file3.txt: size = 0 OK
2025/11/17 05:05:08 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:08 INFO  : file3.txt: Copied (new)
2025/11/17 05:05:09 DEBUG : subdir/.chk_file: size = 109 OK
2025/11/17 05:05:09 DEBUG : subdir/.chk_file: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:05:09 INFO  : subdir/.chk_file: Copied (new)
2025/11/17 05:05:09 DEBUG : file4.txt: size = 0 OK
2025/11/17 05:05:09 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:09 INFO  : file4.txt: Copied (new)
2025/11/17 05:05:09 DEBUG : .chk_file: size = 109 OK
2025/11/17 05:05:09 DEBUG : .chk_file: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:05:09 INFO  : .chk_file: Copied (new)
2025/11/17 05:05:09 DEBUG : file2.txt: size = 0 OK
2025/11/17 05:05:09 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:09 INFO  : file2.txt: Copied (new)
2025/11/17 05:05:09 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/17 05:05:09 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:09 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:09 NOTICE: checking path2 B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2
2025/11/17 05:05:09 NOTICE: (01)  : test check-filename
2025/11/17 05:05:09 NOTICE: (02)  : test initial bisync
2025/11/17 05:05:09 NOTICE: (03)  : bisync resync
2025/11/17 05:05:12 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:12 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:12 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:12 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/17 05:05:12 INFO  : Synching Path1 "/tmp/050503pi/check_filename/path1/" with Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:12 INFO  : Copying Path2 files to Path1
2025/11/17 05:05:12 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:05:13 INFO  : There was nothing to transfer
2025/11/17 05:05:13 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:05:13 INFO  : There was nothing to transfer
2025/11/17 05:05:13 INFO  : Resync updating listings
2025/11/17 05:05:13 INFO  : Validating listings for Path1 "/tmp/050503pi/check_filename/path1/" vs Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:13 INFO  : Bisync successful
2025/11/17 05:05:13 NOTICE: (04)  : test 1. see that check-access passes with the initial setup
2025/11/17 05:05:13 NOTICE: (05)  : bisync check-access check-filename=.chk_file
2025/11/17 05:05:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:16 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:16 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/17 05:05:16 INFO  : Synching Path1 "/tmp/050503pi/check_filename/path1/" with Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:16 INFO  : Building Path1 and Path2 listings
2025/11/17 05:05:17 INFO  : Path1 checking for diffs
2025/11/17 05:05:17 INFO  : Path2 checking for diffs
2025/11/17 05:05:17 INFO  : Checking access health
2025/11/17 05:05:17 INFO  : Found 2 matching ".chk_file" files on both paths
2025/11/17 05:05:17 INFO  : No changes found
2025/11/17 05:05:17 INFO  : Updating listings
2025/11/17 05:05:17 INFO  : Validating listings for Path1 "/tmp/050503pi/check_filename/path1/" vs Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:17 INFO  : Bisync successful
2025/11/17 05:05:17 NOTICE: (06)  : copy-listings initial-pass
2025/11/17 05:05:17 NOTICE: (07)  : test 2. delete the remote subdir .chk_file, run sync. should fail critical.
2025/11/17 05:05:17 NOTICE: (08)  : delete-file TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/.chk_file
2025/11/17 05:05:17 DEBUG : Creating backend with remote "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/"
2025/11/17 05:05:18 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/" to be canonical "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir"
2025/11/17 05:05:19 INFO  : .chk_file: Deleted
2025/11/17 05:05:19 NOTICE: (09)  : bisync check-access check-filename=.chk_file
2025/11/17 05:05:22 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:22 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/17 05:05:22 INFO  : Synching Path1 "/tmp/050503pi/check_filename/path1/" with Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:22 INFO  : Building Path1 and Path2 listings
2025/11/17 05:05:23 INFO  : Path1 checking for diffs
2025/11/17 05:05:23 INFO  : Path2 checking for diffs
2025/11/17 05:05:23 INFO  : - Path2    File was deleted          - subdir/.chk_file
2025/11/17 05:05:23 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/11/17 05:05:23 INFO  : Checking access health
2025/11/17 05:05:23 ERROR : Access test failed: Path1 count 2, Path2 count 1 - .chk_file
2025/11/17 05:05:23 ERROR : -          Access test failed: Path1 file not found in Path2 - subdir/.chk_file
2025/11/17 05:05:23 ERROR : Bisync critical error: check file check failed
2025/11/17 05:05:23 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/17 05:05:23 NOTICE: Bisync error: bisync aborted
2025/11/17 05:05:23 NOTICE: (10)  : move-listings path2-missing
2025/11/17 05:05:23 NOTICE: (11)  : test 3. put the remote subdir .chk_file back, run resync.
2025/11/17 05:05:23 NOTICE: (12)  : copy-file /tmp/050503pi/check_filename/path1/subdir/.chk_file TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/
2025/11/17 05:05:23 DEBUG : copyFile "/tmp/050503pi/check_filename/path1/subdir/.chk_file" to "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/" as ""
2025/11/17 05:05:23 DEBUG : Creating backend with remote "/tmp/050503pi/check_filename/path1/subdir/.chk_file"
2025/11/17 05:05:23 DEBUG : Creating backend with remote "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/subdir/"
2025/11/17 05:05:24 DEBUG : operations.CopyFile ".chk_file" to "B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2/subdir" as ".chk_file"
2025/11/17 05:05:24 DEBUG : .chk_file: Need to transfer - File not found at Destination
2025/11/17 05:05:26 DEBUG : .chk_file: size = 109 OK
2025/11/17 05:05:26 DEBUG : .chk_file: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:05:26 INFO  : .chk_file: Copied (new)
2025/11/17 05:05:26 NOTICE: (13)  : bisync check-access resync check-filename=.chk_file
2025/11/17 05:05:32 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:32 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:32 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:32 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/17 05:05:32 INFO  : Synching Path1 "/tmp/050503pi/check_filename/path1/" with Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:32 INFO  : Copying Path2 files to Path1
2025/11/17 05:05:32 INFO  : Checking access health
2025/11/17 05:05:33 INFO  : Found 2 matching ".chk_file" files on both paths
2025/11/17 05:05:33 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:05:33 INFO  : There was nothing to transfer
2025/11/17 05:05:33 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:05:33 INFO  : There was nothing to transfer
2025/11/17 05:05:33 INFO  : Resync updating listings
2025/11/17 05:05:33 INFO  : Validating listings for Path1 "/tmp/050503pi/check_filename/path1/" vs Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:33 INFO  : Bisync successful
2025/11/17 05:05:33 NOTICE: (14)  : test 4. run sync with check-access. should pass.
2025/11/17 05:05:33 NOTICE: (15)  : bisync check-access check-filename=.chk_file
2025/11/17 05:05:37 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:37 DEBUG : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:37 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:37 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/17 05:05:37 INFO  : Synching Path1 "/tmp/050503pi/check_filename/path1/" with Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:37 INFO  : Building Path1 and Path2 listings
2025/11/17 05:05:38 INFO  : Path1 checking for diffs
2025/11/17 05:05:38 INFO  : Path2 checking for diffs
2025/11/17 05:05:38 INFO  : Checking access health
2025/11/17 05:05:38 INFO  : Found 2 matching ".chk_file" files on both paths
2025/11/17 05:05:38 INFO  : No changes found
2025/11/17 05:05:38 INFO  : Updating listings
2025/11/17 05:05:38 INFO  : Validating listings for Path1 "/tmp/050503pi/check_filename/path1/" vs Path2 "TestB2:rclone-test-padonol3weha/050503pi/check_filename/path2/"
2025/11/17 05:05:38 INFO  : Bisync successful
2025/11/17 05:05:38 DEBUG : Waiting for deletions to finish
2025/11/17 05:05:38 INFO  : file1.txt: Deleted
2025/11/17 05:05:38 INFO  : file4.txt: Deleted
2025/11/17 05:05:38 INFO  : file3.txt: Deleted
2025/11/17 05:05:38 INFO  : .chk_file: Deleted
2025/11/17 05:05:38 INFO  : file2.txt: Deleted
2025/11/17 05:05:38 INFO  : subdir/.chk_file: Deleted
2025/11/17 05:05:38 INFO  : subdir/file20.txt: Deleted
2025/11/17 05:05:38 DEBUG : removing 1 level 1 directories
2025/11/17 05:05:38 INFO  : subdir: Removing directory
2025/11/17 05:05:38 DEBUG : removing 1 level 0 directories
2025/11/17 05:05:38 INFO  : Local file system at /tmp/050503pi/check_filename/path1: Removing directory
2025/11/17 05:05:38 INFO  : B2 bucket rclone-test-padonol3weha path 050503pi/check_filename/path2: cleaning bucket "rclone-test-padonol3weha" of all files
2025/11/17 05:05:38 DEBUG : .chk_file: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f1065f20ed822530a_d20251117_m050508_c001_v0001093_t0051_u01763355908976")
2025/11/17 05:05:38 DEBUG : file1.txt: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f110b2515c6223189_d20251117_m050508_c001_v0001093_t0059_u01763355908605")
2025/11/17 05:05:38 DEBUG : file2.txt: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f10039ce34b6dd865_d20251117_m050509_c001_v0001180_t0001_u01763355909162")
2025/11/17 05:05:38 DEBUG : file3.txt: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f102b742f9e837b58_d20251117_m050508_c001_v0001179_t0025_u01763355908792")
2025/11/17 05:05:38 DEBUG : file4.txt: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f110b2515c622318d_d20251117_m050508_c001_v0001093_t0017_u01763355908938")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f42028f44562fbb90_d20251117_m050537_c001_v7007000_t0000_u01763355937608")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f1027ae0aba9af25d_d20251117_m050536_c001_v0001176_t0049_u01763355936400")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f110b2515c62231cf_d20251117_m050534_c001_v0001093_t0018_u01763355934380")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f42028f44562fbb71_d20251117_m050532_c001_v7007000_t0000_u01763355932672")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f1074a3445fd64bf7_d20251117_m050530_c001_v0001036_t0022_u01763355930899")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f102b742f9e837c37_d20251117_m050526_c001_v0001179_t0017_u01763355926734")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f422ebe3dccfb24a6_d20251117_m050522_c001_v7007000_t0000_u01763355922663")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f115acbd349934e21_d20251117_m050521_c001_v0001179_t0023_u01763355921372")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f10039ce34b6dd887_d20251117_m050519_c001_v0001180_t0040_u01763355919515")
2025/11/17 05:05:38 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f422ebe3dccfb244f_d20251117_m050516_c001_v7007000_t0000_u01763355916574")
2025/11/17 05:05:39 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f1001243c0b05d5a2_d20251117_m050515_c001_v0001044_t0048_u01763355915233")
2025/11/17 05:05:39 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f1065f20ed822531c_d20251117_m050513_c001_v0001093_t0016_u01763355913656")
2025/11/17 05:05:39 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f422ebe3dccfb2425_d20251117_m050512_c001_v7007000_t0000_u01763355912739")
2025/11/17 05:05:39 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f104fa74f4b8cf8fb_d20251117_m050511_c001_v0001146_t0052_u01763355911382")
2025/11/17 05:05:39 DEBUG : modtime_write_test: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f110b2515c622318f_d20251117_m050509_c001_v0001093_t0045_u01763355909612")
2025/11/17 05:05:39 DEBUG : subdir/.chk_file: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f113492f816d9e06f_d20251117_m050525_c001_v0001037_t0031_u01763355925952")
2025/11/17 05:05:39 DEBUG : subdir/.chk_file: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f4196b5bd76edcc86_d20251117_m050518_c001_v7007000_t0000_u01763355918972")
2025/11/17 05:05:39 DEBUG : subdir/.chk_file: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f102b742f9e837b5e_d20251117_m050509_c001_v0001179_t0009_u01763355909005")
2025/11/17 05:05:39 DEBUG : subdir/file20.txt: Deleting (id "4_z8f12cdd3303d9b0b9fa80e1b_f102b742f9e837b62_d20251117_m050509_c001_v0001179_t0003_u01763355909213")
    bisync_test.go:608: TEST check_filename PASSED
2025/11/17 05:05:39 DEBUG : B2 bucket rclone-test-padonol3weha: Purge remote
2025/11/17 05:05:39 INFO  : B2 bucket rclone-test-padonol3weha: cleaning bucket "rclone-test-padonol3weha" of all files
--- PASS: TestBisyncLocalRemote (37.81s)
    --- PASS: TestBisyncLocalRemote/check_filename (36.08s)
=== RUN   TestBisyncRemoteRemote
2025/11/17 05:05:41 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu"
2025/11/17 05:05:41 NOTICE: remote: TestB2:rclone-test-picoxus5buxu
=== RUN   TestBisyncRemoteRemote/basic
2025/11/17 05:05:41 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku"
2025/11/17 05:05:42 INFO  : path1: Making directory
2025/11/17 05:05:43 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1"
2025/11/17 05:05:44 INFO  : path2: Making directory
2025/11/17 05:05:44 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2"
2025/11/17 05:05:45 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/initial"
2025/11/17 05:05:45 DEBUG : Creating backend with remote "/tmp/050541ku/initdir/test_basic-wofuron9"
2025/11/17 05:05:45 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : subdir: Making directory with metadata
2025/11/17 05:05:45 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/17 05:05:45 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/17 05:05:45 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/17 05:05:45 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:05:45 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/17 05:05:45 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/17 05:05:45 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:05:45 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:05:45 DEBUG : Local file system at /tmp/050541ku/initdir/test_basic-wofuron9: Waiting for checks to finish
2025/11/17 05:05:45 DEBUG : Local file system at /tmp/050541ku/initdir/test_basic-wofuron9: Waiting for transfers to finish
2025/11/17 05:05:45 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/17 05:05:45 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:05:45 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/17 05:05:45 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:05:45 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:05:45 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/17 05:05:45 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:45 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/17 05:05:45 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:05:45 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:05:45 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:05:45 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:45 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/17 05:05:45 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:05:45 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:05:45 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/modfiles"
2025/11/17 05:05:45 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_basic-naracaf0"
2025/11/17 05:05:45 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:45 DEBUG : Local file system at /tmp/050541ku/datadir/test_basic-naracaf0: Waiting for checks to finish
2025/11/17 05:05:45 DEBUG : Local file system at /tmp/050541ku/datadir/test_basic-naracaf0: Waiting for transfers to finish
2025/11/17 05:05:45 DEBUG : file1.txt.75b77f44.partial: size = 19 OK
2025/11/17 05:05:45 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:05:45 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt
2025/11/17 05:05:45 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:45 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:05:46 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:05:47 NOTICE: checking initFs Local file system at /tmp/050541ku/initdir/test_basic-wofuron9
2025/11/17 05:05:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 INFO  : subdir: Making directory
2025/11/17 05:05:47 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:47 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: Waiting for checks to finish
2025/11/17 05:05:47 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: Waiting for transfers to finish
2025/11/17 05:05:48 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/17 05:05:48 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:48 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:05:48 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/17 05:05:48 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:48 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/17 05:05:49 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:49 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/17 05:05:49 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:49 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:05:49 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:49 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/17 05:05:49 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:49 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/17 05:05:49 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:49 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:49 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:05:49 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:05:49 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:05:49 NOTICE: checking Path1 B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1
2025/11/17 05:05:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 INFO  : subdir: Making directory
2025/11/17 05:05:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:05:49 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: Waiting for checks to finish
2025/11/17 05:05:49 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: Waiting for transfers to finish
2025/11/17 05:05:51 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:05:51 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:05:51 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:05:51 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:05:51 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/17 05:05:51 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:05:51 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:05:51 NOTICE: checking path2 B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2
2025/11/17 05:05:52 NOTICE: (01)  : test basic
2025/11/17 05:05:52 NOTICE: (02)  : test initial bisync
2025/11/17 05:05:52 NOTICE: (03)  : bisync resync
2025/11/17 05:05:59 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:59 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:05:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:05:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:05:59 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/"
2025/11/17 05:05:59 INFO  : Copying Path2 files to Path1
2025/11/17 05:05:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:05:59 INFO  : There was nothing to transfer
2025/11/17 05:05:59 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:05:59 INFO  : There was nothing to transfer
2025/11/17 05:05:59 INFO  : Resync updating listings
2025/11/17 05:05:59 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/"
2025/11/17 05:05:59 INFO  : Bisync successful
2025/11/17 05:05:59 NOTICE: (04)  : test place newer files on both paths
2025/11/17 05:05:59 NOTICE: (05)  : touch-copy 2001-01-02 /tmp/050541ku/datadir/test_basic-naracaf0/file1.txt TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/
2025/11/17 05:05:59 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_basic-naracaf0/"
2025/11/17 05:05:59 DEBUG : fs cache: renaming cache item "/tmp/050541ku/datadir/test_basic-naracaf0/" to be canonical "/tmp/050541ku/datadir/test_basic-naracaf0"
2025/11/17 05:05:59 DEBUG : file1.txt: Set modification time 2001-01-02
2025/11/17 05:05:59 DEBUG : copyFile "/tmp/050541ku/datadir/test_basic-naracaf0/file1.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/" as ""
2025/11/17 05:05:59 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_basic-naracaf0/file1.txt"
2025/11/17 05:05:59 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/"
2025/11/17 05:06:01 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2" as "file1.txt"
2025/11/17 05:06:01 DEBUG : file1.txt: size = 19 (Local file system at /tmp/050541ku/datadir/test_basic-naracaf0)
2025/11/17 05:06:01 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2)
2025/11/17 05:06:01 DEBUG : file1.txt: Sizes differ
2025/11/17 05:06:02 DEBUG : file1.txt: size = 19 OK
2025/11/17 05:06:02 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/17 05:06:02 INFO  : file1.txt: Copied (replaced existing)
2025/11/17 05:06:02 NOTICE: (06)  : copy-as /tmp/050541ku/datadir/test_basic-naracaf0/file1.txt TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/subdir file20.txt
2025/11/17 05:06:02 DEBUG : copyFile "/tmp/050541ku/datadir/test_basic-naracaf0/file1.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/subdir" as "file20.txt"
2025/11/17 05:06:02 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_basic-naracaf0/file1.txt"
2025/11/17 05:06:02 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/subdir"
2025/11/17 05:06:03 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1/subdir" as "file20.txt"
2025/11/17 05:06:04 DEBUG : file1.txt: size = 19 (Local file system at /tmp/050541ku/datadir/test_basic-naracaf0)
2025/11/17 05:06:04 DEBUG : file20.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1/subdir)
2025/11/17 05:06:04 DEBUG : file1.txt: Sizes differ
2025/11/17 05:06:05 DEBUG : file20.txt: size = 19 OK
2025/11/17 05:06:05 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/17 05:06:05 INFO  : file1.txt: Copied (replaced existing) to: file20.txt
2025/11/17 05:06:05 NOTICE: (07)  : test bisync run
2025/11/17 05:06:05 NOTICE: (08)  : bisync
2025/11/17 05:06:14 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:14 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:06:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:06:14 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/"
2025/11/17 05:06:14 INFO  : Building Path1 and Path2 listings
2025/11/17 05:06:15 INFO  : Path1 checking for diffs
2025/11/17 05:06:15 INFO  : - Path1    File changed: size (larger), time (newer) - subdir/file20.txt
2025/11/17 05:06:15 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/11/17 05:06:15 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/11/17 05:06:15 INFO  : Path2 checking for diffs
2025/11/17 05:06:15 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/11/17 05:06:15 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/11/17 05:06:15 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/11/17 05:06:15 INFO  : Applying changes
2025/11/17 05:06:15 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/subdir/file20.txt
2025/11/17 05:06:15 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/file1.txt
2025/11/17 05:06:15 INFO  : - Path2    Do queued copies to                - Path1
2025/11/17 05:06:17 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:06:17 INFO  : - Path1    Do queued copies to                - Path2
2025/11/17 05:06:18 INFO  : subdir/file20.txt: Copied (server-side copy)
2025/11/17 05:06:18 INFO  : Updating listings
2025/11/17 05:06:18 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/basic/path2/"
2025/11/17 05:06:18 INFO  : Bisync successful
2025/11/17 05:06:18 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:06:18 DEBUG : RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103e6ac43f92f16a_d20251117_m050549_c001_v0001040_t0006_u01763355949112")
2025/11/17 05:06:18 DEBUG : file1.copy1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1150e911adf76815_d20251117_m050548_c001_v0001153_t0019_u01763355948544")
2025/11/17 05:06:18 DEBUG : file1.copy2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10301ef05b6973c5_d20251117_m050548_c001_v0001183_t0019_u01763355948916")
2025/11/17 05:06:18 DEBUG : file1.copy3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103307834ade2352_d20251117_m050548_c001_v0001177_t0004_u01763355948728")
2025/11/17 05:06:18 DEBUG : file1.copy4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103307834ade2354_d20251117_m050548_c001_v0001177_t0059_u01763355948950")
2025/11/17 05:06:18 DEBUG : file1.copy5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1150e911adf76819_d20251117_m050548_c001_v0001153_t0003_u01763355948962")
2025/11/17 05:06:18 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10165b6031b64b05_d20251117_m050617_c001_v0001131_t0036_u01763355977453")
2025/11/17 05:06:18 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10301ef05b6973c9_d20251117_m050549_c001_v0001183_t0029_u01763355949117")
2025/11/17 05:06:18 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f41096641a43c38f1_d20251117_m050610_c001_v7007000_t0000_u01763355970076")
2025/11/17 05:06:19 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11030495d22e2434_d20251117_m050608_c001_v0001104_t0037_u01763355968540")
2025/11/17 05:06:19 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1150e911adf7683b_d20251117_m050606_c001_v0001153_t0016_u01763355966256")
2025/11/17 05:06:19 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4085dfbc2695e117_d20251117_m050555_c001_v7007000_t0000_u01763355955370")
2025/11/17 05:06:19 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1103c76bc5138830_d20251117_m050554_c001_v0001090_t0039_u01763355954031")
2025/11/17 05:06:19 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10301ef05b697401_d20251117_m050552_c001_v0001183_t0037_u01763355952253")
2025/11/17 05:06:19 DEBUG : subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103c654f6196441e_d20251117_m050605_c001_v0001137_t0003_u01763355965473")
2025/11/17 05:06:19 DEBUG : subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103307834ade2356_d20251117_m050549_c001_v0001177_t0022_u01763355949180")
2025/11/17 05:06:20 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/basic/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:06:20 DEBUG : RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1043d144b06f41f0_d20251117_m050551_c001_v0001043_t0037_u01763355951192")
2025/11/17 05:06:20 DEBUG : file1.copy1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102bd7e7c3b27bb1_d20251117_m050550_c001_v0001144_t0032_u01763355950622")
2025/11/17 05:06:20 DEBUG : file1.copy2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1169b6dbe00fce6f_d20251117_m050550_c001_v0001105_t0019_u01763355950808")
2025/11/17 05:06:20 DEBUG : file1.copy3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11655c67b1d39c74_d20251117_m050551_c001_v0001180_t0045_u01763355951006")
2025/11/17 05:06:20 DEBUG : file1.copy4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102bd7e7c3b27bb7_d20251117_m050551_c001_v0001144_t0035_u01763355951175")
2025/11/17 05:06:20 DEBUG : file1.copy5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1169b6dbe00fce71_d20251117_m050551_c001_v0001105_t0054_u01763355951245")
2025/11/17 05:06:20 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10301ef05b697476_d20251117_m050602_c001_v0001183_t0029_u01763355962717")
2025/11/17 05:06:20 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11655c67b1d39c78_d20251117_m050551_c001_v0001180_t0021_u01763355951259")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f414a76597f551cf7_d20251117_m050614_c001_v7007000_t0000_u01763355974607")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10512b526e2f36ce_d20251117_m050613_c001_v0001029_t0011_u01763355973202")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1043d144b06f4232_d20251117_m050610_c001_v0001043_t0022_u01763355970627")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f40214feff80afcac_d20251117_m050559_c001_v7007000_t0000_u01763355959154")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1171cd1e8c236e4d_d20251117_m050557_c001_v0001136_t0053_u01763355957828")
2025/11/17 05:06:20 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1169b6dbe00fce7f_d20251117_m050555_c001_v0001105_t0001_u01763355955553")
2025/11/17 05:06:20 DEBUG : subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f114455ac4a415061_d20251117_m050618_c001_v0001134_t0037_u01763355978269")
2025/11/17 05:06:21 DEBUG : subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11655c67b1d39c7c_d20251117_m050551_c001_v0001180_t0006_u01763355951484")
    bisync_test.go:608: TEST basic PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/11/17 05:06:21 INFO  : path1: Making directory
2025/11/17 05:06:21 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1"
2025/11/17 05:06:22 INFO  : path2: Making directory
2025/11/17 05:06:22 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2"
2025/11/17 05:06:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/11/17 05:06:23 DEBUG : Creating backend with remote "/tmp/050541ku/initdir/test_resync_modes-rekayuc5"
2025/11/17 05:06:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : Local file system at /tmp/050541ku/initdir/test_resync_modes-rekayuc5: Waiting for checks to finish
2025/11/17 05:06:23 DEBUG : Local file system at /tmp/050541ku/initdir/test_resync_modes-rekayuc5: Waiting for transfers to finish
2025/11/17 05:06:23 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/17 05:06:23 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:06:23 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/17 05:06:23 INFO  : file2.txt: Copied (new)
2025/11/17 05:06:23 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:06:23 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:06:23 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:06:23 INFO  : file1.txt: Copied (new)
2025/11/17 05:06:23 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/17 05:06:23 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:06:23 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/17 05:06:23 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:06:23 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/11/17 05:06:23 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:06:23 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:23 DEBUG : Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2: Waiting for checks to finish
2025/11/17 05:06:23 DEBUG : Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2: Waiting for transfers to finish
2025/11/17 05:06:23 DEBUG : file1L.txt.684d937d.partial: size = 33 OK
2025/11/17 05:06:23 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/11/17 05:06:23 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/11/17 05:06:23 INFO  : file1L.txt: Copied (new)
2025/11/17 05:06:23 DEBUG : file1R.txt.d467e63a.partial: size = 42 OK
2025/11/17 05:06:23 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/11/17 05:06:23 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/11/17 05:06:23 INFO  : file1R.txt: Copied (new)
2025/11/17 05:06:23 DEBUG : file2.txt.ac4156f2.partial: size = 37 OK
2025/11/17 05:06:23 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/11/17 05:06:23 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/11/17 05:06:23 INFO  : file2.txt: Copied (new)
2025/11/17 05:06:23 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:06:24 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:06:25 NOTICE: checking initFs Local file system at /tmp/050541ku/initdir/test_resync_modes-rekayuc5
2025/11/17 05:06:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:06:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:25 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Waiting for checks to finish
2025/11/17 05:06:25 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Waiting for transfers to finish
2025/11/17 05:06:26 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:06:26 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:06:26 INFO  : file1.txt: Copied (new)
2025/11/17 05:06:27 DEBUG : file2.txt: size = 0 OK
2025/11/17 05:06:27 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:06:27 INFO  : file2.txt: Copied (new)
2025/11/17 05:06:27 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:06:27 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:06:27 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:06:27 NOTICE: checking Path1 B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1
2025/11/17 05:06:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:06:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:27 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:06:27 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Waiting for checks to finish
2025/11/17 05:06:27 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Waiting for transfers to finish
2025/11/17 05:06:28 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:06:28 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:06:28 INFO  : file1.txt: Copied (new)
2025/11/17 05:06:28 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:06:28 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:06:28 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:06:29 DEBUG : file2.txt: size = 0 OK
2025/11/17 05:06:29 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:06:29 INFO  : file2.txt: Copied (new)
2025/11/17 05:06:29 NOTICE: checking path2 B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2
2025/11/17 05:06:29 NOTICE: (01)  : test resync-mode
2025/11/17 05:06:29 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:06:29 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:06:29 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/"
2025/11/17 05:06:29 DEBUG : fs cache: renaming cache item "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" to be canonical "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:06:29 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/11/17 05:06:29 NOTICE: (04)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:06:29 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:06:29 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:06:29 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:06:30 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:06:30 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:06:30 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:06:30 DEBUG : file1R.txt: Sizes differ
2025/11/17 05:06:32 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:06:32 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:06:32 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:06:32 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:06:32 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:06:32 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/11/17 05:06:32 NOTICE: (06)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:06:32 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:06:32 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:06:32 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:06:33 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:06:33 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:06:33 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1)
2025/11/17 05:06:33 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:06:35 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:06:35 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:06:35 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:06:35 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/11/17 05:06:35 NOTICE: (08)  : bisync resync resync-mode=newer
2025/11/17 05:06:44 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:44 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:44 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:06:44 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:06:44 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:06:44 INFO  : Copying Path2 files to Path1
2025/11/17 05:06:44 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:06:44 INFO  : There was nothing to transfer
2025/11/17 05:06:44 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:06:45 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:06:45 INFO  : Resync updating listings
2025/11/17 05:06:45 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:06:45 INFO  : Bisync successful
2025/11/17 05:06:45 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:06:45 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:06:45 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:06:45 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/11/17 05:06:45 NOTICE: (11)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:06:45 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:06:45 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:06:45 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:06:46 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:06:46 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:06:46 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:06:46 DEBUG : file1R.txt: Sizes differ
2025/11/17 05:06:48 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:06:48 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:06:48 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:06:48 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:06:48 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:06:48 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/11/17 05:06:48 NOTICE: (13)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:06:48 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:06:48 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:06:48 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:06:49 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:06:49 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:06:49 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/11/17 05:06:49 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:06:50 INFO  : file1L.txt: Updated modification time in destination
2025/11/17 05:06:50 DEBUG : file1L.txt: Unchanged skipping
2025/11/17 05:06:50 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/11/17 05:06:50 NOTICE: (15)  : bisync resync resync-mode=path2
2025/11/17 05:06:59 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:59 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:06:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:06:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:06:59 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:06:59 INFO  : Copying Path2 files to Path1
2025/11/17 05:06:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:06:59 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:06:59 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:07:00 INFO  : Resync updating listings
2025/11/17 05:07:00 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:00 INFO  : Bisync successful
2025/11/17 05:07:00 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:07:00 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:07:00 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:00 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/11/17 05:07:00 NOTICE: (18)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:07:00 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:07:00 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:07:00 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:01 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:07:01 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:07:01 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/11/17 05:07:01 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:07:02 INFO  : file1R.txt: Updated modification time in destination
2025/11/17 05:07:02 DEBUG : file1R.txt: Unchanged skipping
2025/11/17 05:07:02 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:07:02 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:02 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/11/17 05:07:02 NOTICE: (20)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:07:02 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:07:02 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:07:02 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:07:03 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:07:03 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:03 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1)
2025/11/17 05:07:03 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:07:05 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:07:05 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:07:05 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:07:05 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/11/17 05:07:05 NOTICE: (22)  : bisync resync resync-mode=larger
2025/11/17 05:07:15 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:15 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:15 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:07:15 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:07:15 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:15 INFO  : Copying Path2 files to Path1
2025/11/17 05:07:15 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:07:15 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/11/17 05:07:15 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:07:15 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:07:15 INFO  : Resync updating listings
2025/11/17 05:07:15 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:15 INFO  : Bisync successful
2025/11/17 05:07:15 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:07:15 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:07:15 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:15 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/11/17 05:07:15 NOTICE: (25)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:07:15 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:07:15 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:07:15 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:16 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:07:17 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:07:17 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/11/17 05:07:17 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:07:18 INFO  : file1R.txt: Updated modification time in destination
2025/11/17 05:07:18 DEBUG : file1R.txt: Unchanged skipping
2025/11/17 05:07:18 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:07:18 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:18 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/11/17 05:07:18 NOTICE: (27)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:07:18 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:07:18 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:07:18 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:07:19 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:07:19 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:19 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1)
2025/11/17 05:07:19 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:07:21 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:07:21 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:07:21 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:07:21 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/11/17 05:07:21 NOTICE: (29)  : bisync resync resync-mode=older
2025/11/17 05:07:30 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:30 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:30 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:07: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/11/17 05:07:30 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:30 INFO  : Copying Path2 files to Path1
2025/11/17 05:07:30 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:07:30 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/11/17 05:07:30 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:07:30 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:07:31 INFO  : Resync updating listings
2025/11/17 05:07:31 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:31 INFO  : Bisync successful
2025/11/17 05:07:31 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:07:31 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:07:31 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:31 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/11/17 05:07:31 NOTICE: (32)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:07:31 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:07:31 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:07:31 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:32 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:07:32 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:07:32 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/11/17 05:07:32 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:07:33 INFO  : file1R.txt: Updated modification time in destination
2025/11/17 05:07:33 DEBUG : file1R.txt: Unchanged skipping
2025/11/17 05:07:33 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:07:33 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:33 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/11/17 05:07:33 NOTICE: (34)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:07:33 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:07:33 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:07:33 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:07:34 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:07:35 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:35 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1)
2025/11/17 05:07:35 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:07:36 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:07:36 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:07:36 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:07:36 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/11/17 05:07:36 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/11/17 05:07:45 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:45 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:07:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:07:45 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:07:45 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:45 INFO  : Copying Path2 files to Path1
2025/11/17 05:07:45 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:07:45 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/11/17 05:07:45 INFO  : There was nothing to transfer
2025/11/17 05:07:45 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:07:46 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/11/17 05:07:46 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:07:46 INFO  : Resync updating listings
2025/11/17 05:07:46 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:46 INFO  : Bisync successful
2025/11/17 05:07:46 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/17 05:07:46 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:07:46 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:46 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/11/17 05:07:46 NOTICE: (39)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:07:46 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:07:46 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:07:46 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:47 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:07:47 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:47 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:07:47 DEBUG : file1R.txt: Sizes differ
2025/11/17 05:07:49 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:07:49 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:07:49 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:07:49 NOTICE: (40)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file2.txt
2025/11/17 05:07:49 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file2.txt"
2025/11/17 05:07:49 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:07:49 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:07:50 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file2.txt"
2025/11/17 05:07:50 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:50 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1)
2025/11/17 05:07:50 DEBUG : file1R.txt: Sizes differ
2025/11/17 05:07:52 DEBUG : file2.txt: size = 42 OK
2025/11/17 05:07:52 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:07:52 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/11/17 05:07:52 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:07:52 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:07:52 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/11/17 05:07:52 NOTICE: (42)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:07:52 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:07:52 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:07:52 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:07:53 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:07:53 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:07:53 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/11/17 05:07:53 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:07:55 INFO  : file1L.txt: Updated modification time in destination
2025/11/17 05:07:55 DEBUG : file1L.txt: Unchanged skipping
2025/11/17 05:07:55 NOTICE: (43)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file2.txt
2025/11/17 05:07:55 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file2.txt"
2025/11/17 05:07:55 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:07:55 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:07:56 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file2.txt"
2025/11/17 05:07:56 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:07:56 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:07:56 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:07:58 DEBUG : file2.txt: size = 33 OK
2025/11/17 05:07:58 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:07:58 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/11/17 05:07:58 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/11/17 05:07:58 NOTICE: (45)  : bisync resync resync-mode=path1
2025/11/17 05:08:07 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:08:07 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:08:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:08:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:08:07 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:07 INFO  : Copying Path2 files to Path1
2025/11/17 05:08:07 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:08:07 INFO  : There was nothing to transfer
2025/11/17 05:08:07 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:08:08 INFO  : file2.txt: Copied (server-side copy)
2025/11/17 05:08:08 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:08:08 INFO  : Resync updating listings
2025/11/17 05:08:08 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:08 INFO  : Bisync successful
2025/11/17 05:08:08 NOTICE: (46)  : test no winner
2025/11/17 05:08:08 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1R.txt
2025/11/17 05:08:08 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:08:08 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/11/17 05:08:08 NOTICE: (48)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file1.txt
2025/11/17 05:08:08 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file1.txt"
2025/11/17 05:08:08 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:08:08 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:09 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file1.txt"
2025/11/17 05:08:09 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:08:09 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:08:09 DEBUG : file1R.txt: Sizes differ
2025/11/17 05:08:11 DEBUG : file1.txt: size = 42 OK
2025/11/17 05:08:11 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:08:11 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/17 05:08:11 NOTICE: (49)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file2.txt
2025/11/17 05:08:11 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file2.txt"
2025/11/17 05:08:11 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1R.txt"
2025/11/17 05:08:11 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:08:12 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file2.txt"
2025/11/17 05:08:12 DEBUG : file2.txt: size = 42 OK
2025/11/17 05:08:12 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/11/17 05:08:12 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/17 05:08:13 INFO  : file1R.txt: Updated modification time in destination
2025/11/17 05:08:13 DEBUG : file1R.txt: Unchanged skipping
2025/11/17 05:08:13 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/050541ku/datadir/test_resync_modes-xikucer2/ file1L.txt
2025/11/17 05:08:13 DEBUG : fs cache: switching user supplied name "/tmp/050541ku/datadir/test_resync_modes-xikucer2/" for canonical name "/tmp/050541ku/datadir/test_resync_modes-xikucer2"
2025/11/17 05:08:13 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/11/17 05:08:13 NOTICE: (51)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/ file1.txt
2025/11/17 05:08:13 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" as "file1.txt"
2025/11/17 05:08:13 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:08:13 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/"
2025/11/17 05:08:14 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1" as "file1.txt"
2025/11/17 05:08:14 DEBUG : file1.txt: size = 33 OK
2025/11/17 05:08:14 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/11/17 05:08:14 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:08:16 INFO  : file1L.txt: Updated modification time in destination
2025/11/17 05:08:16 DEBUG : file1L.txt: Unchanged skipping
2025/11/17 05:08:16 NOTICE: (52)  : copy-as /tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/ file2.txt
2025/11/17 05:08:16 DEBUG : copyFile "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt" to "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/" as "file2.txt"
2025/11/17 05:08:16 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_resync_modes-xikucer2/file1L.txt"
2025/11/17 05:08:16 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:17 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2" as "file2.txt"
2025/11/17 05:08:17 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/050541ku/datadir/test_resync_modes-xikucer2)
2025/11/17 05:08:17 DEBUG : file2.txt: size = 42 (B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2)
2025/11/17 05:08:17 DEBUG : file1L.txt: Sizes differ
2025/11/17 05:08:19 DEBUG : file2.txt: size = 33 OK
2025/11/17 05:08:19 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/17 05:08:19 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/11/17 05:08:19 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/11/17 05:08:19 NOTICE: (54)  : bisync resync resync-mode=newer
2025/11/17 05:08:27 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:08:27 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/17 05:08:27 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/17 05:08:27 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/17 05:08:27 INFO  : Synching Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" with Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:27 INFO  : Copying Path2 files to Path1
2025/11/17 05:08:27 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/17 05:08:28 INFO  : file1.txt: Copied (server-side copy)
2025/11/17 05:08:28 INFO  : file2.txt: Copied (server-side copy)
2025/11/17 05:08:28 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/17 05:08:28 INFO  : Resync updating listings
2025/11/17 05:08:28 INFO  : Validating listings for Path1 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path1/" vs Path2 "TestB2:rclone-test-picoxus5buxu/050541ku/resync_modes/path2/"
2025/11/17 05:08:28 INFO  : Bisync successful
2025/11/17 05:08:28 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:28 DEBUG : RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f100a888e32dd8af3_d20251117_m050626_c001_v0001116_t0018_u01763355986693")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11849ea5616a181c_d20251117_m050828_c001_v0001182_t0000_u01763356108085")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11240929c181e5e3_d20251117_m050815_c001_v0001091_t0036_u01763356095934")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102b899d1137a6ab_d20251117_m050754_c001_v0001178_t0042_u01763356074962")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1097b1ecf72c61f6_d20251117_m050736_c001_v0001116_t0009_u01763356056397")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1152ca3763a23f15_d20251117_m050730_c001_v0001130_t0042_u01763356050550")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10551b1c92292bdc_d20251117_m050721_c001_v0001130_t0055_u01763356041112")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f100ff29b8cf23205_d20251117_m050715_c001_v0001101_t0041_u01763356035326")
2025/11/17 05:08:28 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f116a1f103883f8d1_d20251117_m050705_c001_v0001032_t0041_u01763356025128")
2025/11/17 05:08:29 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1162d2af866ca226_d20251117_m050659_c001_v0001154_t0002_u01763356019628")
2025/11/17 05:08:29 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10470a2edecbdbd9_d20251117_m050650_c001_v0001171_t0039_u01763356010493")
2025/11/17 05:08:29 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1140ecca04ff015d_d20251117_m050634_c001_v0001101_t0005_u01763355994921")
2025/11/17 05:08:29 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1172b699c125a16e_d20251117_m050626_c001_v0001029_t0040_u01763355986295")
2025/11/17 05:08:29 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1061ae6ea4dd282a_d20251117_m050828_c001_v0001180_t0055_u01763356108316")
2025/11/17 05:08:29 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11302fc5e8a1b757_d20251117_m050813_c001_v0001182_t0008_u01763356093408")
2025/11/17 05:08:29 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1131f2002ba0b78c_d20251117_m050752_c001_v0001115_t0048_u01763356072119")
2025/11/17 05:08:29 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1124267b442ebcad_d20251117_m050626_c001_v0001161_t0031_u01763355986480")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4123496e5c814bc3_d20251117_m050823_c001_v7007000_t0000_u01763356103021")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10757ee900638b1b_d20251117_m050821_c001_v0001120_t0041_u01763356101755")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1172b699c125a21c_d20251117_m050819_c001_v0001029_t0031_u01763356099509")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4085587f738f8c69_d20251117_m050802_c001_v7007000_t0000_u01763356082446")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f113e9c264e540f68_d20251117_m050801_c001_v0001130_t0056_u01763356081092")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f100a888e32dd8bb0_d20251117_m050758_c001_v0001116_t0015_u01763356078551")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4196b5bd76edd0da_d20251117_m050741_c001_v7007000_t0000_u01763356061377")
2025/11/17 05:08:29 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117ae0f8023e214b_d20251117_m050739_c001_v0001109_t0013_u01763356059689")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1124267b442ebe0a_d20251117_m050737_c001_v0001161_t0015_u01763356057387")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f418717417556916f_d20251117_m050725_c001_v7007000_t0000_u01763356045604")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1015d14dcf0017ea_d20251117_m050724_c001_v0001113_t0003_u01763356044134")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1172b699c125a1d8_d20251117_m050721_c001_v0001029_t0044_u01763356041853")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4034dd4fe8d3108a_d20251117_m050710_c001_v7007000_t0000_u01763356030626")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f115d8cfaef61b951_d20251117_m050708_c001_v0001116_t0037_u01763356028609")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f100a888e32dd8b3a_d20251117_m050706_c001_v0001116_t0040_u01763356026086")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f41523415b8543f13_d20251117_m050654_c001_v7007000_t0000_u01763356014834")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10097e1a870efba6_d20251117_m050653_c001_v0001178_t0035_u01763356013573")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1124267b442ebd37_d20251117_m050651_c001_v0001161_t0031_u01763356011232")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4123496e5c81446d_d20251117_m050639_c001_v7007000_t0000_u01763355999473")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f111c46057548f17b_d20251117_m050638_c001_v0001137_t0038_u01763355998040")
2025/11/17 05:08:30 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1172b699c125a190_d20251117_m050635_c001_v0001029_t0031_u01763355995691")
2025/11/17 05:08:31 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/resync_modes/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:31 DEBUG : RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11325b6d586cceda_d20251117_m050628_c001_v0001105_t0031_u01763355988545")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10235320b95cfdf6_d20251117_m050810_c001_v0001181_t0034_u01763356090845")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f118b63ee865630c0_d20251117_m050807_c001_v0001144_t0048_u01763356087477")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f116e10dc5776de99_d20251117_m050749_c001_v0001134_t0023_u01763356069051")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10848e2228d1293a_d20251117_m050746_c001_v0001172_t0007_u01763356066220")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1018ca79be712c51_d20251117_m050733_c001_v0001098_t0029_u01763356053346")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1067c214cd420e58_d20251117_m050718_c001_v0001145_t0027_u01763356038176")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f118844060fa5b7c4_d20251117_m050702_c001_v0001184_t0004_u01763356022381")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1065a8bed27fd54e_d20251117_m050647_c001_v0001130_t0057_u01763356007834")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f111e1ccdb475005b_d20251117_m050644_c001_v0001144_t0031_u01763356004664")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1018e812f350c706_d20251117_m050632_c001_v0001180_t0006_u01763355992034")
2025/11/17 05:08:31 DEBUG : file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103ee7ce3740faa7_d20251117_m050628_c001_v0001104_t0016_u01763355988363")
2025/11/17 05:08:31 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117886f200b9b830_d20251117_m050818_c001_v0001177_t0022_u01763356098917")
2025/11/17 05:08:31 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11610d04dd16c401_d20251117_m050807_c001_v0001178_t0010_u01763356087883")
2025/11/17 05:08:31 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f114c5dc70c279a86_d20251117_m050757_c001_v0001130_t0038_u01763356077796")
2025/11/17 05:08:32 DEBUG : file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f109e0d5c76d6d129_d20251117_m050628_c001_v0001101_t0024_u01763355988754")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4085587f738f8c92_d20251117_m050827_c001_v7007000_t0000_u01763356107685")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1011676cda38ae3e_d20251117_m050826_c001_v0001178_t0035_u01763356106376")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103ee7ce3740fbd3_d20251117_m050823_c001_v0001104_t0059_u01763356103571")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f41523415b85440c7_d20251117_m050806_c001_v7007000_t0000_u01763356086910")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1056130c9d0e242b_d20251117_m050805_c001_v0001116_t0041_u01763356085345")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f109e0d5c76d6d2ba_d20251117_m050802_c001_v0001101_t0007_u01763356082993")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4076efd2d5874602_d20251117_m050745_c001_v7007000_t0000_u01763356065651")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1194db34c2173655_d20251117_m050744_c001_v0001093_t0059_u01763356064269")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11325b6d586ccfd2_d20251117_m050741_c001_v0001105_t0054_u01763356061921")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f421c9953e4ab1c71_d20251117_m050730_c001_v7007000_t0000_u01763356050115")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f111cd7a412566c93_d20251117_m050728_c001_v0001157_t0011_u01763356048713")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103ee7ce3740fb5d_d20251117_m050726_c001_v0001104_t0007_u01763356046152")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f423d2aea606f1761_d20251117_m050714_c001_v7007000_t0000_u01763356034939")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11190ae4648e84e4_d20251117_m050713_c001_v0001092_t0029_u01763356033489")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f109e0d5c76d6d1c8_d20251117_m050711_c001_v0001101_t0026_u01763356031168")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f41523415b8543f3d_d20251117_m050659_c001_v7007000_t0000_u01763356019219")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f110c09bc2093558c_d20251117_m050657_c001_v0001113_t0027_u01763356017739")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11325b6d586ccf3a_d20251117_m050655_c001_v0001105_t0032_u01763356015378")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f4067c17e4afe37bf_d20251117_m050644_c001_v7007000_t0000_u01763356004086")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1137f6c00f9caafe_d20251117_m050642_c001_v0001184_t0053_u01763356002863")
2025/11/17 05:08:32 DEBUG : modtime_write_test: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f103ee7ce3740fad5_d20251117_m050640_c001_v0001104_t0006_u01763356000020")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/11/17 05:08:34 INFO  : path1: Making directory
2025/11/17 05:08:34 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/rmdirs/path1"
2025/11/17 05:08:35 INFO  : path2: Making directory
2025/11/17 05:08:35 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/rmdirs/path2"
2025/11/17 05:08:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/11/17 05:08:36 DEBUG : Creating backend with remote "/tmp/050541ku/initdir/test_rmdirs-subonus1"
2025/11/17 05:08:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : subdir: Making directory with metadata
2025/11/17 05:08:36 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/17 05:08:36 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/17 05:08:36 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : Local file system at /tmp/050541ku/initdir/test_rmdirs-subonus1: Waiting for checks to finish
2025/11/17 05:08:36 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : Local file system at /tmp/050541ku/initdir/test_rmdirs-subonus1: Waiting for transfers to finish
2025/11/17 05:08:36 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/17 05:08:36 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/17 05:08:36 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/17 05:08:36 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/17 05:08:36 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/17 05:08:36 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:08:36 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/17 05:08:36 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:36 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/17 05:08:36 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:08:36 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/17 05:08:36 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:08:36 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/17 05:08:36 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:36 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/17 05:08:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/11/17 05:08:36 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_rmdirs-hezabir4"
2025/11/17 05:08:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:36 DEBUG : Local file system at /tmp/050541ku/datadir/test_rmdirs-hezabir4: Waiting for checks to finish
2025/11/17 05:08:36 DEBUG : Local file system at /tmp/050541ku/datadir/test_rmdirs-hezabir4: Waiting for transfers to finish
2025/11/17 05:08:36 DEBUG : file1.txt.f41fd5ee.partial: size = 19 OK
2025/11/17 05:08:36 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:36 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/11/17 05:08:36 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:36 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:37 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:38 NOTICE: checking initFs Local file system at /tmp/050541ku/initdir/test_rmdirs-subonus1
2025/11/17 05:08:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 INFO  : subdir: Making directory
2025/11/17 05:08:38 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:38 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path1: Waiting for checks to finish
2025/11/17 05:08:38 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path1: Waiting for transfers to finish
2025/11/17 05:08:39 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/17 05:08:39 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:39 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:08:39 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/17 05:08:39 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:39 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:08:39 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/17 05:08:39 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:39 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:08:39 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/17 05:08:39 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:39 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:08:39 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/17 05:08:39 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:39 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:08:40 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:08:40 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:40 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:40 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:08:40 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:08:40 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:40 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/17 05:08:40 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:40 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:08:40 NOTICE: checking Path1 B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path1
2025/11/17 05:08:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 INFO  : subdir: Making directory
2025/11/17 05:08:40 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:40 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path2: Waiting for checks to finish
2025/11/17 05:08:40 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path2: Waiting for transfers to finish
2025/11/17 05:08:41 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/17 05:08:41 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:41 INFO  : file1.copy1.txt: Copied (new)
2025/11/17 05:08:41 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/17 05:08:41 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:41 INFO  : file1.copy4.txt: Copied (new)
2025/11/17 05:08:41 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/17 05:08:41 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:41 INFO  : file1.copy2.txt: Copied (new)
2025/11/17 05:08:42 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/17 05:08:42 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:42 INFO  : file1.copy5.txt: Copied (new)
2025/11/17 05:08:42 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/17 05:08:42 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:42 INFO  : file1.copy3.txt: Copied (new)
2025/11/17 05:08:42 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:08:42 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:08:42 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:42 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:08:42 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:42 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:42 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/17 05:08:42 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:42 INFO  : subdir/file20.txt: Copied (new)
2025/11/17 05:08:42 NOTICE: checking path2 B2 bucket rclone-test-picoxus5buxu path 050541ku/rmdirs/path2
2025/11/17 05:08:42 NOTICE: (01)  : test rmdirs
2025/11/17 05:08:42 NOTICE: (02)  : test initial bisync
2025/11/17 05:08:42 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/11/17 05:08:42 INFO  : path1: Making directory
2025/11/17 05:08:42 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/volatile/path1"
2025/11/17 05:08:43 INFO  : path2: Making directory
2025/11/17 05:08:43 DEBUG : Creating backend with remote "TestB2:rclone-test-picoxus5buxu/050541ku/volatile/path2"
2025/11/17 05:08:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/11/17 05:08:44 DEBUG : Creating backend with remote "/tmp/050541ku/initdir/test_volatile-jotafuz4"
2025/11/17 05:08:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/17 05:08:44 INFO  : file2.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : Local file system at /tmp/050541ku/initdir/test_volatile-jotafuz4: Waiting for checks to finish
2025/11/17 05:08:44 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : Local file system at /tmp/050541ku/initdir/test_volatile-jotafuz4: Waiting for transfers to finish
2025/11/17 05:08:44 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/17 05:08:44 INFO  : file4.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/17 05:08:44 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/17 05:08:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/17 05:08:44 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/17 05:08:44 INFO  : file3.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/11/17 05:08:44 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/11/17 05:08:44 INFO  : file6.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/11/17 05:08:44 INFO  : file7.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/17 05:08:44 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:44 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/11/17 05:08:44 INFO  : file8.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/11/17 05:08:44 INFO  : file5.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/11/17 05:08:44 DEBUG : Creating backend with remote "/tmp/050541ku/datadir/test_volatile-dogejuk7"
2025/11/17 05:08:44 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:44 DEBUG : dummy.txt.b3782999.partial: size = 0 OK
2025/11/17 05:08:44 DEBUG : file1.txt.be52b1c9.partial: size = 19 OK
2025/11/17 05:08:44 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/17 05:08:44 DEBUG : Local file system at /tmp/050541ku/datadir/test_volatile-dogejuk7: Waiting for checks to finish
2025/11/17 05:08:44 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/11/17 05:08:44 INFO  : dummy.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : Local file system at /tmp/050541ku/datadir/test_volatile-dogejuk7: Waiting for transfers to finish
2025/11/17 05:08:44 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:44 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/11/17 05:08:44 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK
2025/11/17 05:08:44 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/11/17 05:08:44 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/11/17 05:08:44 INFO  : file5L.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file11.txt.d4a7309d.partial: size = 19 OK
2025/11/17 05:08:44 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK
2025/11/17 05:08:44 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:44 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/11/17 05:08:44 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/11/17 05:08:44 INFO  : file5R.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/11/17 05:08:44 INFO  : file11.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file6.txt.7b4c87b3.partial: size = 19 OK
2025/11/17 05:08:44 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:44 DEBUG : file7.txt.38c43fbf.partial: size = 19 OK
2025/11/17 05:08:44 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/11/17 05:08:44 INFO  : file6.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:44 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/11/17 05:08:44 INFO  : file7.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file2.txt.23ff1769.partial: size = 13 OK
2025/11/17 05:08:44 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/11/17 05:08:44 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/11/17 05:08:44 INFO  : file2.txt: Copied (new)
2025/11/17 05:08:44 DEBUG : file10.txt.bdde3bf8.partial: size = 19 OK
2025/11/17 05:08:44 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/17 05:08:44 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/11/17 05:08:44 INFO  : file10.txt: Copied (new)
2025/11/17 05:08:44 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path1: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:45 INFO  : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path2: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:46 NOTICE: checking initFs Local file system at /tmp/050541ku/initdir/test_volatile-jotafuz4
2025/11/17 05:08:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:46 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path1: Waiting for checks to finish
2025/11/17 05:08:46 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path1: Waiting for transfers to finish
2025/11/17 05:08:47 DEBUG : file2.txt: size = 0 OK
2025/11/17 05:08:47 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:47 INFO  : file2.txt: Copied (new)
2025/11/17 05:08:47 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:08:47 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:47 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:47 DEBUG : file4.txt: size = 0 OK
2025/11/17 05:08:47 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:47 INFO  : file4.txt: Copied (new)
2025/11/17 05:08:48 DEBUG : file5.txt: size = 0 OK
2025/11/17 05:08:48 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:48 INFO  : file5.txt: Copied (new)
2025/11/17 05:08:48 DEBUG : file3.txt: size = 0 OK
2025/11/17 05:08:48 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:48 INFO  : file3.txt: Copied (new)
2025/11/17 05:08:48 DEBUG : file6.txt: size = 0 OK
2025/11/17 05:08:48 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:48 INFO  : file6.txt: Copied (new)
2025/11/17 05:08:48 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:08:48 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:08:48 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:48 DEBUG : file8.txt: size = 0 OK
2025/11/17 05:08:48 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:48 INFO  : file8.txt: Copied (new)
2025/11/17 05:08:48 DEBUG : file7.txt: size = 0 OK
2025/11/17 05:08:48 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:48 INFO  : file7.txt: Copied (new)
2025/11/17 05:08:48 NOTICE: checking Path1 B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path1
2025/11/17 05:08:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/17 05:08:49 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path2: Waiting for checks to finish
2025/11/17 05:08:49 DEBUG : B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path2: Waiting for transfers to finish
2025/11/17 05:08:49 DEBUG : file1.txt: size = 0 OK
2025/11/17 05:08:49 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:49 INFO  : file1.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file3.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file3.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file4.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file4.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file2.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file2.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file5.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file5.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file6.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file6.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : file7.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file7.txt: Copied (new)
2025/11/17 05:08:50 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/17 05:08:50 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/17 05:08:50 INFO  : RCLONE_TEST: Copied (new)
2025/11/17 05:08:50 DEBUG : file8.txt: size = 0 OK
2025/11/17 05:08:50 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/17 05:08:50 INFO  : file8.txt: Copied (new)
2025/11/17 05:08:50 NOTICE: checking path2 B2 bucket rclone-test-picoxus5buxu path 050541ku/volatile/path2
2025/11/17 05:08:51 NOTICE: (01)  : test volatile
2025/11/17 05:08:51 NOTICE: (02)  : test initial bisync
2025/11/17 05:08:51 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/11/17 05:08:51 DEBUG : B2 bucket rclone-test-picoxus5buxu: Purge remote
2025/11/17 05:08:51 INFO  : B2 bucket rclone-test-picoxus5buxu: cleaning bucket "rclone-test-picoxus5buxu" of all files
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10067b60bbc07310_d20251117_m050839_c001_v0001146_t0043_u01763356119740")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f105368f327be18dd_d20251117_m050839_c001_v0001093_t0052_u01763356119170")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117a768dbedf53b4_d20251117_m050839_c001_v0001180_t0009_u01763356119355")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1140ad1ee7ab5379_d20251117_m050839_c001_v0001037_t0038_u01763356119550")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117a768dbedf53ba_d20251117_m050839_c001_v0001180_t0006_u01763356119556")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117a768dbedf53be_d20251117_m050839_c001_v0001180_t0024_u01763356119757")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f105368f327be18df_d20251117_m050839_c001_v0001093_t0045_u01763356119768")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1140ad1ee7ab537b_d20251117_m050839_c001_v0001037_t0008_u01763356119938")
2025/11/17 05:08:51 DEBUG : 050541ku/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f119e8ff3b77176fe_d20251117_m050841_c001_v0001136_t0049_u01763356121870")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1157cec41d9ece15_d20251117_m050841_c001_v0001178_t0043_u01763356121300")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10779f9b5cde321d_d20251117_m050841_c001_v0001032_t0003_u01763356121487")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f111e69fbe2e8e36f_d20251117_m050841_c001_v0001105_t0013_u01763356121685")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1157cec41d9ece19_d20251117_m050841_c001_v0001178_t0036_u01763356121577")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1157cec41d9ece1b_d20251117_m050841_c001_v0001178_t0003_u01763356121889")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f10779f9b5cde321f_d20251117_m050841_c001_v0001032_t0058_u01763356121906")
2025/11/17 05:08:52 DEBUG : 050541ku/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1157cec41d9ece1d_d20251117_m050842_c001_v0001178_t0021_u01763356122104")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f101eeff26a98431e_d20251117_m050847_c001_v0001157_t0024_u01763356127964")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11840c687c6997ab_d20251117_m050847_c001_v0001154_t0020_u01763356127401")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102b742f9e8381fd_d20251117_m050847_c001_v0001179_t0045_u01763356127592")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1014810b5f2c0b4f_d20251117_m050847_c001_v0001120_t0029_u01763356127774")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102b742f9e8381ff_d20251117_m050847_c001_v0001179_t0050_u01763356127835")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11840c687c6997ad_d20251117_m050847_c001_v0001154_t0007_u01763356127928")
2025/11/17 05:08:52 DEBUG : 050541ku/volatile/path1/file6.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f102b742f9e838201_d20251117_m050848_c001_v0001179_t0054_u01763356128044")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path1/file7.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f11840c687c6997b1_d20251117_m050848_c001_v0001154_t0021_u01763356128252")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path1/file8.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1014810b5f2c0b57_d20251117_m050848_c001_v0001120_t0052_u01763356128273")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/RCLONE_TEST: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f1001dc2fec750f29_d20251117_m050850_c001_v0001150_t0007_u01763356130255")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file1.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117ad6f54cad7677_d20251117_m050849_c001_v0001172_t0005_u01763356129685")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file2.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f104b9af96b32ec7c_d20251117_m050850_c001_v0001157_t0043_u01763356130072")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file3.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f119400a63c596246_d20251117_m050849_c001_v0001091_t0003_u01763356129874")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file4.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117ad6f54cad7679_d20251117_m050849_c001_v0001172_t0056_u01763356129980")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file5.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f119400a63c596248_d20251117_m050850_c001_v0001091_t0037_u01763356130188")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file6.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f117ad6f54cad767b_d20251117_m050850_c001_v0001172_t0018_u01763356130338")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file7.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f104b9af96b32ec80_d20251117_m050850_c001_v0001157_t0047_u01763356130404")
2025/11/17 05:08:53 DEBUG : 050541ku/volatile/path2/file8.txt: Deleting (id "4_z7f926d13304d9b0b9fa80e1b_f119400a63c59624a_d20251117_m050850_c001_v0001091_t0015_u01763356130602")
--- PASS: TestBisyncRemoteRemote (193.19s)
    --- PASS: TestBisyncRemoteRemote/basic (39.81s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (132.78s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.22s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.71s)
PASS
2025/11/17 05:08:54 DEBUG : B2 bucket rclone-test-vucivaz6dofi: Purge remote
2025/11/17 05:08:54 INFO  : B2 bucket rclone-test-vucivaz6dofi: cleaning bucket "rclone-test-vucivaz6dofi" of all files
2025/11/17 05:08:55 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^check_filename$|^TestBisyncRemoteRemote$/^(basic|resync_modes|rmdirs|volatile)$'" - Finished OK in 3m52.402176594s (try 2/5)