"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^all_changed$|^TestBisyncRemoteLocal$/^ignorelistingchecksum$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/08/23 04:53:28 DEBUG : Creating backend with remote "TestB2:rclone-test-dujofey2givi"
2025/08/23 04:53:28 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/23 04:53:29 DEBUG : Creating backend with remote "/tmp/rclone385702791"
=== RUN   TestBisyncRemoteLocal
2025/08/23 04:53:29 DEBUG : Creating backend with remote "TestB2:rclone-test-ciqedif9zada"
2025/08/23 04:53:29 NOTICE: remote: TestB2:rclone-test-ciqedif9zada
=== RUN   TestBisyncRemoteLocal/ignorelistingchecksum
2025/08/23 04:53:29 DEBUG : Creating backend with remote "TestB2:rclone-test-ciqedif9zada/045329xo"
2025/08/23 04:53:30 INFO  : path1: Making directory
2025/08/23 04:53:33 DEBUG : Creating backend with remote "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1"
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/tmp/045329xo"
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/tmp/045329xo/ignorelistingchecksum/path2"
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ignorelistingchecksum/initial"
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/tmp/045329xo/initdir/test_ignorelistingchecksum-rutoqib5"
2025/08/23 04:53:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : subdir: Making directory with metadata
2025/08/23 04:53:34 INFO  : subdir: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2025/08/23 04:53:34 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/23 04:53:34 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/23 04:53:34 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/23 04:53:34 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : Local file system at /tmp/045329xo/initdir/test_ignorelistingchecksum-rutoqib5: Waiting for checks to finish
2025/08/23 04:53:34 DEBUG : Local file system at /tmp/045329xo/initdir/test_ignorelistingchecksum-rutoqib5: Waiting for transfers to finish
2025/08/23 04:53:34 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:53:34 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/23 04:53:34 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/23 04:53:34 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:53:34 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:53:34 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/23 04:53:34 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/23 04:53:34 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:53:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:34 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:53:34 INFO  : file1.txt: Copied (new)
2025/08/23 04:53:34 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_ignorelistingchecksum/modfiles"
2025/08/23 04:53:34 DEBUG : Creating backend with remote "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1"
2025/08/23 04:53:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:34 DEBUG : Local file system at /tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1: Waiting for checks to finish
2025/08/23 04:53:34 DEBUG : Local file system at /tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1: Waiting for transfers to finish
2025/08/23 04:53:34 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:53:34 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/08/23 04:53:34 INFO  : file1.txt: Copied (new)
2025/08/23 04:53:34 INFO  : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: cleaning bucket "rclone-test-ciqedif9zada" of all files
2025/08/23 04:53:35 DEBUG : Waiting for deletions to finish
2025/08/23 04:53:35 ERROR : error listing: directory not found
2025/08/23 04:53:35 ERROR : Local file system at /tmp/045329xo/ignorelistingchecksum/path2: Failed to list "": directory not found
2025/08/23 04:53:35 DEBUG : removing 1 level 0 directories
2025/08/23 04:53:35 INFO  : Local file system at /tmp/045329xo/ignorelistingchecksum/path2: Removing directory
2025/08/23 04:53:35 ERROR : Failed to rmdir: stat /tmp/045329xo/ignorelistingchecksum/path2: no such file or directory
2025/08/23 04:53:35 ERROR : error listing: directory not found
2025/08/23 04:53:35 NOTICE: checking initFs Local file system at /tmp/045329xo/initdir/test_ignorelistingchecksum-rutoqib5
2025/08/23 04:53:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 INFO  : subdir: Making directory
2025/08/23 04:53:35 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:35 DEBUG : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: Waiting for checks to finish
2025/08/23 04:53:35 DEBUG : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: Waiting for transfers to finish
2025/08/23 04:53:36 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:36 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:53:36 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:36 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:53:36 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:36 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:53:36 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:36 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:53:36 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:53:36 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:53:37 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:37 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:37 INFO  : file1.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:53:37 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:53:37 NOTICE: checking Path1 B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1
2025/08/23 04:53:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : subdir: Making directory with metadata
2025/08/23 04:53:37 INFO  : subdir: Made directory with metadata (mtime=2024-01-25T05:00:02.874776175Z)
2025/08/23 04:53:37 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/23 04:53:37 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:53:37 DEBUG : Local file system at /tmp/045329xo/ignorelistingchecksum/path2: Waiting for checks to finish
2025/08/23 04:53:37 DEBUG : Local file system at /tmp/045329xo/ignorelistingchecksum/path2: Waiting for transfers to finish
2025/08/23 04:53:37 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/23 04:53:37 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:53:37 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/23 04:53:37 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:53:37 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:53:37 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/23 04:53:37 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:53:37 INFO  : file1.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/23 04:53:37 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:53:37 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/23 04:53:37 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:53:37 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/23 04:53:37 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:53:37 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:53:37 NOTICE: checking path2 Local file system at /tmp/045329xo/ignorelistingchecksum/path2
2025/08/23 04:53:37 NOTICE: (01)  : test basic
2025/08/23 04:53:37 NOTICE: (02)  : test initial bisync
2025/08/23 04:53:37 NOTICE: (03)  : bisync resync
2025/08/23 04:53:40 DEBUG : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:53:40 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:53:40 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:53:40 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:53:40 INFO  : Synching Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" with Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:40 INFO  : Copying Path2 files to Path1
2025/08/23 04:53:40 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:53:40 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:53:41 INFO  : Resync updating listings
2025/08/23 04:53:41 INFO  : Validating listings for Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" vs Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:41 INFO  : Bisync successful
2025/08/23 04:53:41 NOTICE: (04)  : bisync resync ignore-listing-checksum
2025/08/23 04:53:44 DEBUG : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:53:44 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:53:44 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:53:44 INFO  : Synching Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" with Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:44 INFO  : Copying Path2 files to Path1
2025/08/23 04:53:44 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:53:44 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:53:45 INFO  : Resync updating listings
2025/08/23 04:53:45 INFO  : Validating listings for Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" vs Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:45 INFO  : Bisync successful
2025/08/23 04:53:45 NOTICE: (05)  : test place newer files on both paths
2025/08/23 04:53:45 NOTICE: (06)  : touch-copy 2001-01-02 /tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt /tmp/045329xo/ignorelistingchecksum/path2/
2025/08/23 04:53:45 DEBUG : Creating backend with remote "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/"
2025/08/23 04:53:45 DEBUG : fs cache: renaming cache item "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/" to be canonical "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1"
2025/08/23 04:53:45 DEBUG : file1.txt: Set modification time 2001-01-02
2025/08/23 04:53:45 DEBUG : copyFile "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt" to "/tmp/045329xo/ignorelistingchecksum/path2/" as ""
2025/08/23 04:53:45 DEBUG : Creating backend with remote "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt"
2025/08/23 04:53:45 DEBUG : Creating backend with remote "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:45 DEBUG : operations.CopyFile "file1.txt" to "Local file system at /tmp/045329xo/ignorelistingchecksum/path2" as "file1.txt"
2025/08/23 04:53:45 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:53:45 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:53:45 DEBUG : file1.txt.925c801f.partial: renamed to: file1.txt
2025/08/23 04:53:45 INFO  : file1.txt: Copied (replaced existing)
2025/08/23 04:53:45 NOTICE: (07)  : copy-as /tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/subdir file20.txt
2025/08/23 04:53:45 DEBUG : copyFile "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt" to "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/subdir" as "file20.txt"
2025/08/23 04:53:45 DEBUG : Creating backend with remote "/tmp/045329xo/datadir/test_ignorelistingchecksum-dalamon1/file1.txt"
2025/08/23 04:53:45 DEBUG : Creating backend with remote "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/subdir"
2025/08/23 04:53:46 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1/subdir" as "file20.txt"
2025/08/23 04:53:46 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:53:48 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:53:48 INFO  : file1.txt: Copied (replaced existing) to: file20.txt
2025/08/23 04:53:48 NOTICE: (08)  : test bisync run
2025/08/23 04:53:48 NOTICE: (09)  : bisync ignore-listing-checksum
2025/08/23 04:53:52 DEBUG : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:53:52 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:53:52 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:53:52 INFO  : Synching Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" with Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:52 INFO  : Building Path1 and Path2 listings
2025/08/23 04:53:52 INFO  : Path1 checking for diffs
2025/08/23 04:53:52 INFO  : - Path1    File changed: size (larger), time (newer) - subdir/file20.txt
2025/08/23 04:53:52 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/08/23 04:53:52 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/08/23 04:53:52 INFO  : Path2 checking for diffs
2025/08/23 04:53:52 INFO  : - Path2    File changed: size (larger), time (newer) - file1.txt
2025/08/23 04:53:52 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/08/23 04:53:52 INFO  : (Modified:    1 newer,    0 older,    1 larger,    0 smaller)
2025/08/23 04:53:52 INFO  : Applying changes
2025/08/23 04:53:52 INFO  : - Path1    Queue copy to Path2       - /tmp/045329xo/ignorelistingchecksum/path2/subdir/file20.txt
2025/08/23 04:53:52 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/file1.txt
2025/08/23 04:53:52 INFO  : - Path2    Do queued copies to                - Path1
2025/08/23 04:53:53 INFO  : file1.txt: Copied (replaced existing)
2025/08/23 04:53:53 INFO  : - Path1    Do queued copies to                - Path2
2025/08/23 04:53:54 INFO  : subdir/file20.txt: Copied (replaced existing)
2025/08/23 04:53:54 INFO  : Updating listings
2025/08/23 04:53:54 INFO  : Validating listings for Path1 "TestB2:rclone-test-ciqedif9zada/045329xo/ignorelistingchecksum/path1/" vs Path2 "/tmp/045329xo/ignorelistingchecksum/path2/"
2025/08/23 04:53:54 INFO  : Bisync successful
2025/08/23 04:53:54 INFO  : B2 bucket rclone-test-ciqedif9zada path 045329xo/ignorelistingchecksum/path1: cleaning bucket "rclone-test-ciqedif9zada" of all files
2025/08/23 04:53:54 DEBUG : RCLONE_TEST: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f110f93fb79723b55_d20250823_m045336_c001_v0001161_t0027_u01755924816716")
2025/08/23 04:53:54 DEBUG : file1.copy1.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f1011e694eeaaae64_d20250823_m045336_c001_v0001099_t0033_u01755924816141")
2025/08/23 04:53:54 DEBUG : file1.copy2.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106ba9d53453ef2e_d20250823_m045336_c001_v0001161_t0053_u01755924816525")
2025/08/23 04:53:54 DEBUG : file1.copy3.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106b672c980e1588_d20250823_m045336_c001_v0001040_t0055_u01755924816330")
2025/08/23 04:53:54 DEBUG : file1.copy4.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f1011e694eeaaae68_d20250823_m045336_c001_v0001099_t0040_u01755924816496")
2025/08/23 04:53:54 DEBUG : file1.copy5.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106b672c980e158a_d20250823_m045336_c001_v0001040_t0058_u01755924816741")
2025/08/23 04:53:54 DEBUG : file1.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106ba9d53453ef96_d20250823_m045353_c001_v0001161_t0027_u01755924833591")
2025/08/23 04:53:54 DEBUG : file1.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f1011e694eeaaae6a_d20250823_m045336_c001_v0001099_t0004_u01755924816868")
2025/08/23 04:53:54 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f4216375bb914d59c_d20250823_m045352_c001_v7007000_t0000_u01755924832496")
2025/08/23 04:53:54 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f101a7ca5d92da676_d20250823_m045351_c001_v0001042_t0059_u01755924831147")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f1011e694eeaaae88_d20250823_m045348_c001_v0001099_t0050_u01755924828906")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f414ceec2a18ff5f9_d20250823_m045344_c001_v7007000_t0000_u01755924824526")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f112bbf20cc61a58c_d20250823_m045343_c001_v0001136_t0018_u01755924823156")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106b672c980e159c_d20250823_m045341_c001_v0001040_t0000_u01755924821426")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f414ceec2a18ff5d2_d20250823_m045340_c001_v7007000_t0000_u01755924820448")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f1146af8798609dea_d20250823_m045339_c001_v0001182_t0047_u01755924819248")
2025/08/23 04:53:55 DEBUG : modtime_write_test: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f110f93fb79723b61_d20250823_m045337_c001_v0001161_t0053_u01755924817653")
2025/08/23 04:53:55 DEBUG : subdir/file20.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f113c632a2dfee70a_d20250823_m045348_c001_v0001043_t0001_u01755924828079")
2025/08/23 04:53:55 DEBUG : subdir/file20.txt: Deleting (id "4_z2ff24de3406ddb5b9f880e1b_f106ba9d53453ef30_d20250823_m045336_c001_v0001161_t0010_u01755924816879")
2025/08/23 04:53:55 DEBUG : Waiting for deletions to finish
2025/08/23 04:53:55 INFO  : file1.copy1.txt: Deleted
2025/08/23 04:53:55 INFO  : file1.copy2.txt: Deleted
2025/08/23 04:53:55 INFO  : RCLONE_TEST: Deleted
2025/08/23 04:53:55 INFO  : file1.copy3.txt: Deleted
2025/08/23 04:53:55 INFO  : file1.copy4.txt: Deleted
2025/08/23 04:53:55 INFO  : file1.copy5.txt: Deleted
2025/08/23 04:53:55 INFO  : file1.txt: Deleted
2025/08/23 04:53:55 INFO  : subdir/file20.txt: Deleted
2025/08/23 04:53:55 DEBUG : removing 1 level 1 directories
2025/08/23 04:53:55 INFO  : subdir: Removing directory
2025/08/23 04:53:55 DEBUG : removing 1 level 0 directories
2025/08/23 04:53:55 INFO  : Local file system at /tmp/045329xo/ignorelistingchecksum/path2: Removing directory
    bisync_test.go:596: TEST ignorelistingchecksum PASSED
2025/08/23 04:53:55 DEBUG : B2 bucket rclone-test-ciqedif9zada: Purge remote
2025/08/23 04:53:55 INFO  : B2 bucket rclone-test-ciqedif9zada: cleaning bucket "rclone-test-ciqedif9zada" of all files
--- PASS: TestBisyncRemoteLocal (28.75s)
    --- PASS: TestBisyncRemoteLocal/ignorelistingchecksum (26.21s)
=== RUN   TestBisyncLocalRemote
2025/08/23 04:53:57 DEBUG : Creating backend with remote "TestB2:rclone-test-gidelep5nili"
2025/08/23 04:53:58 NOTICE: remote: TestB2:rclone-test-gidelep5nili
=== RUN   TestBisyncLocalRemote/all_changed
2025/08/23 04:53:58 DEBUG : Creating backend with remote "/tmp/045358go"
2025/08/23 04:53:58 DEBUG : Creating backend with remote "/tmp/045358go/all_changed/path1"
2025/08/23 04:53:58 DEBUG : Creating backend with remote "TestB2:rclone-test-gidelep5nili/045358go"
2025/08/23 04:53:59 INFO  : path2: Making directory
2025/08/23 04:54:00 DEBUG : Creating backend with remote "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2"
2025/08/23 04:54:01 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial"
2025/08/23 04:54:01 DEBUG : Creating backend with remote "/tmp/045358go/initdir/test_all_changed-qojocec0"
2025/08/23 04:54:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : subdir: Making directory with metadata
2025/08/23 04:54:01 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/23 04:54:01 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/23 04:54:01 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:01 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : Local file system at /tmp/045358go/initdir/test_all_changed-qojocec0: Waiting for checks to finish
2025/08/23 04:54:01 DEBUG : Local file system at /tmp/045358go/initdir/test_all_changed-qojocec0: Waiting for transfers to finish
2025/08/23 04:54:01 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/23 04:54:01 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/23 04:54:01 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/23 04:54:01 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:54:01 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:54:01 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:01 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/23 04:54:01 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/23 04:54:01 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/23 04:54:01 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:54:01 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:54:01 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:01 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:01 DEBUG : Waiting for deletions to finish
2025/08/23 04:54:01 ERROR : error listing: directory not found
2025/08/23 04:54:01 ERROR : Local file system at /tmp/045358go/all_changed/path1: Failed to list "": directory not found
2025/08/23 04:54:01 DEBUG : removing 1 level 0 directories
2025/08/23 04:54:01 INFO  : Local file system at /tmp/045358go/all_changed/path1: Removing directory
2025/08/23 04:54:01 ERROR : Failed to rmdir: stat /tmp/045358go/all_changed/path1: no such file or directory
2025/08/23 04:54:01 INFO  : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: cleaning bucket "rclone-test-gidelep5nili" of all files
2025/08/23 04:54:02 ERROR : error listing: directory not found
2025/08/23 04:54:02 NOTICE: checking initFs Local file system at /tmp/045358go/initdir/test_all_changed-qojocec0
2025/08/23 04:54:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 DEBUG : subdir: Making directory with metadata
2025/08/23 04:54:02 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/23 04:54:02 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/23 04:54:02 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/23 04:54:02 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/23 04:54:02 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/23 04:54:02 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:54:02 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:02 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/23 04:54:02 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : Local file system at /tmp/045358go/all_changed/path1: Waiting for checks to finish
2025/08/23 04:54:02 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/23 04:54:02 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : Local file system at /tmp/045358go/all_changed/path1: Waiting for transfers to finish
2025/08/23 04:54:02 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:02 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/23 04:54:02 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:54:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:54:02 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:54:02 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:02 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:02 NOTICE: checking Path1 Local file system at /tmp/045358go/all_changed/path1
2025/08/23 04:54:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 INFO  : subdir: Making directory
2025/08/23 04:54:03 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:03 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Waiting for checks to finish
2025/08/23 04:54:03 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Waiting for transfers to finish
2025/08/23 04:54:03 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:03 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:04 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:54:04 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:54:04 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:04 NOTICE: checking path2 B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2
2025/08/23 04:54:04 NOTICE: (01)  : test all-changed
2025/08/23 04:54:04 NOTICE: (02)  : test initial bisync
2025/08/23 04:54:04 NOTICE: (03)  : bisync resync
2025/08/23 04:54:07 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:07 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:07 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:54:07 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:54:07 INFO  : Synching Path1 "/tmp/045358go/all_changed/path1/" with Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:07 INFO  : Copying Path2 files to Path1
2025/08/23 04:54:07 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:54:07 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:54:08 INFO  : Resync updating listings
2025/08/23 04:54:08 INFO  : Validating listings for Path1 "/tmp/045358go/all_changed/path1/" vs Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:08 INFO  : Bisync successful
2025/08/23 04:54:08 NOTICE: (04)  : test change timestamp on all files except RCLONE_TEST
2025/08/23 04:54:08 NOTICE: (05)  : touch-glob 2005-01-02 /tmp/045358go/all_changed/path1/ file*
2025/08/23 04:54:08 DEBUG : Creating backend with remote "/tmp/045358go/all_changed/path1/"
2025/08/23 04:54:08 DEBUG : fs cache: renaming cache item "/tmp/045358go/all_changed/path1/" to be canonical "/tmp/045358go/all_changed/path1"
2025/08/23 04:54:08 DEBUG : file1.copy1.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 DEBUG : file1.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 DEBUG : file1.copy5.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 DEBUG : file1.copy4.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 DEBUG : file1.copy3.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 DEBUG : file1.copy2.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 NOTICE: (06)  : touch-glob 2005-01-02 /tmp/045358go/all_changed/path1/subdir file*
2025/08/23 04:54:08 DEBUG : Creating backend with remote "/tmp/045358go/all_changed/path1/subdir"
2025/08/23 04:54:08 DEBUG : file20.txt: Set modification time 2005-01-02
2025/08/23 04:54:08 NOTICE: (07)  : test sync should pass
2025/08/23 04:54:08 NOTICE: (08)  : bisync
2025/08/23 04:54:11 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:11 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:54:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:54:11 INFO  : Synching Path1 "/tmp/045358go/all_changed/path1/" with Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:11 INFO  : Building Path1 and Path2 listings
2025/08/23 04:54:11 INFO  : Path1 checking for diffs
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.copy1.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.copy2.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.copy3.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.copy4.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.copy5.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - file1.txt
2025/08/23 04:54:11 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2025/08/23 04:54:11 INFO  : Path1:    7 changes:    0 new,    7 modified,    0 deleted
2025/08/23 04:54:11 INFO  : (Modified:    7 newer,    0 older)
2025/08/23 04:54:11 INFO  : Path2 checking for diffs
2025/08/23 04:54:11 INFO  : Applying changes
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy1.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy2.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy3.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy4.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy5.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.txt
2025/08/23 04:54:11 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/subdir/file20.txt
2025/08/23 04:54:11 INFO  : - Path1    Do queued copies to                - Path2
2025/08/23 04:54:12 INFO  : file1.copy1.txt: Updated modification time in destination
2025/08/23 04:54:12 INFO  : file1.copy5.txt: Updated modification time in destination
2025/08/23 04:54:12 INFO  : file1.copy4.txt: Updated modification time in destination
2025/08/23 04:54:12 INFO  : file1.copy2.txt: Updated modification time in destination
2025/08/23 04:54:13 INFO  : file1.copy3.txt: Updated modification time in destination
2025/08/23 04:54:13 INFO  : file1.txt: Updated modification time in destination
2025/08/23 04:54:13 INFO  : subdir/file20.txt: Updated modification time in destination
2025/08/23 04:54:13 INFO  : Updating listings
2025/08/23 04:54:13 INFO  : Validating listings for Path1 "/tmp/045358go/all_changed/path1/" vs Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:13 INFO  : Bisync successful
2025/08/23 04:54:13 NOTICE: (09)  : test change timestamp on all files including RCLONE_TEST
2025/08/23 04:54:13 NOTICE: (10)  : touch-glob 2004-01-02 /tmp/045358go/all_changed/path1/ *
2025/08/23 04:54:13 DEBUG : fs cache: switching user supplied name "/tmp/045358go/all_changed/path1/" for canonical name "/tmp/045358go/all_changed/path1"
2025/08/23 04:54:13 DEBUG : file1.copy1.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : file1.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : file1.copy5.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : RCLONE_TEST: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : file1.copy4.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : file1.copy3.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 DEBUG : file1.copy2.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 NOTICE: (11)  : touch-glob 2004-01-02 /tmp/045358go/all_changed/path1/subdir *
2025/08/23 04:54:13 DEBUG : file20.txt: Set modification time 2004-01-02
2025/08/23 04:54:13 NOTICE: (12)  : test sync should fail
2025/08/23 04:54:13 NOTICE: (13)  : bisync
2025/08/23 04:54:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:16 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:16 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:54: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/08/23 04:54:16 INFO  : Synching Path1 "/tmp/045358go/all_changed/path1/" with Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:16 INFO  : Building Path1 and Path2 listings
2025/08/23 04:54:16 INFO  : Path1 checking for diffs
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (newer) - RCLONE_TEST
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.copy1.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.copy2.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.copy3.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.copy4.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.copy5.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - file1.txt
2025/08/23 04:54:16 INFO  : - Path1    File changed: time (older) - subdir/file20.txt
2025/08/23 04:54:16 INFO  : Path1:    8 changes:    0 new,    8 modified,    0 deleted
2025/08/23 04:54:16 INFO  : (Modified:    1 newer,    7 older)
2025/08/23 04:54:16 INFO  : Path2 checking for diffs
2025/08/23 04:54:16 ERROR : Safety abort: all files were changed on Path1 "/tmp/045358go/all_changed/path1/". Run with --force if desired.
2025/08/23 04:54:16 NOTICE: Bisync aborted. Please try again.
2025/08/23 04:54:16 NOTICE: Bisync error: all files were changed
2025/08/23 04:54:16 NOTICE: (14)  : test sync with force should pass
2025/08/23 04:54:16 NOTICE: (15)  : bisync force
2025/08/23 04:54:20 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:54:20 DEBUG : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:20 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:54:20 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/08/23 04:54:20 INFO  : Synching Path1 "/tmp/045358go/all_changed/path1/" with Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:20 INFO  : Building Path1 and Path2 listings
2025/08/23 04:54:20 INFO  : Path1 checking for diffs
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (newer) - RCLONE_TEST
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.copy1.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.copy2.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.copy3.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.copy4.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.copy5.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - file1.txt
2025/08/23 04:54:20 INFO  : - Path1    File changed: time (older) - subdir/file20.txt
2025/08/23 04:54:20 INFO  : Path1:    8 changes:    0 new,    8 modified,    0 deleted
2025/08/23 04:54:20 INFO  : (Modified:    1 newer,    7 older)
2025/08/23 04:54:20 INFO  : Path2 checking for diffs
2025/08/23 04:54:20 INFO  : Applying changes
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/RCLONE_TEST
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy1.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy2.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy3.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy4.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.copy5.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/file1.txt
2025/08/23 04:54:20 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/subdir/file20.txt
2025/08/23 04:54:20 INFO  : - Path1    Do queued copies to                - Path2
2025/08/23 04:54:21 INFO  : file1.copy1.txt: Updated modification time in destination
2025/08/23 04:54:21 INFO  : file1.copy2.txt: Updated modification time in destination
2025/08/23 04:54:21 INFO  : file1.copy3.txt: Updated modification time in destination
2025/08/23 04:54:21 INFO  : file1.copy4.txt: Updated modification time in destination
2025/08/23 04:54:21 INFO  : file1.copy5.txt: Updated modification time in destination
2025/08/23 04:54:21 INFO  : file1.txt: Updated modification time in destination
2025/08/23 04:54:22 INFO  : subdir/file20.txt: Updated modification time in destination
2025/08/23 04:54:22 INFO  : RCLONE_TEST: Updated modification time in destination
2025/08/23 04:54:22 INFO  : Updating listings
2025/08/23 04:54:22 INFO  : Validating listings for Path1 "/tmp/045358go/all_changed/path1/" vs Path2 "TestB2:rclone-test-gidelep5nili/045358go/all_changed/path2/"
2025/08/23 04:54:22 INFO  : Bisync successful
2025/08/23 04:54:22 DEBUG : Waiting for deletions to finish
2025/08/23 04:54:22 INFO  : RCLONE_TEST: Deleted
2025/08/23 04:54:22 INFO  : file1.copy1.txt: Deleted
2025/08/23 04:54:22 INFO  : file1.copy2.txt: Deleted
2025/08/23 04:54:22 INFO  : file1.copy3.txt: Deleted
2025/08/23 04:54:22 INFO  : file1.copy4.txt: Deleted
2025/08/23 04:54:22 INFO  : file1.copy5.txt: Deleted
2025/08/23 04:54:22 INFO  : file1.txt: Deleted
2025/08/23 04:54:22 INFO  : subdir/file20.txt: Deleted
2025/08/23 04:54:22 DEBUG : removing 1 level 1 directories
2025/08/23 04:54:22 INFO  : subdir: Removing directory
2025/08/23 04:54:22 DEBUG : removing 1 level 0 directories
2025/08/23 04:54:22 INFO  : Local file system at /tmp/045358go/all_changed/path1: Removing directory
2025/08/23 04:54:22 INFO  : B2 bucket rclone-test-gidelep5nili path 045358go/all_changed/path2: cleaning bucket "rclone-test-gidelep5nili" of all files
2025/08/23 04:54:23 DEBUG : RCLONE_TEST: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f10369cb622733cc4_d20250823_m045422_c001_v0001130_t0059_u01755924862633")
2025/08/23 04:54:23 DEBUG : RCLONE_TEST: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1146b7e19a2b1b0f_d20250823_m045404_c001_v0001146_t0048_u01755924844321")
2025/08/23 04:54:23 DEBUG : file1.copy1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1087ff179cffb097_d20250823_m045421_c001_v0001176_t0014_u01755924861242")
2025/08/23 04:54:23 DEBUG : file1.copy1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f10571c0732d1c52e_d20250823_m045412_c001_v0001137_t0005_u01755924852057")
2025/08/23 04:54:23 DEBUG : file1.copy1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f106e54e751d2ae55_d20250823_m045403_c001_v0001182_t0051_u01755924843745")
2025/08/23 04:54:23 DEBUG : file1.copy2.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f112b0987244cd0da_d20250823_m045421_c001_v0001157_t0001_u01755924861252")
2025/08/23 04:54:23 DEBUG : file1.copy2.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f111ff79822458777_d20250823_m045412_c001_v0001137_t0054_u01755924852801")
2025/08/23 04:54:23 DEBUG : file1.copy2.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f117be788f99fa132_d20250823_m045403_c001_v0001182_t0028_u01755924843944")
2025/08/23 04:54:23 DEBUG : file1.copy3.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1065907c7ad40a43_d20250823_m045421_c001_v0001184_t0013_u01755924861607")
2025/08/23 04:54:23 DEBUG : file1.copy3.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f11479177374cba99_d20250823_m045412_c001_v0001109_t0016_u01755924852509")
2025/08/23 04:54:23 DEBUG : file1.copy3.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1146af8798609e7e_d20250823_m045404_c001_v0001182_t0032_u01755924844124")
2025/08/23 04:54:23 DEBUG : file1.copy4.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f117c237a7dcd9897_d20250823_m045421_c001_v0001038_t0028_u01755924861496")
2025/08/23 04:54:23 DEBUG : file1.copy4.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1072777017679748_d20250823_m045412_c001_v0001176_t0039_u01755924852803")
2025/08/23 04:54:23 DEBUG : file1.copy4.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f106e54e751d2ae57_d20250823_m045403_c001_v0001182_t0021_u01755924843955")
2025/08/23 04:54:23 DEBUG : file1.copy5.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f116cc5fd0cf101d1_d20250823_m045421_c001_v0001145_t0027_u01755924861622")
2025/08/23 04:54:23 DEBUG : file1.copy5.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f100259000da2577c_d20250823_m045412_c001_v0001179_t0024_u01755924852634")
2025/08/23 04:54:23 DEBUG : file1.copy5.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f106e54e751d2ae5b_d20250823_m045404_c001_v0001182_t0013_u01755924844148")
2025/08/23 04:54:23 DEBUG : file1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1020be2657df42b7_d20250823_m045421_c001_v0001178_t0053_u01755924861838")
2025/08/23 04:54:23 DEBUG : file1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f11097d2dcfa6bfef_d20250823_m045412_c001_v0001036_t0032_u01755924852791")
2025/08/23 04:54:23 DEBUG : file1.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f117be788f99fa134_d20250823_m045404_c001_v0001182_t0016_u01755924844161")
2025/08/23 04:54:23 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f406aa4d65be53b01_d20250823_m045420_c001_v7007000_t0000_u01755924860263")
2025/08/23 04:54:23 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f108d1746fef566e3_d20250823_m045418_c001_v0001044_t0050_u01755924858844")
2025/08/23 04:54:23 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1146b7e19a2b1b39_d20250823_m045417_c001_v0001146_t0056_u01755924857333")
2025/08/23 04:54:23 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f406aa4d65be53ae0_d20250823_m045416_c001_v7007000_t0000_u01755924856408")
2025/08/23 04:54:23 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f110cf67adc597636_d20250823_m045415_c001_v0001113_t0037_u01755924855136")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1146af8798609eac_d20250823_m045413_c001_v0001182_t0051_u01755924853722")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f410b0eeab72df5d6_d20250823_m045411_c001_v7007000_t0000_u01755924851081")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1085f6a6701e2a76_d20250823_m045409_c001_v0001181_t0043_u01755924849872")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f117be788f99fa152_d20250823_m045408_c001_v0001182_t0057_u01755924848458")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f410b0eeab72df5c2_d20250823_m045407_c001_v7007000_t0000_u01755924847523")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f10496e5d95419269_d20250823_m045406_c001_v0001183_t0008_u01755924846316")
2025/08/23 04:54:24 DEBUG : modtime_write_test: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f106e54e751d2ae5f_d20250823_m045404_c001_v0001182_t0032_u01755924844887")
2025/08/23 04:54:24 DEBUG : subdir/file20.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f10304052c45cfdfa_d20250823_m045421_c001_v0001104_t0027_u01755924861791")
2025/08/23 04:54:24 DEBUG : subdir/file20.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f10522aa768162ea0_d20250823_m045412_c001_v0001117_t0051_u01755924852828")
2025/08/23 04:54:24 DEBUG : subdir/file20.txt: Deleting (id "4_z8f32bde3406ddb5b9f880e1b_f1146af8798609e80_d20250823_m045404_c001_v0001182_t0046_u01755924844322")
    bisync_test.go:596: TEST all_changed PASSED
2025/08/23 04:54:24 DEBUG : B2 bucket rclone-test-gidelep5nili: Purge remote
2025/08/23 04:54:24 INFO  : B2 bucket rclone-test-gidelep5nili: cleaning bucket "rclone-test-gidelep5nili" of all files
--- PASS: TestBisyncLocalRemote (31.20s)
    --- PASS: TestBisyncLocalRemote/all_changed (26.42s)
=== RUN   TestBisyncRemoteRemote
2025/08/23 04:54:29 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu"
2025/08/23 04:54:29 NOTICE: remote: TestB2:rclone-test-penibes5ciyu
=== RUN   TestBisyncRemoteRemote/resync
2025/08/23 04:54:29 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we"
2025/08/23 04:54:30 INFO  : path1: Making directory
2025/08/23 04:54:32 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1"
2025/08/23 04:54:38 INFO  : path2: Making directory
2025/08/23 04:54:38 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2"
2025/08/23 04:54:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/08/23 04:54:39 DEBUG : Creating backend with remote "/tmp/045429we/initdir/test_resync-cunugeh4"
2025/08/23 04:54:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : Local file system at /tmp/045429we/initdir/test_resync-cunugeh4: Waiting for checks to finish
2025/08/23 04:54:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:54:39 DEBUG : Local file system at /tmp/045429we/initdir/test_resync-cunugeh4: Waiting for transfers to finish
2025/08/23 04:54:39 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/23 04:54:39 INFO  : file2.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/23 04:54:39 INFO  : file3.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/23 04:54:39 INFO  : file5.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/23 04:54:39 INFO  : file4.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/23 04:54:39 INFO  : file6.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:54:39 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/23 04:54:39 INFO  : file7.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:54:39 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:54:39 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/08/23 04:54:39 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7"
2025/08/23 04:54:39 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:39 DEBUG : Local file system at /tmp/045429we/datadir/test_resync-zosinuk7: Waiting for checks to finish
2025/08/23 04:54:39 DEBUG : Local file system at /tmp/045429we/datadir/test_resync-zosinuk7: Waiting for transfers to finish
2025/08/23 04:54:39 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:54:39 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt
2025/08/23 04:54:39 INFO  : fileA.txt: Copied (new)
2025/08/23 04:54:39 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:54:39 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt
2025/08/23 04:54:39 INFO  : fileB.txt: Copied (new)
2025/08/23 04:54:39 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:54:40 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:54:41 NOTICE: checking initFs Local file system at /tmp/045429we/initdir/test_resync-cunugeh4
2025/08/23 04:54:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:41 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Waiting for checks to finish
2025/08/23 04:54:41 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Waiting for transfers to finish
2025/08/23 04:54:42 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:42 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file4.txt: Copied (new)
2025/08/23 04:54:42 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file5.txt: Copied (new)
2025/08/23 04:54:42 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file2.txt: Copied (new)
2025/08/23 04:54:42 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:54:42 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:42 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file3.txt: Copied (new)
2025/08/23 04:54:42 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:42 INFO  : file6.txt: Copied (new)
2025/08/23 04:54:43 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:43 INFO  : file7.txt: Copied (new)
2025/08/23 04:54:43 NOTICE: checking Path1 B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1
2025/08/23 04:54:43 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:54:43 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Waiting for checks to finish
2025/08/23 04:54:43 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Waiting for transfers to finish
2025/08/23 04:54:44 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file1.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file4.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file5.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file3.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file6.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file7.txt: Copied (new)
2025/08/23 04:54:44 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:54:44 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:54:44 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:54:44 INFO  : file2.txt: Copied (new)
2025/08/23 04:54:44 NOTICE: checking path2 B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2
2025/08/23 04:54:45 NOTICE: (01)  : test resync
2025/08/23 04:54:45 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/08/23 04:54:45 NOTICE: (03)  : purge-children TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/
2025/08/23 04:54:45 DEBUG : RCLONE_TEST: Remove file
2025/08/23 04:54:45 DEBUG : file1.txt: Remove file
2025/08/23 04:54:45 DEBUG : file2.txt: Remove file
2025/08/23 04:54:45 DEBUG : file3.txt: Remove file
2025/08/23 04:54:46 DEBUG : file4.txt: Remove file
2025/08/23 04:54:46 DEBUG : file5.txt: Remove file
2025/08/23 04:54:46 DEBUG : file6.txt: Remove file
2025/08/23 04:54:46 DEBUG : file7.txt: Remove file
2025/08/23 04:54:46 NOTICE: (04)  : bisync resync
2025/08/23 04:54:54 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:54 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:54:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:54:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:54:54 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:54:54 INFO  : Copying Path2 files to Path1
2025/08/23 04:54:54 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:54:54 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:54:54 INFO  : file3.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : file2.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : file6.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : file4.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : file5.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : file7.txt: Copied (server-side copy)
2025/08/23 04:54:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:54:55 INFO  : Resync updating listings
2025/08/23 04:54:56 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:54:56 INFO  : Bisync successful
2025/08/23 04:54:56 NOTICE: (05)  : move-listings empty-path1
2025/08/23 04:54:56 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/08/23 04:54:56 NOTICE: (07)  : purge-children TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/
2025/08/23 04:54:56 DEBUG : RCLONE_TEST: Remove file
2025/08/23 04:54:56 DEBUG : file1.txt: Remove file
2025/08/23 04:54:56 DEBUG : file2.txt: Remove file
2025/08/23 04:54:56 DEBUG : file3.txt: Remove file
2025/08/23 04:54:56 DEBUG : file4.txt: Remove file
2025/08/23 04:54:57 DEBUG : file5.txt: Remove file
2025/08/23 04:54:57 DEBUG : file6.txt: Remove file
2025/08/23 04:54:57 DEBUG : file7.txt: Remove file
2025/08/23 04:54:57 NOTICE: (08)  : bisync resync
2025/08/23 04:55:06 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:06 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:55:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:55:06 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:06 INFO  : Copying Path2 files to Path1
2025/08/23 04:55:06 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:55:06 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:55:07 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file3.txt: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file4.txt: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file2.txt: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file7.txt: Copied (server-side copy)
2025/08/23 04:55:07 INFO  : file6.txt: Copied (server-side copy)
2025/08/23 04:55:08 INFO  : file5.txt: Copied (server-side copy)
2025/08/23 04:55:08 INFO  : Resync updating listings
2025/08/23 04:55:08 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:08 INFO  : Bisync successful
2025/08/23 04:55:08 NOTICE: (09)  : move-listings empty-path2
2025/08/23 04:55:08 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/08/23 04:55:08 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/045429we/datadir/test_resync-zosinuk7/ fileA.txt
2025/08/23 04:55:08 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/"
2025/08/23 04:55:08 DEBUG : fs cache: renaming cache item "/tmp/045429we/datadir/test_resync-zosinuk7/" to be canonical "/tmp/045429we/datadir/test_resync-zosinuk7"
2025/08/23 04:55:08 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/08/23 04:55:08 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/045429we/datadir/test_resync-zosinuk7/ fileB.txt
2025/08/23 04:55:08 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync-zosinuk7/" for canonical name "/tmp/045429we/datadir/test_resync-zosinuk7"
2025/08/23 04:55:08 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/08/23 04:55:08 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/08/23 04:55:08 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/08/23 04:55:08 NOTICE: (15)  : delete-file TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/file1.txt
2025/08/23 04:55:08 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:09 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/" to be canonical "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2"
2025/08/23 04:55:09 INFO  : file1.txt: Deleted
2025/08/23 04:55:09 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/08/23 04:55:09 NOTICE: (17)  : delete-file TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/file2.txt
2025/08/23 04:55:09 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/"
2025/08/23 04:55:11 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" to be canonical "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1"
2025/08/23 04:55:12 INFO  : file2.txt: Deleted
2025/08/23 04:55:12 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/08/23 04:55:12 NOTICE: (19)  : copy-as /tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/ file3.txt
2025/08/23 04:55:12 DEBUG : copyFile "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/" as "file3.txt"
2025/08/23 04:55:12 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt"
2025/08/23 04:55:12 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:13 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2" as "file3.txt"
2025/08/23 04:55:13 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:55:15 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:55:15 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/08/23 04:55:15 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/08/23 04:55:15 NOTICE: (21)  : delete-file TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/file4.txt
2025/08/23 04:55:15 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" for canonical name "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1"
2025/08/23 04:55:15 INFO  : file4.txt: Deleted
2025/08/23 04:55:15 NOTICE: (22)  : copy-as /tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/ file4.txt
2025/08/23 04:55:15 DEBUG : copyFile "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/" as "file4.txt"
2025/08/23 04:55:15 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt"
2025/08/23 04:55:15 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:16 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2" as "file4.txt"
2025/08/23 04:55:16 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:55:18 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:55:18 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/08/23 04:55:18 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/08/23 04:55:18 NOTICE: (24)  : copy-as /tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/ file5.txt
2025/08/23 04:55:18 DEBUG : copyFile "/tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/" as "file5.txt"
2025/08/23 04:55:18 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt"
2025/08/23 04:55:18 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:19 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2" as "file5.txt"
2025/08/23 04:55:19 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:55:21 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:55:21 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/08/23 04:55:21 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/08/23 04:55:21 NOTICE: (26)  : copy-as /tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/ file6.txt
2025/08/23 04:55:21 DEBUG : copyFile "/tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" as "file6.txt"
2025/08/23 04:55:21 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/fileB.txt"
2025/08/23 04:55:21 DEBUG : Config file has changed externally - reloading
2025/08/23 04:55:21 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/"
2025/08/23 04:55:22 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1" as "file6.txt"
2025/08/23 04:55:22 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:55:24 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:55:24 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/08/23 04:55:24 NOTICE: (27)  : copy-as /tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/ file6.txt
2025/08/23 04:55:24 DEBUG : copyFile "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/" as "file6.txt"
2025/08/23 04:55:24 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync-zosinuk7/fileA.txt"
2025/08/23 04:55:24 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:25 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2" as "file6.txt"
2025/08/23 04:55:25 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/23 04:55:27 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/23 04:55:27 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/08/23 04:55:27 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/08/23 04:55:27 NOTICE: (29)  : test run bisync with resync
2025/08/23 04:55:27 NOTICE: (30)  : bisync resync
2025/08/23 04:55:35 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:35 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:55:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:55:35 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:35 INFO  : Copying Path2 files to Path1
2025/08/23 04:55:35 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:55:36 INFO  : file2.txt: Copied (server-side copy)
2025/08/23 04:55:36 INFO  : file4.txt: Copied (server-side copy)
2025/08/23 04:55:36 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:55:36 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:55:37 INFO  : file6.txt: Updated modification time in destination
2025/08/23 04:55:37 INFO  : file3.txt: Copied (server-side copy)
2025/08/23 04:55:37 INFO  : file5.txt: Copied (server-side copy)
2025/08/23 04:55:37 INFO  : Resync updating listings
2025/08/23 04:55:37 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:37 INFO  : Bisync successful
2025/08/23 04:55:37 NOTICE: (31)  : copy-listings mixed-diffs
2025/08/23 04:55:37 NOTICE: (32)  : test run normal bisync
2025/08/23 04:55:37 NOTICE: (33)  : bisync
2025/08/23 04:55:45 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:45 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:55: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/08/23 04:55:45 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:45 INFO  : Building Path1 and Path2 listings
2025/08/23 04:55:45 INFO  : Path1 checking for diffs
2025/08/23 04:55:45 INFO  : Path2 checking for diffs
2025/08/23 04:55:45 INFO  : No changes found
2025/08/23 04:55:45 INFO  : Updating listings
2025/08/23 04:55:45 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:45 INFO  : Bisync successful
2025/08/23 04:55:45 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/08/23 04:55:45 NOTICE: (35)  : purge-children TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/
2025/08/23 04:55:46 DEBUG : RCLONE_TEST: Remove file
2025/08/23 04:55:46 DEBUG : file1.txt: Remove file
2025/08/23 04:55:46 DEBUG : file2.txt: Remove file
2025/08/23 04:55:46 DEBUG : file3.txt: Remove file
2025/08/23 04:55:46 DEBUG : file4.txt: Remove file
2025/08/23 04:55:47 DEBUG : file5.txt: Remove file
2025/08/23 04:55:47 DEBUG : file6.txt: Remove file
2025/08/23 04:55:47 DEBUG : file7.txt: Remove file
2025/08/23 04:55:47 NOTICE: (36)  : bisync
2025/08/23 04:55:55 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:55 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:55:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:55:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:55:55 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync/path2/"
2025/08/23 04:55:55 INFO  : Building Path1 and Path2 listings
2025/08/23 04:55:55 INFO  : Path1 checking for diffs
2025/08/23 04:55:55 INFO  : Path2 checking for diffs
2025/08/23 04:55:55 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/045429we/workdir/TestB2_rclone-test-penibes5ciyu_045429we_resync_path1..TestB2_rclone-test-penibes5ciyu_045429we_resync_path2.path2.lst-new
2025/08/23 04:55:55 ERROR : Bisync critical error: empty current Path2 listing: /tmp/045429we/workdir/TestB2_rclone-test-penibes5ciyu_045429we_resync_path1..TestB2_rclone-test-penibes5ciyu_045429we_resync_path2.path2.lst-new
2025/08/23 04:55:55 ERROR : Bisync aborted. Must run --resync to recover.
2025/08/23 04:55:55 NOTICE: Bisync error: bisync aborted
2025/08/23 04:55:55 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:55:55 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f117226633fbf7dad_d20250823_m045454_c001_v0001117_t0015_u01755924894491")
2025/08/23 04:55:55 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa46_d20250823_m045445_c001_v7007000_t0000_u01755924885416")
2025/08/23 04:55:55 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11426dceaffd97cd_d20250823_m045442_c001_v0001179_t0030_u01755924882566")
2025/08/23 04:55:55 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1035617a2a113e58_d20250823_m045454_c001_v0001184_t0055_u01755924894847")
2025/08/23 04:55:55 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa4b_d20250823_m045445_c001_v7007000_t0000_u01755924885599")
2025/08/23 04:55:55 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113b090dc661d7d0_d20250823_m045441_c001_v0001176_t0007_u01755924881994")
2025/08/23 04:55:55 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f107cb6413289bcf8_d20250823_m045535_c001_v0001165_t0054_u01755924935854")
2025/08/23 04:55:55 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4095e5353829464f_d20250823_m045512_c001_v7007000_t0000_u01755924912093")
2025/08/23 04:55:55 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10369cb622733d10_d20250823_m045454_c001_v0001130_t0018_u01755924894868")
2025/08/23 04:55:56 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa50_d20250823_m045445_c001_v7007000_t0000_u01755924885781")
2025/08/23 04:55:56 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1017f34584ff1ab1_d20250823_m045442_c001_v0001105_t0043_u01755924882377")
2025/08/23 04:55:56 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11342b8d28b83c75_d20250823_m045454_c001_v0001183_t0013_u01755924894866")
2025/08/23 04:55:56 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa55_d20250823_m045445_c001_v7007000_t0000_u01755924885965")
2025/08/23 04:55:56 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f108f6813fcd97d82_d20250823_m045442_c001_v0001106_t0022_u01755924882184")
2025/08/23 04:55:56 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1095f5e5ed930487_d20250823_m045536_c001_v0001099_t0032_u01755924936111")
2025/08/23 04:55:56 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4095e5353829467f_d20250823_m045515_c001_v7007000_t0000_u01755924915295")
2025/08/23 04:55:56 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118f38a1cf103d02_d20250823_m045455_c001_v0001038_t0016_u01755924895071")
2025/08/23 04:55:56 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa5a_d20250823_m045446_c001_v7007000_t0000_u01755924886147")
2025/08/23 04:55:56 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113b090dc661d7d6_d20250823_m045442_c001_v0001176_t0013_u01755924882205")
2025/08/23 04:55:56 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1134c2c14ab5b606_d20250823_m045455_c001_v0001134_t0004_u01755924895097")
2025/08/23 04:55:56 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa5f_d20250823_m045446_c001_v7007000_t0000_u01755924886330")
2025/08/23 04:55:56 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113b090dc661d7d8_d20250823_m045442_c001_v0001176_t0046_u01755924882418")
2025/08/23 04:55:56 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f100bd2808c462e39_d20250823_m045523_c001_v0001038_t0055_u01755924923680")
2025/08/23 04:55:56 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11550f85d63bf9cc_d20250823_m045455_c001_v0001179_t0023_u01755924895237")
2025/08/23 04:55:56 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa64_d20250823_m045446_c001_v7007000_t0000_u01755924886512")
2025/08/23 04:55:57 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113b090dc661d7da_d20250823_m045442_c001_v0001176_t0055_u01755924882633")
2025/08/23 04:55:57 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104bbedeb5cc9b98_d20250823_m045455_c001_v0001150_t0019_u01755924895304")
2025/08/23 04:55:57 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffa6b_d20250823_m045446_c001_v7007000_t0000_u01755924886696")
2025/08/23 04:55:57 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1017f34584ff1ab3_d20250823_m045442_c001_v0001105_t0056_u01755924882661")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4186dec1394808af_d20250823_m045551_c001_v7007000_t0000_u01755924951090")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113110a940066978_d20250823_m045549_c001_v0001179_t0042_u01755924949874")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11426dceaffd993d_d20250823_m045548_c001_v0001179_t0048_u01755924948075")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4186dec139480817_d20250823_m045541_c001_v7007000_t0000_u01755924941203")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113d986336772f5c_d20250823_m045539_c001_v0001180_t0042_u01755924939935")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1017f34584ff1b98_d20250823_m045537_c001_v0001105_t0054_u01755924937963")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4186dec1394806ed_d20250823_m045531_c001_v7007000_t0000_u01755924931101")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109b271f4f753948_d20250823_m045529_c001_v0001176_t0046_u01755924929886")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113b090dc661d8b4_d20250823_m045527_c001_v0001176_t0051_u01755924927679")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f41575e1e6bcd6f75_d20250823_m045501_c001_v7007000_t0000_u01755924901545")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f111e79119877406f_d20250823_m045500_c001_v0001172_t0025_u01755924900232")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f108f6813fcd97db0_d20250823_m045458_c001_v0001106_t0003_u01755924898106")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffae7_d20250823_m045450_c001_v7007000_t0000_u01755924890065")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f108c01e0d1116f0f_d20250823_m045448_c001_v0001134_t0032_u01755924888658")
2025/08/23 04:55:57 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11426dceaffd97e5_d20250823_m045446_c001_v0001179_t0006_u01755924886876")
2025/08/23 04:55:58 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:55:58 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89c5_d20250823_m045546_c001_v7007000_t0000_u01755924946232")
2025/08/23 04:55:58 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103f89b894e31767_d20250823_m045506_c001_v0001106_t0049_u01755924906776")
2025/08/23 04:55:58 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc6431321_d20250823_m045456_c001_v7007000_t0000_u01755924896281")
2025/08/23 04:55:58 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1195560c6c217465_d20250823_m045444_c001_v0001146_t0008_u01755924884575")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89c8_d20250823_m045546_c001_v7007000_t0000_u01755924946414")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104b764f2670c4f4_d20250823_m045536_c001_v0001165_t0040_u01755924936776")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a18ffdbc_d20250823_m045509_c001_v7007000_t0000_u01755924909903")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1116a5c72ef8fa56_d20250823_m045507_c001_v0001183_t0054_u01755924907106")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc6431326_d20250823_m045456_c001_v7007000_t0000_u01755924896464")
2025/08/23 04:55:58 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c33322_d20250823_m045444_c001_v0001182_t0053_u01755924884011")
2025/08/23 04:55:58 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89cb_d20250823_m045546_c001_v7007000_t0000_u01755924946597")
2025/08/23 04:55:59 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f108daefc4a49f01f_d20250823_m045507_c001_v0001157_t0055_u01755924907144")
2025/08/23 04:55:59 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc643132b_d20250823_m045456_c001_v7007000_t0000_u01755924896646")
2025/08/23 04:55:59 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1164a4c54c4ffb49_d20250823_m045444_c001_v0001144_t0001_u01755924884267")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89cd_d20250823_m045546_c001_v7007000_t0000_u01755924946778")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1008e5e3c4e02a7d_d20250823_m045537_c001_v0001091_t0051_u01755924937009")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11711e22a6e96c8c_d20250823_m045514_c001_v0001150_t0022_u01755924914842")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f110fd36fc1a071f2_d20250823_m045507_c001_v0001183_t0056_u01755924907140")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc643132c_d20250823_m045456_c001_v7007000_t0000_u01755924896827")
2025/08/23 04:55:59 DEBUG : file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104bb6672b9a3062_d20250823_m045444_c001_v0001136_t0036_u01755924884388")
2025/08/23 04:55:59 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89d0_d20250823_m045546_c001_v7007000_t0000_u01755924946959")
2025/08/23 04:55:59 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f110fd36fc1a07252_d20250823_m045518_c001_v0001183_t0044_u01755924918045")
2025/08/23 04:55:59 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1147e60ca4ae8d33_d20250823_m045507_c001_v0001183_t0028_u01755924907290")
2025/08/23 04:55:59 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc6431331_d20250823_m045457_c001_v7007000_t0000_u01755924897009")
2025/08/23 04:55:59 DEBUG : file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c33326_d20250823_m045444_c001_v0001182_t0045_u01755924884220")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89d5_d20250823_m045547_c001_v7007000_t0000_u01755924947156")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1151015c0ea0f155_d20250823_m045537_c001_v0001041_t0008_u01755924937138")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119e38a1bd80efe5_d20250823_m045520_c001_v0001183_t0035_u01755924920816")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f107054a4a44138de_d20250823_m045507_c001_v0001144_t0046_u01755924907341")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc6431336_d20250823_m045457_c001_v7007000_t0000_u01755924897193")
2025/08/23 04:55:59 DEBUG : file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c3332c_d20250823_m045444_c001_v0001182_t0042_u01755924884432")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89d6_d20250823_m045547_c001_v7007000_t0000_u01755924947338")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11284834314bca44_d20250823_m045537_c001_v0001178_t0045_u01755924937155")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118c0f403f204d21_d20250823_m045526_c001_v0001106_t0008_u01755924926823")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118bc83eb54ec8de_d20250823_m045507_c001_v0001043_t0055_u01755924907371")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc643133a_d20250823_m045457_c001_v7007000_t0000_u01755924897376")
2025/08/23 04:56:00 DEBUG : file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c33330_d20250823_m045444_c001_v0001182_t0041_u01755924884633")
2025/08/23 04:56:00 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89d8_d20250823_m045547_c001_v7007000_t0000_u01755924947520")
2025/08/23 04:56:00 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f108631b4c15fc6b3_d20250823_m045507_c001_v0001183_t0056_u01755924907526")
2025/08/23 04:56:00 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc643133f_d20250823_m045457_c001_v7007000_t0000_u01755924897558")
2025/08/23 04:56:00 DEBUG : file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104bb6672b9a3066_d20250823_m045444_c001_v0001136_t0031_u01755924884677")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4186dec1394808be_d20250823_m045555_c001_v7007000_t0000_u01755924955330")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1167c601f576df40_d20250823_m045553_c001_v0001036_t0049_u01755924953782")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c334f1_d20250823_m045551_c001_v0001182_t0024_u01755924951643")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f407a47d19c6e89bb_d20250823_m045545_c001_v7007000_t0000_u01755924945667")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11248d745c134e83_d20250823_m045544_c001_v0001179_t0046_u01755924944434")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1164a4c54c4ffbf7_d20250823_m045541_c001_v0001144_t0028_u01755924941753")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f401150c7394f01b5_d20250823_m045535_c001_v7007000_t0000_u01755924935431")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f100a98e73726b3a0_d20250823_m045533_c001_v0001115_t0025_u01755924933878")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1195560c6c217534_d20250823_m045531_c001_v0001146_t0032_u01755924931647")
2025/08/23 04:56:00 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4209e2bde22926d0_d20250823_m045506_c001_v7007000_t0000_u01755924906140")
2025/08/23 04:56:01 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1145a7a725fd218e_d20250823_m045504_c001_v0001172_t0020_u01755924904749")
2025/08/23 04:56:01 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104bb6672b9a30b0_d20250823_m045502_c001_v0001136_t0027_u01755924902094")
2025/08/23 04:56:01 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f412d3a4cc64312ff_d20250823_m045454_c001_v7007000_t0000_u01755924894082")
2025/08/23 04:56:01 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11015714f64cae94_d20250823_m045452_c001_v0001153_t0011_u01755924892732")
2025/08/23 04:56:01 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10301f8632c3335c_d20250823_m045450_c001_v0001182_t0057_u01755924890604")
    bisync_test.go:596: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/08/23 04:56:01 INFO  : path1: Making directory
2025/08/23 04:56:01 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1"
2025/08/23 04:56:02 INFO  : path2: Making directory
2025/08/23 04:56:02 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2"
2025/08/23 04:56:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/08/23 04:56:03 DEBUG : Creating backend with remote "/tmp/045429we/initdir/test_resync_modes-baxogel5"
2025/08/23 04:56:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : Local file system at /tmp/045429we/initdir/test_resync_modes-baxogel5: Waiting for checks to finish
2025/08/23 04:56:03 DEBUG : Local file system at /tmp/045429we/initdir/test_resync_modes-baxogel5: Waiting for transfers to finish
2025/08/23 04:56:03 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:56:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:56:03 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/23 04:56:03 INFO  : file2.txt: Copied (new)
2025/08/23 04:56:03 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:56:03 INFO  : file1.txt: Copied (new)
2025/08/23 04:56:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:56:03 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:56:03 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:56:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/08/23 04:56:03 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:03 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:03 DEBUG : Local file system at /tmp/045429we/datadir/test_resync_modes-vufayaf3: Waiting for checks to finish
2025/08/23 04:56:03 DEBUG : Local file system at /tmp/045429we/datadir/test_resync_modes-vufayaf3: Waiting for transfers to finish
2025/08/23 04:56:03 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/08/23 04:56:03 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/08/23 04:56:03 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2025/08/23 04:56:03 INFO  : file1L.txt: Copied (new)
2025/08/23 04:56:03 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2025/08/23 04:56:03 INFO  : file2.txt: Copied (new)
2025/08/23 04:56:03 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/08/23 04:56:03 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2025/08/23 04:56:03 INFO  : file1R.txt: Copied (new)
2025/08/23 04:56:03 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:56:04 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:56:05 NOTICE: checking initFs Local file system at /tmp/045429we/initdir/test_resync_modes-baxogel5
2025/08/23 04:56:05 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:56:05 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:05 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:05 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Waiting for checks to finish
2025/08/23 04:56:05 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Waiting for transfers to finish
2025/08/23 04:56:06 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:56:06 INFO  : file1.txt: Copied (new)
2025/08/23 04:56:07 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:56:07 INFO  : file2.txt: Copied (new)
2025/08/23 04:56:07 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:56:07 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:56:07 NOTICE: checking Path1 B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1
2025/08/23 04:56:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:56:07 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:07 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:56:07 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Waiting for checks to finish
2025/08/23 04:56:07 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Waiting for transfers to finish
2025/08/23 04:56:08 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:56:08 INFO  : file2.txt: Copied (new)
2025/08/23 04:56:08 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:56:08 INFO  : file1.txt: Copied (new)
2025/08/23 04:56:09 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:56:09 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:56:09 NOTICE: checking path2 B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2
2025/08/23 04:56:09 NOTICE: (01)  : test resync-mode
2025/08/23 04:56:09 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:56:09 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:56:09 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/"
2025/08/23 04:56:09 DEBUG : fs cache: renaming cache item "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" to be canonical "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:09 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/08/23 04:56:09 NOTICE: (04)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:56:09 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:56:09 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:56:09 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:10 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:56:10 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/23 04:56:12 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:56:12 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:56:12 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:56:12 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:12 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/08/23 04:56:12 NOTICE: (06)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:56:12 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:56:12 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:56:12 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:56:13 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:56:13 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/23 04:56:15 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:56:15 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:56:15 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/08/23 04:56:15 NOTICE: (08)  : bisync resync resync-mode=newer
2025/08/23 04:56:24 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:24 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:24 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:56:24 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:56:24 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:24 INFO  : Copying Path2 files to Path1
2025/08/23 04:56:24 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:56:24 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:56:24 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:56:24 INFO  : Resync updating listings
2025/08/23 04:56:24 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:24 INFO  : Bisync successful
2025/08/23 04:56:24 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:56:24 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:56:24 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:24 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/08/23 04:56:24 NOTICE: (11)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:56:24 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:56:24 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:56:24 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:25 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:56:25 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/23 04:56:27 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:56:27 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:56:27 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:56:27 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:27 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/08/23 04:56:27 NOTICE: (13)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:56:27 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:56:27 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:56:27 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:56:28 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:56:28 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/08/23 04:56:28 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:56:30 INFO  : file1L.txt: Updated modification time in destination
2025/08/23 04:56:30 DEBUG : file1L.txt: Unchanged skipping
2025/08/23 04:56:30 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/08/23 04:56:30 NOTICE: (15)  : bisync resync resync-mode=path2
2025/08/23 04:56:38 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:38 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:56:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:56:38 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:38 INFO  : Copying Path2 files to Path1
2025/08/23 04:56:38 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:56:39 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:56:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:56:39 INFO  : Resync updating listings
2025/08/23 04:56:39 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:39 INFO  : Bisync successful
2025/08/23 04:56:39 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:56:39 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:56:39 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:39 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/08/23 04:56:39 NOTICE: (18)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:56:39 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:56:39 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:56:39 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:40 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:56:40 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/08/23 04:56:40 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:56:41 INFO  : file1R.txt: Updated modification time in destination
2025/08/23 04:56:41 DEBUG : file1R.txt: Unchanged skipping
2025/08/23 04:56:41 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:56:41 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:41 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/08/23 04:56:41 NOTICE: (20)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:56:41 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:56:41 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:56:41 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:56:42 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:56:42 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/23 04:56:44 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:56:44 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:56:44 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/08/23 04:56:44 NOTICE: (22)  : bisync resync resync-mode=larger
2025/08/23 04:56:53 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:53 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:56:53 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:56:53 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:56:53 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:53 INFO  : Copying Path2 files to Path1
2025/08/23 04:56:53 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:56:53 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/08/23 04:56:54 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:56:54 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:56:54 INFO  : Resync updating listings
2025/08/23 04:56:54 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:54 INFO  : Bisync successful
2025/08/23 04:56:54 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:56:54 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:56:54 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:54 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/08/23 04:56:54 NOTICE: (25)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:56:54 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:56:54 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:56:54 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:56:55 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:56:55 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/08/23 04:56:55 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:56:57 INFO  : file1R.txt: Updated modification time in destination
2025/08/23 04:56:57 DEBUG : file1R.txt: Unchanged skipping
2025/08/23 04:56:57 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:56:57 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:56:57 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/08/23 04:56:57 NOTICE: (27)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:56:57 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:56:57 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:56:57 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:56:58 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:56:58 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/23 04:57:00 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:00 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:57:00 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/08/23 04:57:00 NOTICE: (29)  : bisync resync resync-mode=older
2025/08/23 04:57:08 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:08 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:57:08 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:57:08 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:08 INFO  : Copying Path2 files to Path1
2025/08/23 04:57:08 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:57:08 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/08/23 04:57:09 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:57:09 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:57:09 INFO  : Resync updating listings
2025/08/23 04:57:09 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:09 INFO  : Bisync successful
2025/08/23 04:57:09 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:57:09 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:57:09 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:09 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/08/23 04:57:09 NOTICE: (32)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:57:09 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:57:09 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:57:09 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:10 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:57:10 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/08/23 04:57:10 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:57:11 INFO  : file1R.txt: Updated modification time in destination
2025/08/23 04:57:11 DEBUG : file1R.txt: Unchanged skipping
2025/08/23 04:57:11 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:57:11 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:11 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/08/23 04:57:11 NOTICE: (34)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:57:11 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:57:11 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:57:11 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:57:12 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:57:13 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/23 04:57:14 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:14 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:57:14 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/08/23 04:57:14 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/08/23 04:57:22 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:22 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:57:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:57:22 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:22 INFO  : Copying Path2 files to Path1
2025/08/23 04:57:22 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:57:23 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/23 04:57:23 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:57:23 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/23 04:57:24 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:57:24 INFO  : Resync updating listings
2025/08/23 04:57:24 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:24 INFO  : Bisync successful
2025/08/23 04:57:24 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/23 04:57:24 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:57:24 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:24 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/08/23 04:57:24 NOTICE: (39)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:57:24 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:57:24 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:57:24 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:25 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:57:25 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/23 04:57:27 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:57:27 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:57:27 NOTICE: (40)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file2.txt
2025/08/23 04:57:27 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file2.txt"
2025/08/23 04:57:27 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:57:27 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:57:28 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file2.txt"
2025/08/23 04:57:28 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/23 04:57:29 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:57:29 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/08/23 04:57:29 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:57:29 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:29 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/08/23 04:57:29 NOTICE: (42)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:57:29 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:57:29 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:57:29 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:57:30 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:57:31 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/08/23 04:57:31 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:32 INFO  : file1L.txt: Updated modification time in destination
2025/08/23 04:57:32 DEBUG : file1L.txt: Unchanged skipping
2025/08/23 04:57:32 NOTICE: (43)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file2.txt
2025/08/23 04:57:32 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file2.txt"
2025/08/23 04:57:32 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:57:32 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:33 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file2.txt"
2025/08/23 04:57:33 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/23 04:57:35 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:35 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/23 04:57:35 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/08/23 04:57:35 NOTICE: (45)  : bisync resync resync-mode=path1
2025/08/23 04:57:44 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:44 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:57:44 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:57: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/08/23 04:57:44 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:44 INFO  : Copying Path2 files to Path1
2025/08/23 04:57:44 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:57:44 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:57:45 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:57:45 INFO  : file2.txt: Copied (server-side copy)
2025/08/23 04:57:45 INFO  : Resync updating listings
2025/08/23 04:57:45 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:45 INFO  : Bisync successful
2025/08/23 04:57:45 NOTICE: (46)  : test no winner
2025/08/23 04:57:45 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1R.txt
2025/08/23 04:57:45 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:45 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/08/23 04:57:45 NOTICE: (48)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file1.txt
2025/08/23 04:57:45 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file1.txt"
2025/08/23 04:57:45 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:57:45 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:46 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file1.txt"
2025/08/23 04:57:46 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/23 04:57:48 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:57:48 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/23 04:57:48 NOTICE: (49)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file2.txt
2025/08/23 04:57:48 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file2.txt"
2025/08/23 04:57:48 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1R.txt"
2025/08/23 04:57:48 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:57:49 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file2.txt"
2025/08/23 04:57:49 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/08/23 04:57:49 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/23 04:57:51 INFO  : file1R.txt: Updated modification time in destination
2025/08/23 04:57:51 DEBUG : file1R.txt: Unchanged skipping
2025/08/23 04:57:51 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/045429we/datadir/test_resync_modes-vufayaf3/ file1L.txt
2025/08/23 04:57:51 DEBUG : fs cache: switching user supplied name "/tmp/045429we/datadir/test_resync_modes-vufayaf3/" for canonical name "/tmp/045429we/datadir/test_resync_modes-vufayaf3"
2025/08/23 04:57:51 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/08/23 04:57:51 NOTICE: (51)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/ file1.txt
2025/08/23 04:57:51 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" as "file1.txt"
2025/08/23 04:57:51 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:57:51 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/"
2025/08/23 04:57:52 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1" as "file1.txt"
2025/08/23 04:57:52 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/08/23 04:57:52 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:53 INFO  : file1L.txt: Updated modification time in destination
2025/08/23 04:57:53 DEBUG : file1L.txt: Unchanged skipping
2025/08/23 04:57:53 NOTICE: (52)  : copy-as /tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/ file2.txt
2025/08/23 04:57:53 DEBUG : copyFile "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt" to "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/" as "file2.txt"
2025/08/23 04:57:53 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_resync_modes-vufayaf3/file1L.txt"
2025/08/23 04:57:53 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:57:54 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2" as "file2.txt"
2025/08/23 04:57:55 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/23 04:57:56 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/23 04:57:56 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/23 04:57:56 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/08/23 04:57:56 NOTICE: (54)  : bisync resync resync-mode=newer
2025/08/23 04:58:05 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:58:05 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/23 04:58:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/23 04:58:05 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/23 04:58:05 INFO  : Synching Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" with Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:58:05 INFO  : Copying Path2 files to Path1
2025/08/23 04:58:05 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/23 04:58:05 INFO  : file1.txt: Copied (server-side copy)
2025/08/23 04:58:06 INFO  : file2.txt: Copied (server-side copy)
2025/08/23 04:58:06 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/23 04:58:06 INFO  : Resync updating listings
2025/08/23 04:58:06 INFO  : Validating listings for Path1 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path1/" vs Path2 "TestB2:rclone-test-penibes5ciyu/045429we/resync_modes/path2/"
2025/08/23 04:58:06 INFO  : Bisync successful
2025/08/23 04:58:06 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:06 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f106d59cc9a51665d_d20250823_m045606_c001_v0001044_t0054_u01755924966843")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f116b2053181822ab_d20250823_m045805_c001_v0001183_t0052_u01755925085794")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f116a268bf4b14741_d20250823_m045753_c001_v0001038_t0047_u01755925073424")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103b36b8983a779c_d20250823_m045732_c001_v0001102_t0017_u01755925052156")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1059e3f4d7224ccd_d20250823_m045714_c001_v0001178_t0007_u01755925034465")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118a24395a389f51_d20250823_m045708_c001_v0001183_t0000_u01755925028950")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1134c2c14ab5b802_d20250823_m045700_c001_v0001134_t0000_u01755925020102")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103ae4ecd1e7041d_d20250823_m045653_c001_v0001040_t0034_u01755925013835")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11620bf1f5ccd4cc_d20250823_m045644_c001_v0001104_t0039_u01755925004370")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1016ade56e55ba96_d20250823_m045638_c001_v0001101_t0002_u01755924998773")
2025/08/23 04:58:06 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118f028b66537fa4_d20250823_m045629_c001_v0001040_t0055_u01755924989865")
2025/08/23 04:58:07 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f101a853354df8b85_d20250823_m045614_c001_v0001157_t0056_u01755924974862")
2025/08/23 04:58:07 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1032fc8038fb35d7_d20250823_m045606_c001_v0001150_t0003_u01755924966470")
2025/08/23 04:58:07 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f100a19f447599fe4_d20250823_m045806_c001_v0001178_t0031_u01755925086028")
2025/08/23 04:58:07 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10562423b3c92340_d20250823_m045750_c001_v0001038_t0035_u01755925070779")
2025/08/23 04:58:07 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1154c2ec2992b329_d20250823_m045729_c001_v0001165_t0048_u01755925049703")
2025/08/23 04:58:07 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103cb22052361d08_d20250823_m045606_c001_v0001134_t0018_u01755924966656")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f404f5cb36847f2e0_d20250823_m045801_c001_v7007000_t0000_u01755925081067")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f117ba90c7b74c3d9_d20250823_m045759_c001_v0001161_t0005_u01755925079717")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1032fc8038fb3721_d20250823_m045757_c001_v0001150_t0032_u01755925077323")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f419ef394b46a86de_d20250823_m045739_c001_v7007000_t0000_u01755925059701")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11721c21aad23b10_d20250823_m045738_c001_v0001183_t0003_u01755925058499")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f106d59cc9a516719_d20250823_m045736_c001_v0001044_t0004_u01755925056039")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4175e8ba9536df49_d20250823_m045718_c001_v7007000_t0000_u01755925038822")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10435d4445e55041_d20250823_m045717_c001_v0001184_t0022_u01755925037617")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103cb22052361dee_d20250823_m045715_c001_v0001134_t0013_u01755925035041")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f400ccce2f0c326bd_d20250823_m045704_c001_v7007000_t0000_u01755925024659")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1077cec2409863b7_d20250823_m045703_c001_v0001101_t0047_u01755925023264")
2025/08/23 04:58:07 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1032fc8038fb368f_d20250823_m045700_c001_v0001150_t0006_u01755925020997")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f414ceec2a190047b_d20250823_m045648_c001_v7007000_t0000_u01755925008882")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f113d986336773084_d20250823_m045647_c001_v0001180_t0038_u01755925007637")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f106d59cc9a5166bb_d20250823_m045645_c001_v0001044_t0039_u01755925005291")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f415c3187c7a5ec67_d20250823_m045634_c001_v7007000_t0000_u01755924994452")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f105d3bc34ff39545_d20250823_m045633_c001_v0001113_t0007_u01755924993028")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f103cb22052361d55_d20250823_m045630_c001_v0001134_t0033_u01755924990785")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f41313bf9675bfcb1_d20250823_m045620_c001_v7007000_t0000_u01755924980005")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1130734d01de812e_d20250823_m045618_c001_v0001144_t0058_u01755924978373")
2025/08/23 04:58:08 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1032fc8038fb35f7_d20250823_m045615_c001_v0001150_t0007_u01755924975769")
2025/08/23 04:58:08 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/resync_modes/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:09 DEBUG : RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104854353d1d27dd_d20250823_m045608_c001_v0001117_t0046_u01755924968576")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1012c78e169f9ccb_d20250823_m045748_c001_v0001029_t0029_u01755925068014")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11744950f2f521a7_d20250823_m045744_c001_v0001136_t0035_u01755925064812")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11057eabd52c3f41_d20250823_m045726_c001_v0001098_t0056_u01755925046790")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11326d0b259199e1_d20250823_m045723_c001_v0001093_t0018_u01755925043416")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1107305c19a9b093_d20250823_m045711_c001_v0001171_t0000_u01755925031578")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f111c9547aed5a8b7_d20250823_m045656_c001_v0001106_t0017_u01755925016705")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11194b5f54280a8a_d20250823_m045641_c001_v0001150_t0026_u01755925001589")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f101812a231a7b4fa_d20250823_m045627_c001_v0001183_t0003_u01755924987355")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f10705675db414456_d20250823_m045624_c001_v0001184_t0020_u01755924984503")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1066877a0c7d610e_d20250823_m045611_c001_v0001098_t0054_u01755924971962")
2025/08/23 04:58:09 DEBUG : file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1116a5c72ef8fca9_d20250823_m045608_c001_v0001183_t0017_u01755924968379")
2025/08/23 04:58:09 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f106b672c980e17ee_d20250823_m045756_c001_v0001040_t0056_u01755925076530")
2025/08/23 04:58:09 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f116eeb544eacba31_d20250823_m045745_c001_v0001165_t0022_u01755925065214")
2025/08/23 04:58:09 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11164dd24a8e439a_d20250823_m045735_c001_v0001145_t0040_u01755925055320")
2025/08/23 04:58:09 DEBUG : file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109d87e17ea5f72b_d20250823_m045608_c001_v0001184_t0045_u01755924968193")
2025/08/23 04:58:09 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f406aa4d65be54a8c_d20250823_m045805_c001_v7007000_t0000_u01755925085393")
2025/08/23 04:58:09 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1114fc92c16148af_d20250823_m045803_c001_v0001037_t0040_u01755925083811")
2025/08/23 04:58:09 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109d87e17ea5fb22_d20250823_m045801_c001_v0001184_t0050_u01755925081614")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4028ad8d5e444094_d20250823_m045744_c001_v7007000_t0000_u01755925064203")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109210aa02fb9d17_d20250823_m045742_c001_v0001115_t0022_u01755925062663")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104854353d1d28d0_d20250823_m045740_c001_v0001117_t0036_u01755925060260")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4174a87bfb5ecb3a_d20250823_m045722_c001_v7007000_t0000_u01755925042843")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f106cd7fad3efac4b_d20250823_m045721_c001_v0001137_t0040_u01755925041556")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1116a5c72ef8feeb_d20250823_m045719_c001_v0001183_t0017_u01755925039389")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f408ecead5779ac4c_d20250823_m045708_c001_v7007000_t0000_u01755925028561")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f116abe150ac6c5a3_d20250823_m045707_c001_v0001176_t0009_u01755925027345")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109d87e17ea5f93e_d20250823_m045705_c001_v0001184_t0004_u01755925025214")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f418897f1e6666b62_d20250823_m045653_c001_v7007000_t0000_u01755925013444")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f116609903f385e7f_d20250823_m045652_c001_v0001092_t0038_u01755925012055")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104854353d1d2842_d20250823_m045649_c001_v0001117_t0050_u01755925009435")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f4186dec139480bce_d20250823_m045638_c001_v7007000_t0000_u01755924998385")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1013433878c490e1_d20250823_m045637_c001_v0001178_t0042_u01755924997133")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1116a5c72ef8fd89_d20250823_m045634_c001_v0001183_t0031_u01755924994993")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f404f5cb36847eab8_d20250823_m045623_c001_v7007000_t0000_u01755924983928")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1116a5c72ef8fd1d_d20250823_m045622_c001_v0001183_t0004_u01755924982712")
2025/08/23 04:58:10 DEBUG : modtime_write_test: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109d87e17ea5f789_d20250823_m045620_c001_v0001184_t0044_u01755924980552")
    bisync_test.go:596: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/08/23 04:58:11 INFO  : path1: Making directory
2025/08/23 04:58:11 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/rmdirs/path1"
2025/08/23 04:58:12 INFO  : path2: Making directory
2025/08/23 04:58:12 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/rmdirs/path2"
2025/08/23 04:58:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/08/23 04:58:13 DEBUG : Creating backend with remote "/tmp/045429we/initdir/test_rmdirs-migayem9"
2025/08/23 04:58:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : subdir: Making directory with metadata
2025/08/23 04:58:13 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/23 04:58:13 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/23 04:58:13 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : Local file system at /tmp/045429we/initdir/test_rmdirs-migayem9: Waiting for checks to finish
2025/08/23 04:58:13 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : Local file system at /tmp/045429we/initdir/test_rmdirs-migayem9: Waiting for transfers to finish
2025/08/23 04:58:13 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/23 04:58:13 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/23 04:58:13 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/23 04:58:13 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/23 04:58:13 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/23 04:58:13 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:58:13 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:13 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/23 04:58:13 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:58:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:58:13 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:58:13 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:13 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/23 04:58:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/08/23 04:58:13 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_rmdirs-yifepos8"
2025/08/23 04:58:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:13 DEBUG : Local file system at /tmp/045429we/datadir/test_rmdirs-yifepos8: Waiting for checks to finish
2025/08/23 04:58:13 DEBUG : Local file system at /tmp/045429we/datadir/test_rmdirs-yifepos8: Waiting for transfers to finish
2025/08/23 04:58:13 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:13 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/08/23 04:58:13 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:13 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:14 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:15 NOTICE: checking initFs Local file system at /tmp/045429we/initdir/test_rmdirs-migayem9
2025/08/23 04:58:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 INFO  : subdir: Making directory
2025/08/23 04:58:15 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:15 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path1: Waiting for checks to finish
2025/08/23 04:58:15 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path1: Waiting for transfers to finish
2025/08/23 04:58:16 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:16 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:58:16 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:16 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:58:16 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:16 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:58:16 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:16 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:58:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:58:17 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:17 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:17 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:58:17 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:17 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:58:17 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:17 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:17 NOTICE: checking Path1 B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path1
2025/08/23 04:58:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 INFO  : subdir: Making directory
2025/08/23 04:58:17 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:17 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path2: Waiting for checks to finish
2025/08/23 04:58:17 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path2: Waiting for transfers to finish
2025/08/23 04:58:18 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:18 INFO  : file1.copy1.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : file1.copy2.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : file1.copy3.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : file1.copy4.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:58:19 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:19 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : file1.copy5.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:19 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:19 INFO  : subdir/file20.txt: Copied (new)
2025/08/23 04:58:19 NOTICE: checking path2 B2 bucket rclone-test-penibes5ciyu path 045429we/rmdirs/path2
2025/08/23 04:58:19 NOTICE: (01)  : test rmdirs
2025/08/23 04:58:19 NOTICE: (02)  : test initial bisync
2025/08/23 04:58:19 NOTICE: (03)  : bisync resync
    bisync_test.go:996: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/08/23 04:58:19 INFO  : path1: Making directory
2025/08/23 04:58:19 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/volatile/path1"
2025/08/23 04:58:20 INFO  : path2: Making directory
2025/08/23 04:58:20 DEBUG : Creating backend with remote "TestB2:rclone-test-penibes5ciyu/045429we/volatile/path2"
2025/08/23 04:58:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/08/23 04:58:21 DEBUG : Creating backend with remote "/tmp/045429we/initdir/test_volatile-foniyob9"
2025/08/23 04:58:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : Local file system at /tmp/045429we/initdir/test_volatile-foniyob9: Waiting for checks to finish
2025/08/23 04:58:21 DEBUG : Local file system at /tmp/045429we/initdir/test_volatile-foniyob9: Waiting for transfers to finish
2025/08/23 04:58:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/23 04:58:21 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/23 04:58:21 INFO  : file2.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/23 04:58:21 INFO  : file3.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/23 04:58:21 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/23 04:58:21 INFO  : file4.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/23 04:58:21 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:21 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/23 04:58:21 INFO  : file6.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/23 04:58:21 INFO  : file5.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/23 04:58:21 INFO  : file7.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/08/23 04:58:21 INFO  : file8.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/08/23 04:58:21 DEBUG : Creating backend with remote "/tmp/045429we/datadir/test_volatile-fagosow5"
2025/08/23 04:58:21 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:21 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/23 04:58:21 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/08/23 04:58:21 INFO  : dummy.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : Local file system at /tmp/045429we/datadir/test_volatile-fagosow5: Waiting for checks to finish
2025/08/23 04:58:21 DEBUG : Local file system at /tmp/045429we/datadir/test_volatile-fagosow5: Waiting for transfers to finish
2025/08/23 04:58:21 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:21 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:21 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/08/23 04:58:21 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/08/23 04:58:21 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/08/23 04:58:21 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:21 INFO  : file10.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/08/23 04:58:21 INFO  : file2.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/08/23 04:58:21 INFO  : file11.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:21 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/08/23 04:58:21 INFO  : file7.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/23 04:58:21 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/08/23 04:58:21 INFO  : file6.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/08/23 04:58:21 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/08/23 04:58:21 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/08/23 04:58:21 INFO  : file5R.txt: Copied (new)
2025/08/23 04:58:21 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/08/23 04:58:21 INFO  : file5L.txt: Copied (new)
2025/08/23 04:58:21 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path1: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:22 INFO  : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path2: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:23 NOTICE: checking initFs Local file system at /tmp/045429we/initdir/test_volatile-foniyob9
2025/08/23 04:58:24 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:24 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path1: Waiting for checks to finish
2025/08/23 04:58:24 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path1: Waiting for transfers to finish
2025/08/23 04:58:25 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file3.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file2.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file6.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file5.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:58:25 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:25 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file7.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file4.txt: Copied (new)
2025/08/23 04:58:25 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:25 INFO  : file8.txt: Copied (new)
2025/08/23 04:58:25 NOTICE: checking Path1 B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path1
2025/08/23 04:58:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/23 04:58:26 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path2: Waiting for checks to finish
2025/08/23 04:58:26 DEBUG : B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path2: Waiting for transfers to finish
2025/08/23 04:58:27 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file1.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file2.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file4.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file5.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file6.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/23 04:58:27 INFO  : RCLONE_TEST: Copied (new)
2025/08/23 04:58:27 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file7.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file8.txt: Copied (new)
2025/08/23 04:58:27 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/23 04:58:27 INFO  : file3.txt: Copied (new)
2025/08/23 04:58:27 NOTICE: checking path2 B2 bucket rclone-test-penibes5ciyu path 045429we/volatile/path2
2025/08/23 04:58:28 NOTICE: (01)  : test volatile
2025/08/23 04:58:28 NOTICE: (02)  : test initial bisync
2025/08/23 04:58:28 NOTICE: (03)  : bisync resync
    bisync_test.go:967: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/08/23 04:58:28 DEBUG : B2 bucket rclone-test-penibes5ciyu: Purge remote
2025/08/23 04:58:28 INFO  : B2 bucket rclone-test-penibes5ciyu: cleaning bucket "rclone-test-penibes5ciyu" of all files
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119f5b8a6bebc8f2_d20250823_m045816_c001_v0001101_t0036_u01755925096802")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1151015c0ea0f2c9_d20250823_m045816_c001_v0001041_t0028_u01755925096619")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1187fe0130c0a857_d20250823_m045816_c001_v0001105_t0041_u01755925096245")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f115657d6e62f2ce0_d20250823_m045816_c001_v0001104_t0024_u01755925096426")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1187fe0130c0a85b_d20250823_m045816_c001_v0001105_t0006_u01755925096595")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f115657d6e62f2ce2_d20250823_m045816_c001_v0001104_t0005_u01755925096904")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1151015c0ea0f2cb_d20250823_m045816_c001_v0001041_t0055_u01755925096952")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1187fe0130c0a85d_d20250823_m045817_c001_v0001105_t0002_u01755925097029")
2025/08/23 04:58:28 DEBUG : 045429we/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1011a96a7627b2db_d20250823_m045818_c001_v0001137_t0025_u01755925098990")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11438f02105bdc26_d20250823_m045818_c001_v0001161_t0057_u01755925098427")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1119546b17bcbdd4_d20250823_m045818_c001_v0001113_t0014_u01755925098618")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1027486d495a41a3_d20250823_m045818_c001_v0001090_t0023_u01755925098806")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11438f02105bdc2a_d20250823_m045818_c001_v0001161_t0044_u01755925098763")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1119546b17bcbdd6_d20250823_m045819_c001_v0001113_t0033_u01755925099159")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1027486d495a41a7_d20250823_m045819_c001_v0001090_t0024_u01755925099190")
2025/08/23 04:58:29 DEBUG : 045429we/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f11438f02105bdc2c_d20250823_m045819_c001_v0001161_t0004_u01755925099247")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f118f38a1cf103fb6_d20250823_m045825_c001_v0001038_t0055_u01755925105223")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104f521c0d925359_d20250823_m045824_c001_v0001116_t0001_u01755925104639")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109da28a78667801_d20250823_m045825_c001_v0001183_t0036_u01755925105025")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1072bde93af31347_d20250823_m045824_c001_v0001099_t0001_u01755925104841")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f104f521c0d92535d_d20250823_m045825_c001_v0001116_t0015_u01755925105195")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1072bde93af31349_d20250823_m045825_c001_v0001099_t0029_u01755925105244")
2025/08/23 04:58:29 DEBUG : 045429we/volatile/path1/file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109da28a78667803_d20250823_m045825_c001_v0001183_t0000_u01755925105255")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path1/file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f109da28a78667805_d20250823_m045825_c001_v0001183_t0030_u01755925105459")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path1/file8.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1072bde93af3134d_d20250823_m045825_c001_v0001099_t0016_u01755925105526")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/RCLONE_TEST: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f114c2b3f8a88e689_d20250823_m045827_c001_v0001137_t0054_u01755925107312")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file1.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119c4624b2fdfa19_d20250823_m045826_c001_v0001178_t0048_u01755925106940")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file2.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1024879ded13d86f_d20250823_m045827_c001_v0001183_t0014_u01755925107122")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file3.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1011a96a7627b2eb_d20250823_m045827_c001_v0001137_t0008_u01755925107506")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file4.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119c4624b2fdfa1d_d20250823_m045827_c001_v0001178_t0059_u01755925107149")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file5.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1024879ded13d871_d20250823_m045827_c001_v0001183_t0021_u01755925107328")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file6.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119c4624b2fdfa1f_d20250823_m045827_c001_v0001178_t0039_u01755925107360")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file7.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f1024879ded13d873_d20250823_m045827_c001_v0001183_t0023_u01755925107541")
2025/08/23 04:58:30 DEBUG : 045429we/volatile/path2/file8.txt: Deleting (id "4_z5f423d83407ddb5b9f880e1b_f119c4624b2fdfa21_d20250823_m045827_c001_v0001178_t0059_u01755925107587")
--- PASS: TestBisyncRemoteRemote (242.91s)
    --- PASS: TestBisyncRemoteRemote/resync (91.90s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (129.70s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.48s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.34s)
PASS
2025/08/23 04:58:32 DEBUG : B2 bucket rclone-test-dujofey2givi: Purge remote
2025/08/23 04:58:32 INFO  : B2 bucket rclone-test-dujofey2givi: cleaning bucket "rclone-test-dujofey2givi" of all files
2025/08/23 04:58:32 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^all_changed$|^TestBisyncRemoteLocal$/^ignorelistingchecksum$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 5m4.282613111s (try 2/5)