"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(filters|resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/10/14 05:33:49 DEBUG : Creating backend with remote "TestB2:rclone-test-moharah6mequ"
2025/10/14 05:33:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/14 05:33:50 DEBUG : Creating backend with remote "/tmp/rclone993068659"
=== RUN   TestBisyncRemoteRemote
2025/10/14 05:33:50 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu"
2025/10/14 05:33:50 NOTICE: remote: TestB2:rclone-test-soyerib3detu
=== RUN   TestBisyncRemoteRemote/filters
2025/10/14 05:33:50 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi"
2025/10/14 05:33:51 INFO  : path1: Making directory
2025/10/14 05:33:52 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1"
2025/10/14 05:33:53 INFO  : path2: Making directory
2025/10/14 05:33:53 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2"
2025/10/14 05:33:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filters/initial"
2025/10/14 05:33:55 DEBUG : Creating backend with remote "/tmp/053350mi/initdir/test_filters-coruraz3"
2025/10/14 05:33:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : subdir: Making directory with metadata
2025/10/14 05:33:55 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/14 05:33:55 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/14 05:33:55 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : Local file system at /tmp/053350mi/initdir/test_filters-coruraz3: Waiting for checks to finish
2025/10/14 05:33:55 DEBUG : Local file system at /tmp/053350mi/initdir/test_filters-coruraz3: Waiting for transfers to finish
2025/10/14 05:33:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/14 05:33:55 INFO  : file1.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/14 05:33:55 INFO  : file2.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/14 05:33:55 INFO  : file3.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/14 05:33:55 INFO  : file4.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/14 05:33:55 INFO  : file6.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/14 05:33:55 INFO  : file5.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/14 05:33:55 INFO  : file7.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:33:55 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/14 05:33:55 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:33:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/14 05:33:55 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/14 05:33:55 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:33:55 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/14 05:33:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_filters/modfiles"
2025/10/14 05:33:55 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_filters-pafituh1"
2025/10/14 05:33:55 DEBUG : fileZ.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : filtersfile.flt: Need to transfer - File not found at Destination
2025/10/14 05:33:55 DEBUG : Local file system at /tmp/053350mi/datadir/test_filters-pafituh1: Waiting for checks to finish
2025/10/14 05:33:55 DEBUG : Local file system at /tmp/053350mi/datadir/test_filters-pafituh1: Waiting for transfers to finish
2025/10/14 05:33:55 DEBUG : filtersfile.flt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2025/10/14 05:33:55 DEBUG : filtersfile.flt.e46c0e4d.partial: renamed to: filtersfile.flt
2025/10/14 05:33:55 INFO  : filtersfile.flt: Copied (new)
2025/10/14 05:33:55 DEBUG : fileZ.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:33:55 DEBUG : fileZ.txt.64882095.partial: renamed to: fileZ.txt
2025/10/14 05:33:55 INFO  : fileZ.txt: Copied (new)
2025/10/14 05:33:55 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:33:55 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:33:56 NOTICE: checking initFs Local file system at /tmp/053350mi/initdir/test_filters-coruraz3
2025/10/14 05:33:57 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 INFO  : subdir: Making directory
2025/10/14 05:33:57 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:57 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: Waiting for checks to finish
2025/10/14 05:33:57 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: Waiting for transfers to finish
2025/10/14 05:33:57 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:57 INFO  : file1.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file3.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file4.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file5.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file6.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file2.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : file7.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:58 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:33:58 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:33:58 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:33:58 NOTICE: checking Path1 B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1
2025/10/14 05:33:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 INFO  : subdir: Making directory
2025/10/14 05:33:59 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:33:59 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: Waiting for checks to finish
2025/10/14 05:33:59 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: Waiting for transfers to finish
2025/10/14 05:33:59 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:33:59 INFO  : file2.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file1.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file4.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file3.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:34:00 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:34:00 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file5.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file6.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:34:00 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:00 INFO  : file7.txt: Copied (new)
2025/10/14 05:34:00 NOTICE: checking path2 B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2
2025/10/14 05:34:01 NOTICE: (01)  : test filters
2025/10/14 05:34:01 NOTICE: (02)  : copy-file /tmp/053350mi/datadir/test_filters-pafituh1/filtersfile.flt /tmp/053350mi/workdir/
2025/10/14 05:34:01 DEBUG : copyFile "/tmp/053350mi/datadir/test_filters-pafituh1/filtersfile.flt" to "/tmp/053350mi/workdir/" as ""
2025/10/14 05:34:01 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_filters-pafituh1/filtersfile.flt"
2025/10/14 05:34:01 DEBUG : Creating backend with remote "/tmp/053350mi/workdir/"
2025/10/14 05:34:01 DEBUG : operations.CopyFile "filtersfile.flt" to "Local file system at /tmp/053350mi/workdir" as "filtersfile.flt"
2025/10/14 05:34:01 DEBUG : filtersfile.flt: Need to transfer - File not found at Destination
2025/10/14 05:34:01 DEBUG : filtersfile.flt: md5 = 1cec24a8ae7c33c49693a14d53fd6a96 OK
2025/10/14 05:34:01 DEBUG : filtersfile.flt.e46c0e4d.partial: renamed to: filtersfile.flt
2025/10/14 05:34:01 INFO  : filtersfile.flt: Copied (new)
2025/10/14 05:34:01 NOTICE: (03)  : test resync to force building of the filters md5 hash
2025/10/14 05:34:01 NOTICE: (04)  : bisync filters-file=/tmp/053350mi/workdir/filtersfile.flt resync
2025/10/14 05:34:08 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:08 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:08 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:34: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/10/14 05:34:08 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/"
2025/10/14 05:34:08 INFO  : Using filters file /tmp/053350mi/workdir/filtersfile.flt
2025/10/14 05:34:08 INFO  : Storing filters file hash to /tmp/053350mi/workdir/filtersfile.flt.md5
2025/10/14 05:34:08 INFO  : Copying Path2 files to Path1
2025/10/14 05:34:08 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:34:08 INFO  : There was nothing to transfer
2025/10/14 05:34:08 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:34:09 INFO  : There was nothing to transfer
2025/10/14 05:34:09 INFO  : Resync updating listings
2025/10/14 05:34:09 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/"
2025/10/14 05:34:09 INFO  : Bisync successful
2025/10/14 05:34:09 NOTICE: (05)  : copy-listings resync
2025/10/14 05:34:09 NOTICE: (06)  : test place new files on the remote
2025/10/14 05:34:09 NOTICE: (07)  : touch-glob 2001-01-02 /tmp/053350mi/datadir/test_filters-pafituh1/ fileZ.txt
2025/10/14 05:34:09 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_filters-pafituh1/"
2025/10/14 05:34:09 DEBUG : fs cache: renaming cache item "/tmp/053350mi/datadir/test_filters-pafituh1/" to be canonical "/tmp/053350mi/datadir/test_filters-pafituh1"
2025/10/14 05:34:09 DEBUG : fileZ.txt: Set modification time 2001-01-02
2025/10/14 05:34:09 NOTICE: (08)  : copy-as /tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/ fileZ.txt
2025/10/14 05:34:09 DEBUG : copyFile "/tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/" as "fileZ.txt"
2025/10/14 05:34:09 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt"
2025/10/14 05:34:09 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/"
2025/10/14 05:34:10 DEBUG : operations.CopyFile "fileZ.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2" as "fileZ.txt"
2025/10/14 05:34:10 DEBUG : fileZ.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:12 DEBUG : fileZ.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:34:12 INFO  : fileZ.txt: Copied (new)
2025/10/14 05:34:12 NOTICE: (09)  : copy-as /tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/subdir fileZ.txt
2025/10/14 05:34:12 DEBUG : copyFile "/tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/subdir" as "fileZ.txt"
2025/10/14 05:34:12 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_filters-pafituh1/fileZ.txt"
2025/10/14 05:34:12 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/subdir"
2025/10/14 05:34:13 DEBUG : operations.CopyFile "fileZ.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1/subdir" as "fileZ.txt"
2025/10/14 05:34:13 DEBUG : fileZ.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:14 DEBUG : fileZ.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:34:14 INFO  : fileZ.txt: Copied (new)
2025/10/14 05:34:14 NOTICE: (10)  : test bisync with filters-file. path2-side fileZ.txt will be filtered.
2025/10/14 05:34:14 NOTICE: (11)  : bisync filters-file=/tmp/053350mi/workdir/filtersfile.flt
2025/10/14 05:34:25 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:25 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:34:25 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:34:25 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/"
2025/10/14 05:34:25 INFO  : Using filters file /tmp/053350mi/workdir/filtersfile.flt
2025/10/14 05:34:25 INFO  : Building Path1 and Path2 listings
2025/10/14 05:34:25 INFO  : Path1 checking for diffs
2025/10/14 05:34:25 INFO  : - Path1    File is new               - subdir/fileZ.txt
2025/10/14 05:34:25 INFO  : Path1:    1 changes:    1 new,    0 modified,    0 deleted
2025/10/14 05:34:25 INFO  : Path2 checking for diffs
2025/10/14 05:34:25 INFO  : Applying changes
2025/10/14 05:34:25 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/subdir/fileZ.txt
2025/10/14 05:34:25 INFO  : - Path1    Do queued copies to                - Path2
2025/10/14 05:34:26 INFO  : subdir/fileZ.txt: Copied (server-side copy)
2025/10/14 05:34:26 INFO  : Updating listings
2025/10/14 05:34:26 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/filters/path2/"
2025/10/14 05:34:26 INFO  : Bisync successful
2025/10/14 05:34:26 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:34:26 DEBUG : RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105e3d4d217bae2a_d20251014_m053358_c001_v0001150_t0003_u01760420038311")
2025/10/14 05:34:26 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110db01f3bf1d076_d20251014_m053357_c001_v0001183_t0018_u01760420037730")
2025/10/14 05:34:26 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110fd067a6a50dcb_d20251014_m053358_c001_v0001099_t0005_u01760420038110")
2025/10/14 05:34:26 DEBUG : file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10123381a2936d2e_d20251014_m053357_c001_v0001181_t0007_u01760420037919")
2025/10/14 05:34:26 DEBUG : file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110db01f3bf1d078_d20251014_m053357_c001_v0001183_t0019_u01760420037948")
2025/10/14 05:34:26 DEBUG : file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10123381a2936d32_d20251014_m053358_c001_v0001181_t0037_u01760420038136")
2025/10/14 05:34:26 DEBUG : file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110db01f3bf1d07a_d20251014_m053358_c001_v0001183_t0021_u01760420038159")
2025/10/14 05:34:26 DEBUG : file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10123381a2936d36_d20251014_m053358_c001_v0001181_t0036_u01760420038341")
2025/10/14 05:34:26 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f40776c4bb3c558d9_d20251014_m053419_c001_v7007000_t0000_u01760420059074")
2025/10/14 05:34:27 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10480246d8c4389f_d20251014_m053417_c001_v0001100_t0033_u01760420057447")
2025/10/14 05:34:27 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10123381a2936dee_d20251014_m053415_c001_v0001181_t0001_u01760420055299")
2025/10/14 05:34:27 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4165fb729366c9d1_d20251014_m053404_c001_v7007000_t0000_u01760420044418")
2025/10/14 05:34:27 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103e620e323cdbe9_d20251014_m053402_c001_v0001098_t0032_u01760420042980")
2025/10/14 05:34:27 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110fd067a6a50dd1_d20251014_m053401_c001_v0001099_t0016_u01760420041108")
2025/10/14 05:34:27 DEBUG : subdir/file20.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110db01f3bf1d07e_d20251014_m053358_c001_v0001183_t0058_u01760420038351")
2025/10/14 05:34:27 DEBUG : subdir/fileZ.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f116363c297851e35_d20251014_m053414_c001_v0001182_t0008_u01760420054737")
2025/10/14 05:34:27 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/filters/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:34:28 DEBUG : RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1041af8f8fbd6140_d20251014_m053400_c001_v0001183_t0055_u01760420040228")
2025/10/14 05:34:28 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1070cb859a8f1347_d20251014_m053359_c001_v0001099_t0053_u01760420039668")
2025/10/14 05:34:28 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103206201c393b2e_d20251014_m053359_c001_v0001183_t0004_u01760420039856")
2025/10/14 05:34:28 DEBUG : file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1087b3a4d6974ab9_d20251014_m053400_c001_v0001120_t0053_u01760420040051")
2025/10/14 05:34:28 DEBUG : file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103206201c393b35_d20251014_m053400_c001_v0001183_t0020_u01760420040080")
2025/10/14 05:34:28 DEBUG : file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1070cb859a8f1349_d20251014_m053400_c001_v0001099_t0019_u01760420040189")
2025/10/14 05:34:28 DEBUG : file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103206201c393b3b_d20251014_m053400_c001_v0001183_t0025_u01760420040316")
2025/10/14 05:34:28 DEBUG : file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1087b3a4d6974abb_d20251014_m053400_c001_v0001120_t0022_u01760420040393")
2025/10/14 05:34:28 DEBUG : fileZ.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1040c7fcb48a244e_d20251014_m053411_c001_v0001146_t0019_u01760420051779")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f40290cfc92868e8e_d20251014_m053425_c001_v7007000_t0000_u01760420065146")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100d967af5d0b71e_d20251014_m053423_c001_v0001043_t0002_u01760420063800")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103206201c393c98_d20251014_m053419_c001_v0001183_t0027_u01760420059623")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4182b62e22c8e733_d20251014_m053408_c001_v7007000_t0000_u01760420048191")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f102e5c53e7ad6a5f_d20251014_m053406_c001_v0001184_t0036_u01760420046990")
2025/10/14 05:34:28 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1070cb859a8f1367_d20251014_m053404_c001_v0001099_t0029_u01760420044605")
2025/10/14 05:34:28 DEBUG : subdir/file20.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1041af8f8fbd6144_d20251014_m053400_c001_v0001183_t0035_u01760420040488")
2025/10/14 05:34:28 DEBUG : subdir/fileZ.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101fb5bf2200e89c_d20251014_m053426_c001_v0001183_t0025_u01760420066462")
    bisync_test.go:608: TEST filters PASSED
=== RUN   TestBisyncRemoteRemote/resync
2025/10/14 05:34:29 INFO  : path1: Making directory
2025/10/14 05:34:29 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1"
2025/10/14 05:34:30 INFO  : path2: Making directory
2025/10/14 05:34:30 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2"
2025/10/14 05:34:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/10/14 05:34:31 DEBUG : Creating backend with remote "/tmp/053350mi/initdir/test_resync-fevokes6"
2025/10/14 05:34:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : Local file system at /tmp/053350mi/initdir/test_resync-fevokes6: Waiting for checks to finish
2025/10/14 05:34:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/14 05:34:31 INFO  : file2.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : Local file system at /tmp/053350mi/initdir/test_resync-fevokes6: Waiting for transfers to finish
2025/10/14 05:34:31 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/14 05:34:31 INFO  : file1.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/14 05:34:31 INFO  : file3.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/14 05:34:31 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/14 05:34:31 INFO  : file5.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/14 05:34:31 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:34:31 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/14 05:34:31 INFO  : file4.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:34:31 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/14 05:34:31 INFO  : file6.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/14 05:34:31 INFO  : file7.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/10/14 05:34:31 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6"
2025/10/14 05:34:31 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:31 DEBUG : Local file system at /tmp/053350mi/datadir/test_resync-pihuyux6: Waiting for checks to finish
2025/10/14 05:34:31 DEBUG : Local file system at /tmp/053350mi/datadir/test_resync-pihuyux6: Waiting for transfers to finish
2025/10/14 05:34:31 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:34:31 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:34:31 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/10/14 05:34:31 INFO  : fileB.txt: Copied (new)
2025/10/14 05:34:31 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/10/14 05:34:31 INFO  : fileA.txt: Copied (new)
2025/10/14 05:34:31 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:34:32 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:34:33 NOTICE: checking initFs Local file system at /tmp/053350mi/initdir/test_resync-fevokes6
2025/10/14 05:34:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:33 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1: Waiting for checks to finish
2025/10/14 05:34:33 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1: Waiting for transfers to finish
2025/10/14 05:34:34 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:34 INFO  : file2.txt: Copied (new)
2025/10/14 05:34:34 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:34 INFO  : file1.txt: Copied (new)
2025/10/14 05:34:34 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:34 INFO  : file3.txt: Copied (new)
2025/10/14 05:34:35 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:34:35 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:34:35 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:35 INFO  : file4.txt: Copied (new)
2025/10/14 05:34:35 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:35 INFO  : file6.txt: Copied (new)
2025/10/14 05:34:35 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:35 INFO  : file5.txt: Copied (new)
2025/10/14 05:34:35 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:35 INFO  : file7.txt: Copied (new)
2025/10/14 05:34:35 NOTICE: checking Path1 B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1
2025/10/14 05:34:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:34:36 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2: Waiting for checks to finish
2025/10/14 05:34:36 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2: Waiting for transfers to finish
2025/10/14 05:34:36 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:36 INFO  : file1.txt: Copied (new)
2025/10/14 05:34:37 DEBUG : file2.txt: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/10/14 05:34:37 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/10/14 05:34:37 DEBUG : pacer: Rate limited, increasing sleep to 1s
2025/10/14 05:34:37 DEBUG : file2.txt: Received error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 0/10
2025/10/14 05:34:37 DEBUG : pacer: Reducing sleep to 500ms
2025/10/14 05:34:37 DEBUG : pacer: Reducing sleep to 250ms
2025/10/14 05:34:37 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:37 INFO  : file4.txt: Copied (new)
2025/10/14 05:34:37 DEBUG : pacer: Reducing sleep to 125ms
2025/10/14 05:34:37 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:37 INFO  : file3.txt: Copied (new)
2025/10/14 05:34:37 DEBUG : pacer: Reducing sleep to 62.5ms
2025/10/14 05:34:37 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:34:37 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:34:38 DEBUG : pacer: Reducing sleep to 31.25ms
2025/10/14 05:34:38 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:38 INFO  : file5.txt: Copied (new)
2025/10/14 05:34:38 DEBUG : pacer: Reducing sleep to 15.625ms
2025/10/14 05:34:38 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:38 INFO  : file6.txt: Copied (new)
2025/10/14 05:34:38 DEBUG : pacer: Reducing sleep to 10ms
2025/10/14 05:34:38 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:38 INFO  : file7.txt: Copied (new)
2025/10/14 05:34:38 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:34:38 INFO  : file2.txt: Copied (new)
2025/10/14 05:34:38 NOTICE: checking path2 B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2
2025/10/14 05:34:38 NOTICE: (01)  : test resync
2025/10/14 05:34:38 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/10/14 05:34:38 NOTICE: (03)  : purge-children TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/
2025/10/14 05:34:38 DEBUG : RCLONE_TEST: Remove file
2025/10/14 05:34:39 DEBUG : file1.txt: Remove file
2025/10/14 05:34:39 DEBUG : file2.txt: Remove file
2025/10/14 05:34:39 DEBUG : file3.txt: Remove file
2025/10/14 05:34:39 DEBUG : file4.txt: Remove file
2025/10/14 05:34:39 DEBUG : file5.txt: Remove file
2025/10/14 05:34:40 DEBUG : file6.txt: Remove file
2025/10/14 05:34:40 DEBUG : file7.txt: Remove file
2025/10/14 05:34:40 NOTICE: (04)  : bisync resync
2025/10/14 05:34:48 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:48 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:34:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:34:48 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:34:48 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:34:48 INFO  : Copying Path2 files to Path1
2025/10/14 05:34:48 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:34:49 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/14 05:34:49 INFO  : file2.txt: Copied (server-side copy)
2025/10/14 05:34:49 INFO  : file4.txt: Copied (server-side copy)
2025/10/14 05:34:49 INFO  : file5.txt: Copied (server-side copy)
2025/10/14 05:34:50 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:34:50 INFO  : file6.txt: Copied (server-side copy)
2025/10/14 05:34:50 INFO  : file3.txt: Copied (server-side copy)
2025/10/14 05:34:50 INFO  : file7.txt: Copied (server-side copy)
2025/10/14 05:34:50 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:34:50 INFO  : Resync updating listings
2025/10/14 05:34:50 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:34:50 INFO  : Bisync successful
2025/10/14 05:34:50 NOTICE: (05)  : move-listings empty-path1
2025/10/14 05:34:50 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/10/14 05:34:50 NOTICE: (07)  : purge-children TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/
2025/10/14 05:34:50 DEBUG : RCLONE_TEST: Remove file
2025/10/14 05:34:51 DEBUG : file1.txt: Remove file
2025/10/14 05:34:51 DEBUG : file2.txt: Remove file
2025/10/14 05:34:51 DEBUG : file3.txt: Remove file
2025/10/14 05:34:51 DEBUG : file4.txt: Remove file
2025/10/14 05:34:51 DEBUG : file5.txt: Remove file
2025/10/14 05:34:51 DEBUG : file6.txt: Remove file
2025/10/14 05:34:52 DEBUG : file7.txt: Remove file
2025/10/14 05:34:52 NOTICE: (08)  : bisync resync
2025/10/14 05:35:01 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:35:01 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:35:01 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:35:01 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:35:01 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:01 INFO  : Copying Path2 files to Path1
2025/10/14 05:35:01 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:35:01 INFO  : There was nothing to transfer
2025/10/14 05:35:01 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:35:01 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file4.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file3.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file2.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file7.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file5.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : file6.txt: Copied (server-side copy)
2025/10/14 05:35:02 INFO  : Resync updating listings
2025/10/14 05:35:02 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:02 INFO  : Bisync successful
2025/10/14 05:35:02 NOTICE: (09)  : move-listings empty-path2
2025/10/14 05:35:02 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/10/14 05:35:02 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/053350mi/datadir/test_resync-pihuyux6/ fileA.txt
2025/10/14 05:35:02 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/"
2025/10/14 05:35:02 DEBUG : fs cache: renaming cache item "/tmp/053350mi/datadir/test_resync-pihuyux6/" to be canonical "/tmp/053350mi/datadir/test_resync-pihuyux6"
2025/10/14 05:35:02 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/10/14 05:35:02 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/053350mi/datadir/test_resync-pihuyux6/ fileB.txt
2025/10/14 05:35:02 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync-pihuyux6/" for canonical name "/tmp/053350mi/datadir/test_resync-pihuyux6"
2025/10/14 05:35:02 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/10/14 05:35:02 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/10/14 05:35:02 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/10/14 05:35:02 NOTICE: (15)  : delete-file TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/file1.txt
2025/10/14 05:35:02 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:04 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/" to be canonical "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2"
2025/10/14 05:35:04 INFO  : file1.txt: Deleted
2025/10/14 05:35:04 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/10/14 05:35:04 NOTICE: (17)  : delete-file TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/file2.txt
2025/10/14 05:35:04 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/"
2025/10/14 05:35:05 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" to be canonical "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1"
2025/10/14 05:35:06 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF)
2025/10/14 05:35:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/10/14 05:35:06 DEBUG : pacer: Reducing sleep to 10ms
2025/10/14 05:35:06 INFO  : file2.txt: Deleted
2025/10/14 05:35:06 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/10/14 05:35:06 NOTICE: (19)  : copy-as /tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/ file3.txt
2025/10/14 05:35:06 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/" as "file3.txt"
2025/10/14 05:35:06 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt"
2025/10/14 05:35:06 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:07 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2" as "file3.txt"
2025/10/14 05:35:08 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/14 05:35:09 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:35:09 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/10/14 05:35:09 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/10/14 05:35:09 NOTICE: (21)  : delete-file TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/file4.txt
2025/10/14 05:35:09 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" for canonical name "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1"
2025/10/14 05:35:09 INFO  : file4.txt: Deleted
2025/10/14 05:35:09 NOTICE: (22)  : copy-as /tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/ file4.txt
2025/10/14 05:35:09 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/" as "file4.txt"
2025/10/14 05:35:09 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt"
2025/10/14 05:35:09 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:10 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2" as "file4.txt"
2025/10/14 05:35:11 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/14 05:35:13 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:35:13 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/10/14 05:35:13 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/10/14 05:35:13 NOTICE: (24)  : copy-as /tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/ file5.txt
2025/10/14 05:35:13 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/" as "file5.txt"
2025/10/14 05:35:13 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt"
2025/10/14 05:35:13 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:14 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2" as "file5.txt"
2025/10/14 05:35:14 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/10/14 05:35:16 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:35:16 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/10/14 05:35:16 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/10/14 05:35:16 NOTICE: (26)  : copy-as /tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/ file6.txt
2025/10/14 05:35:16 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/" as "file6.txt"
2025/10/14 05:35:16 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/fileB.txt"
2025/10/14 05:35:16 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path1/"
2025/10/14 05:35:17 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path1" as "file6.txt"
2025/10/14 05:35:17 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/10/14 05:35:19 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:35:19 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/10/14 05:35:19 NOTICE: (27)  : copy-as /tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/ file6.txt
2025/10/14 05:35:19 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/" as "file6.txt"
2025/10/14 05:35:19 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync-pihuyux6/fileA.txt"
2025/10/14 05:35:19 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync/path2/"
2025/10/14 05:35:20 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync/path2" as "file6.txt"
2025/10/14 05:35:20 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/10/14 05:35:22 DEBUG : file6.txt: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/10/14 05:35:22 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/10/14 05:35:22 DEBUG : pacer: Rate limited, increasing sleep to 1s
2025/10/14 05:35:22 DEBUG : fileA.txt: Received error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 0/10
2025/10/14 05:35:22 DEBUG : pacer: Reducing sleep to 500ms
2025/10/14 05:35:23 DEBUG : pacer: Reducing sleep to 250ms
2025/10/14 05:35:23 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/14 05:35:23 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/10/14 05:35:23 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/10/14 05:35:23 NOTICE: (29)  : test run bisync with resync
2025/10/14 05:35:23 NOTICE: (30)  : bisync resync
2025/10/14 05:35:28 DEBUG : modtime_write_test: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/10/14 05:35:28 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/10/14 05:35:28 DEBUG : pacer: Rate limited, increasing sleep to 1s
    bisync_test.go:1025: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1025
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1046
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/usr/local/go/src/strings/iter.go:61
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	no tomes available (503 service_unavailable): trying again in 1s
        	Test:       	TestBisyncRemoteRemote/resync
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/10/14 05:35:28 INFO  : path1: Making directory
2025/10/14 05:35:28 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1"
2025/10/14 05:35:30 INFO  : path2: Making directory
2025/10/14 05:35:30 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2"
2025/10/14 05:35:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/10/14 05:35:31 DEBUG : Creating backend with remote "/tmp/053350mi/initdir/test_resync_modes-vufiwer8"
2025/10/14 05:35:31 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : Local file system at /tmp/053350mi/initdir/test_resync_modes-vufiwer8: Waiting for checks to finish
2025/10/14 05:35:31 DEBUG : Local file system at /tmp/053350mi/initdir/test_resync_modes-vufiwer8: Waiting for transfers to finish
2025/10/14 05:35:31 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:35:31 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:35:31 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/14 05:35:31 INFO  : file1.txt: Copied (new)
2025/10/14 05:35:31 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/14 05:35:31 INFO  : file2.txt: Copied (new)
2025/10/14 05:35:31 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/14 05:35:31 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/14 05:35:31 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:35:31 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/10/14 05:35:31 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:35:31 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:31 DEBUG : Local file system at /tmp/053350mi/datadir/test_resync_modes-kagoxec2: Waiting for checks to finish
2025/10/14 05:35:31 DEBUG : Local file system at /tmp/053350mi/datadir/test_resync_modes-kagoxec2: Waiting for transfers to finish
2025/10/14 05:35:31 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/10/14 05:35:31 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/10/14 05:35:31 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/10/14 05:35:31 INFO  : file1L.txt: Copied (new)
2025/10/14 05:35:31 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/10/14 05:35:31 INFO  : file1R.txt: Copied (new)
2025/10/14 05:35:31 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/10/14 05:35:31 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/10/14 05:35:31 INFO  : file2.txt: Copied (new)
2025/10/14 05:35:31 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:35:31 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:35:32 NOTICE: checking initFs Local file system at /tmp/053350mi/initdir/test_resync_modes-vufiwer8
2025/10/14 05:35:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:35:33 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:33 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:33 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Waiting for checks to finish
2025/10/14 05:35:33 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Waiting for transfers to finish
2025/10/14 05:35:33 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:35:33 INFO  : file2.txt: Copied (new)
2025/10/14 05:35:34 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:35:34 INFO  : file1.txt: Copied (new)
2025/10/14 05:35:34 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:35:34 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:35:34 NOTICE: checking Path1 B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1
2025/10/14 05:35:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:35:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:35:34 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Waiting for checks to finish
2025/10/14 05:35:34 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Waiting for transfers to finish
2025/10/14 05:35:35 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:35:35 INFO  : file1.txt: Copied (new)
2025/10/14 05:35:35 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:35:35 INFO  : file2.txt: Copied (new)
2025/10/14 05:35:36 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:35:36 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:35:36 NOTICE: checking path2 B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2
2025/10/14 05:35:36 NOTICE: (01)  : test resync-mode
2025/10/14 05:35:36 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:35:36 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:35:36 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/"
2025/10/14 05:35:36 DEBUG : fs cache: renaming cache item "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" to be canonical "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:35:36 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/10/14 05:35:36 NOTICE: (04)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:35:36 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:35:36 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:35:36 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:35:37 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:35:37 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/14 05:35:39 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:35:39 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:35:39 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:35:39 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:35:39 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/10/14 05:35:39 NOTICE: (06)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:35:39 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:35:39 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:35:39 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:35:40 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:35:40 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/14 05:35:42 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:35:42 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:35:42 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/10/14 05:35:42 NOTICE: (08)  : bisync resync resync-mode=newer
2025/10/14 05:35:50 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:35:50 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:35:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:35:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:35:50 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:35:50 INFO  : Copying Path2 files to Path1
2025/10/14 05:35:50 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:35:50 INFO  : There was nothing to transfer
2025/10/14 05:35:50 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:35:51 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:35:51 INFO  : Resync updating listings
2025/10/14 05:35:51 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:35:51 INFO  : Bisync successful
2025/10/14 05:35:51 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:35:51 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:35:51 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:35:51 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/10/14 05:35:51 NOTICE: (11)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:35:51 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:35:51 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:35:51 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:35:52 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:35:52 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/14 05:35:54 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:35:54 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:35:54 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:35:54 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:35:54 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/10/14 05:35:54 NOTICE: (13)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:35:54 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:35:54 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:35:54 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:35:55 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:35:55 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/10/14 05:35:55 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:35:56 INFO  : file1L.txt: Updated modification time in destination
2025/10/14 05:35:56 DEBUG : file1L.txt: Unchanged skipping
2025/10/14 05:35:56 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/10/14 05:35:56 NOTICE: (15)  : bisync resync resync-mode=path2
2025/10/14 05:36:05 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:05 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:36: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/10/14 05:36:05 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:05 INFO  : Copying Path2 files to Path1
2025/10/14 05:36:05 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:36:05 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:36:05 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:36:05 INFO  : Resync updating listings
2025/10/14 05:36:05 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:05 INFO  : Bisync successful
2025/10/14 05:36:05 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:36:05 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:36:05 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:05 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/10/14 05:36:05 NOTICE: (18)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:36:05 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:36:05 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:36:05 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:06 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:36:06 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/10/14 05:36:06 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:36:08 INFO  : file1R.txt: Updated modification time in destination
2025/10/14 05:36:08 DEBUG : file1R.txt: Unchanged skipping
2025/10/14 05:36:08 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:36:08 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:08 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/10/14 05:36:08 NOTICE: (20)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:36:08 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:36:08 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:36:08 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:36:09 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:36:09 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/14 05:36:11 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:36:11 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:36:11 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/10/14 05:36:11 NOTICE: (22)  : bisync resync resync-mode=larger
2025/10/14 05:36:19 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:19 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:36:19 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:36:19 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:19 INFO  : Copying Path2 files to Path1
2025/10/14 05:36:19 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:36:20 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/10/14 05:36:20 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:36:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:36:20 INFO  : Resync updating listings
2025/10/14 05:36:20 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:20 INFO  : Bisync successful
2025/10/14 05:36:20 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:36:20 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:36:20 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:20 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/10/14 05:36:20 NOTICE: (25)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:36:20 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:36:20 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:36:20 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:21 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:36:21 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/10/14 05:36:21 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:36:23 INFO  : file1R.txt: Updated modification time in destination
2025/10/14 05:36:23 DEBUG : file1R.txt: Unchanged skipping
2025/10/14 05:36:23 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:36:23 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:23 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/10/14 05:36:23 NOTICE: (27)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:36:23 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:36:23 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:36:23 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:36:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:36:24 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/14 05:36:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:36:26 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:36:26 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/10/14 05:36:26 NOTICE: (29)  : bisync resync resync-mode=older
2025/10/14 05:36:34 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:34 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:34 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:36:34 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:36:34 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:34 INFO  : Copying Path2 files to Path1
2025/10/14 05:36:34 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:36:34 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/10/14 05:36:35 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:36:35 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:36:35 INFO  : Resync updating listings
2025/10/14 05:36:35 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:35 INFO  : Bisync successful
2025/10/14 05:36:35 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:36:35 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:36:35 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:35 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/10/14 05:36:35 NOTICE: (32)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:36:35 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:36:35 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:36:35 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:36 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:36:36 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/10/14 05:36:36 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:36:37 INFO  : file1R.txt: Updated modification time in destination
2025/10/14 05:36:37 DEBUG : file1R.txt: Unchanged skipping
2025/10/14 05:36:37 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:36:37 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:37 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/10/14 05:36:37 NOTICE: (34)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:36:37 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:36:37 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:36:37 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:36:38 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:36:39 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/14 05:36:40 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:36:40 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:36:40 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/10/14 05:36:40 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/10/14 05:36:49 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:49 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:36:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:36:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:36:49 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:49 INFO  : Copying Path2 files to Path1
2025/10/14 05:36:49 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:36:49 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/14 05:36:49 INFO  : There was nothing to transfer
2025/10/14 05:36:49 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:36:49 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/14 05:36:49 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:36:49 INFO  : Resync updating listings
2025/10/14 05:36:49 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:49 INFO  : Bisync successful
2025/10/14 05:36:49 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/14 05:36:49 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:36:49 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:49 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/10/14 05:36:49 NOTICE: (39)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:36:49 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:36:49 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:36:49 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:50 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:36:51 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/14 05:36:53 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:36:53 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:36:53 NOTICE: (40)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file2.txt
2025/10/14 05:36:53 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file2.txt"
2025/10/14 05:36:53 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:36:53 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:36:54 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file2.txt"
2025/10/14 05:36:54 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/14 05:36:56 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:36:56 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/10/14 05:36:56 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:36:56 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:36:56 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/10/14 05:36:56 NOTICE: (42)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:36:56 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:36:56 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:36:56 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:36:57 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:36:57 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/10/14 05:36:57 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:36:58 INFO  : file1L.txt: Updated modification time in destination
2025/10/14 05:36:58 DEBUG : file1L.txt: Unchanged skipping
2025/10/14 05:36:58 NOTICE: (43)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file2.txt
2025/10/14 05:36:58 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file2.txt"
2025/10/14 05:36:58 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:36:58 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:36:59 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file2.txt"
2025/10/14 05:37:00 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/14 05:37:01 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:37:01 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/14 05:37:01 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/10/14 05:37:01 NOTICE: (45)  : bisync resync resync-mode=path1
2025/10/14 05:37:10 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:37:10 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:37:10 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:37:10 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:37:10 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:10 INFO  : Copying Path2 files to Path1
2025/10/14 05:37:10 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:37:10 INFO  : There was nothing to transfer
2025/10/14 05:37:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:37:11 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:37:11 INFO  : file2.txt: Copied (server-side copy)
2025/10/14 05:37:11 INFO  : Resync updating listings
2025/10/14 05:37:11 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:11 INFO  : Bisync successful
2025/10/14 05:37:11 NOTICE: (46)  : test no winner
2025/10/14 05:37:11 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1R.txt
2025/10/14 05:37:11 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:37:11 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/10/14 05:37:11 NOTICE: (48)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file1.txt
2025/10/14 05:37:11 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file1.txt"
2025/10/14 05:37:11 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:37:11 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:12 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file1.txt"
2025/10/14 05:37:12 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/14 05:37:14 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:37:14 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/14 05:37:14 NOTICE: (49)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file2.txt
2025/10/14 05:37:14 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file2.txt"
2025/10/14 05:37:14 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1R.txt"
2025/10/14 05:37:14 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:37:15 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file2.txt"
2025/10/14 05:37:15 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/10/14 05:37:15 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/14 05:37:17 INFO  : file1R.txt: Updated modification time in destination
2025/10/14 05:37:17 DEBUG : file1R.txt: Unchanged skipping
2025/10/14 05:37:17 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/053350mi/datadir/test_resync_modes-kagoxec2/ file1L.txt
2025/10/14 05:37:17 DEBUG : fs cache: switching user supplied name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/" for canonical name "/tmp/053350mi/datadir/test_resync_modes-kagoxec2"
2025/10/14 05:37:17 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/10/14 05:37:17 NOTICE: (51)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/ file1.txt
2025/10/14 05:37:17 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" as "file1.txt"
2025/10/14 05:37:17 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:37:17 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/"
2025/10/14 05:37:18 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1" as "file1.txt"
2025/10/14 05:37:18 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/10/14 05:37:18 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:37:19 INFO  : file1L.txt: Updated modification time in destination
2025/10/14 05:37:19 DEBUG : file1L.txt: Unchanged skipping
2025/10/14 05:37:19 NOTICE: (52)  : copy-as /tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/ file2.txt
2025/10/14 05:37:19 DEBUG : copyFile "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt" to "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/" as "file2.txt"
2025/10/14 05:37:19 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_resync_modes-kagoxec2/file1L.txt"
2025/10/14 05:37:19 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:21 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2" as "file2.txt"
2025/10/14 05:37:21 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/14 05:37:22 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/14 05:37:22 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/14 05:37:22 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/10/14 05:37:22 NOTICE: (54)  : bisync resync resync-mode=newer
2025/10/14 05:37:31 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:37:31 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/14 05:37:31 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/14 05:37:31 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/14 05:37:31 INFO  : Synching Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" with Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:31 INFO  : Copying Path2 files to Path1
2025/10/14 05:37:31 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/14 05:37:32 INFO  : file1.txt: Copied (server-side copy)
2025/10/14 05:37:32 INFO  : file2.txt: Copied (server-side copy)
2025/10/14 05:37:32 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/14 05:37:32 INFO  : Resync updating listings
2025/10/14 05:37:32 INFO  : Validating listings for Path1 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path1/" vs Path2 "TestB2:rclone-test-soyerib3detu/053350mi/resync_modes/path2/"
2025/10/14 05:37:32 INFO  : Bisync successful
2025/10/14 05:37:32 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:33 DEBUG : RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114353e32842a17a_d20251014_m053534_c001_v0001036_t0058_u01760420134146")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11026cbed65db262_d20251014_m053731_c001_v0001120_t0024_u01760420251926")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f102cddb32b631c71_d20251014_m053719_c001_v0001171_t0000_u01760420239811")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105da2eca74ff86e_d20251014_m053658_c001_v0001161_t0037_u01760420218433")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1186f4cf0147ea88_d20251014_m053640_c001_v0001178_t0001_u01760420200380")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107df47e7b49a078_d20251014_m053634_c001_v0001178_t0025_u01760420194962")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1141978d0b1477b9_d20251014_m053626_c001_v0001150_t0005_u01760420186166")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1130da4c7ea2ba75_d20251014_m053620_c001_v0001180_t0041_u01760420180254")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114689d473a9d228_d20251014_m053610_c001_v0001115_t0044_u01760420170885")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f117e0911a8d4cf6e_d20251014_m053605_c001_v0001182_t0029_u01760420165320")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f116a2547363a9f83_d20251014_m053556_c001_v0001179_t0038_u01760420156815")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10918bf9b8160054_d20251014_m053542_c001_v0001171_t0050_u01760420142024")
2025/10/14 05:37:33 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100c911a91f33976_d20251014_m053533_c001_v0001179_t0023_u01760420133967")
2025/10/14 05:37:33 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1191a959ff52be56_d20251014_m053732_c001_v0001131_t0021_u01760420252302")
2025/10/14 05:37:33 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107a67e5b8c0bdc7_d20251014_m053717_c001_v0001100_t0006_u01760420237066")
2025/10/14 05:37:33 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100973ca5146bc7d_d20251014_m053655_c001_v0001146_t0052_u01760420215818")
2025/10/14 05:37:33 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111d2ca03aa30f2c_d20251014_m053533_c001_v0001178_t0034_u01760420133781")
2025/10/14 05:37:33 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f419401ec4494bf05_d20251014_m053726_c001_v7007000_t0000_u01760420246941")
2025/10/14 05:37:33 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1079f5a0cce063ad_d20251014_m053725_c001_v0001104_t0031_u01760420245385")
2025/10/14 05:37:33 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111d2ca03aa312c6_d20251014_m053723_c001_v0001178_t0051_u01760420243248")
2025/10/14 05:37:33 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f409a344462b24314_d20251014_m053706_c001_v7007000_t0000_u01760420226095")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f106165247e9de8a7_d20251014_m053704_c001_v0001184_t0049_u01760420224888")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114353e32842a2a3_d20251014_m053702_c001_v0001036_t0027_u01760420222150")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f408d91f05975cb49_d20251014_m053644_c001_v7007000_t0000_u01760420204957")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103f485d51c893d5_d20251014_m053643_c001_v0001040_t0036_u01760420203096")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100c911a91f33b74_d20251014_m053640_c001_v0001179_t0028_u01760420200952")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f420744ac7478b33b_d20251014_m053630_c001_v7007000_t0000_u01760420190335")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f113774c697f46205_d20251014_m053629_c001_v0001177_t0052_u01760420189124")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111d2ca03aa31110_d20251014_m053626_c001_v0001178_t0014_u01760420186988")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f40290cfc92869094_d20251014_m053615_c001_v7007000_t0000_u01760420175310")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1118478b55846cf9_d20251014_m053614_c001_v0001181_t0053_u01760420174104")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114353e32842a206_d20251014_m053611_c001_v0001036_t0015_u01760420171789")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4149afcae7cdfc6f_d20251014_m053600_c001_v7007000_t0000_u01760420160811")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1077a5c317600402_d20251014_m053559_c001_v0001136_t0047_u01760420159506")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100c911a91f33a2c_d20251014_m053557_c001_v0001179_t0019_u01760420157381")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4149afcae7cdfc1b_d20251014_m053546_c001_v7007000_t0000_u01760420146125")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1106cec31a577560_d20251014_m053544_c001_v0001172_t0049_u01760420144859")
2025/10/14 05:37:34 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111d2ca03aa30f80_d20251014_m053542_c001_v0001178_t0054_u01760420142701")
2025/10/14 05:37:35 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/resync_modes/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:35 DEBUG : RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10907f7d43884c39_d20251014_m053535_c001_v0001176_t0044_u01760420135830")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f102ed92a29017486_d20251014_m053714_c001_v0001091_t0040_u01760420234198")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11730b5de47298c9_d20251014_m053710_c001_v0001153_t0022_u01760420230891")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1042387fc08cbddd_d20251014_m053652_c001_v0001105_t0028_u01760420212468")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11233bbfb48514d5_d20251014_m053649_c001_v0001146_t0004_u01760420209576")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1118478b55846dbb_d20251014_m053637_c001_v0001181_t0006_u01760420197595")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1111f77fb1ca65fe_d20251014_m053622_c001_v0001106_t0033_u01760420182928")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111ae65968d0547f_d20251014_m053608_c001_v0001165_t0030_u01760420168073")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11098fac47af6959_d20251014_m053554_c001_v0001137_t0016_u01760420154016")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114ae12869e93d3c_d20251014_m053551_c001_v0001181_t0045_u01760420151213")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f104108c3a612a215_d20251014_m053538_c001_v0001113_t0033_u01760420138947")
2025/10/14 05:37:35 DEBUG : file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107bee2431661957_d20251014_m053535_c001_v0001134_t0049_u01760420135445")
2025/10/14 05:37:35 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1176d7a0b769421c_d20251014_m053722_c001_v0001177_t0047_u01760420242633")
2025/10/14 05:37:35 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115a59e87f02e502_d20251014_m053711_c001_v0001182_t0044_u01760420231255")
2025/10/14 05:37:35 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1165ca451a1d773e_d20251014_m053701_c001_v0001093_t0021_u01760420221447")
2025/10/14 05:37:36 DEBUG : file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1118478b55846bed_d20251014_m053535_c001_v0001181_t0031_u01760420135636")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506e11_d20251014_m053731_c001_v7007000_t0000_u01760420251539")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1088e3e6db85987a_d20251014_m053729_c001_v0001029_t0026_u01760420249951")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107bee2431661b61_d20251014_m053727_c001_v0001134_t0030_u01760420247488")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4175adbfc6d85e5f_d20251014_m053710_c001_v7007000_t0000_u01760420230309")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f106b1f66d678c5aa_d20251014_m053708_c001_v0001090_t0043_u01760420228788")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10907f7d43884d8e_d20251014_m053706_c001_v0001176_t0032_u01760420226642")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f420744ac7478b34c_d20251014_m053648_c001_v7007000_t0000_u01760420208995")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1153039476a47cd7_d20251014_m053647_c001_v0001157_t0049_u01760420207646")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1118478b55846dfa_d20251014_m053645_c001_v0001181_t0032_u01760420205510")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4192e54f0fa9d7b3_d20251014_m053634_c001_v7007000_t0000_u01760420194570")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1144d13af6d6a6cd_d20251014_m053633_c001_v0001172_t0053_u01760420193217")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107bee2431661a42_d20251014_m053630_c001_v0001134_t0020_u01760420190880")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f422b06bf1c52ce38_d20251014_m053619_c001_v7007000_t0000_u01760420179873")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f114ba2a4bcde25ce_d20251014_m053617_c001_v0001144_t0034_u01760420177983")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10907f7d43884caa_d20251014_m053615_c001_v0001176_t0016_u01760420175857")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f409a344462b24298_d20251014_m053604_c001_v7007000_t0000_u01760420164924")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11684881011ae69e_d20251014_m053603_c001_v0001041_t0051_u01760420163534")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1118478b55846c9d_d20251014_m053601_c001_v0001181_t0009_u01760420161360")
2025/10/14 05:37:36 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f419401ec4494bd4c_d20251014_m053550_c001_v7007000_t0000_u01760420150604")
2025/10/14 05:37:37 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10925b614a7769f1_d20251014_m053549_c001_v0001178_t0043_u01760420149301")
2025/10/14 05:37:37 DEBUG : modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107bee2431661983_d20251014_m053546_c001_v0001134_t0032_u01760420146670")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/10/14 05:37:37 INFO  : path1: Making directory
2025/10/14 05:37:37 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/rmdirs/path1"
2025/10/14 05:37:38 INFO  : path2: Making directory
2025/10/14 05:37:38 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/rmdirs/path2"
2025/10/14 05:37:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/10/14 05:37:39 DEBUG : Creating backend with remote "/tmp/053350mi/initdir/test_rmdirs-sudekaq6"
2025/10/14 05:37:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : subdir: Making directory with metadata
2025/10/14 05:37:39 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/14 05:37:39 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/14 05:37:39 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/10/14 05:37:39 INFO  : file1.copy1.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/10/14 05:37:39 INFO  : file1.copy2.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : Local file system at /tmp/053350mi/initdir/test_rmdirs-sudekaq6: Waiting for checks to finish
2025/10/14 05:37:39 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/10/14 05:37:39 INFO  : file1.copy5.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/10/14 05:37:39 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 INFO  : file1.copy3.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : Local file system at /tmp/053350mi/initdir/test_rmdirs-sudekaq6: Waiting for transfers to finish
2025/10/14 05:37:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/10/14 05:37:39 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/14 05:37:39 INFO  : file1.copy4.txt: Copied (new)
2025/10/14 05:37:39 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:39 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/14 05:37:39 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:37:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/14 05:37:39 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/14 05:37:39 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:39 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/14 05:37:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/10/14 05:37:39 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_rmdirs-jigomej0"
2025/10/14 05:37:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:39 DEBUG : Local file system at /tmp/053350mi/datadir/test_rmdirs-jigomej0: Waiting for checks to finish
2025/10/14 05:37:39 DEBUG : Local file system at /tmp/053350mi/datadir/test_rmdirs-jigomej0: Waiting for transfers to finish
2025/10/14 05:37:39 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:39 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/10/14 05:37:39 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:39 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:40 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:41 NOTICE: checking initFs Local file system at /tmp/053350mi/initdir/test_rmdirs-sudekaq6
2025/10/14 05:37:41 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 INFO  : subdir: Making directory
2025/10/14 05:37:41 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:41 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path1: Waiting for checks to finish
2025/10/14 05:37:41 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path1: Waiting for transfers to finish
2025/10/14 05:37:42 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:42 INFO  : file1.copy2.txt: Copied (new)
2025/10/14 05:37:42 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:42 INFO  : file1.copy4.txt: Copied (new)
2025/10/14 05:37:43 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:43 INFO  : file1.copy3.txt: Copied (new)
2025/10/14 05:37:43 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:37:43 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:43 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:43 INFO  : file1.copy5.txt: Copied (new)
2025/10/14 05:37:43 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:43 INFO  : file1.copy1.txt: Copied (new)
2025/10/14 05:37:43 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:43 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:37:43 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:43 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:43 NOTICE: checking Path1 B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path1
2025/10/14 05:37:43 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 INFO  : subdir: Making directory
2025/10/14 05:37:43 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:43 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path2: Waiting for checks to finish
2025/10/14 05:37:43 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path2: Waiting for transfers to finish
2025/10/14 05:37:44 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:44 INFO  : file1.copy1.txt: Copied (new)
2025/10/14 05:37:44 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:44 INFO  : file1.copy4.txt: Copied (new)
2025/10/14 05:37:44 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:44 INFO  : file1.copy2.txt: Copied (new)
2025/10/14 05:37:45 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:45 INFO  : file1.copy5.txt: Copied (new)
2025/10/14 05:37:45 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:45 INFO  : file1.copy3.txt: Copied (new)
2025/10/14 05:37:45 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:37:45 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:45 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:45 INFO  : subdir/file20.txt: Copied (new)
2025/10/14 05:37:45 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:45 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:45 NOTICE: checking path2 B2 bucket rclone-test-soyerib3detu path 053350mi/rmdirs/path2
2025/10/14 05:37:45 NOTICE: (01)  : test rmdirs
2025/10/14 05:37:45 NOTICE: (02)  : test initial bisync
2025/10/14 05:37:45 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/10/14 05:37:45 INFO  : path1: Making directory
2025/10/14 05:37:45 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/volatile/path1"
2025/10/14 05:37:46 INFO  : path2: Making directory
2025/10/14 05:37:46 DEBUG : Creating backend with remote "TestB2:rclone-test-soyerib3detu/053350mi/volatile/path2"
2025/10/14 05:37:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/10/14 05:37:47 DEBUG : Creating backend with remote "/tmp/053350mi/initdir/test_volatile-gavimiq0"
2025/10/14 05:37:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : Local file system at /tmp/053350mi/initdir/test_volatile-gavimiq0: Waiting for checks to finish
2025/10/14 05:37:47 DEBUG : Local file system at /tmp/053350mi/initdir/test_volatile-gavimiq0: Waiting for transfers to finish
2025/10/14 05:37:47 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/14 05:37:47 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/14 05:37:47 INFO  : file2.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/14 05:37:47 INFO  : file4.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/14 05:37:47 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/14 05:37:47 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:47 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/14 05:37:47 INFO  : file3.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/14 05:37:47 INFO  : file5.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/14 05:37:47 INFO  : file6.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/10/14 05:37:47 INFO  : file8.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/14 05:37:47 INFO  : file7.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/10/14 05:37:47 DEBUG : Creating backend with remote "/tmp/053350mi/datadir/test_volatile-cewesit6"
2025/10/14 05:37:47 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:47 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/14 05:37:47 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/10/14 05:37:47 INFO  : dummy.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : Local file system at /tmp/053350mi/datadir/test_volatile-cewesit6: Waiting for checks to finish
2025/10/14 05:37:47 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:47 DEBUG : Local file system at /tmp/053350mi/datadir/test_volatile-cewesit6: Waiting for transfers to finish
2025/10/14 05:37:47 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/10/14 05:37:47 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:47 INFO  : file11.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/10/14 05:37:47 INFO  : file10.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/10/14 05:37:47 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/10/14 05:37:47 INFO  : file2.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/10/14 05:37:47 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/10/14 05:37:47 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:47 INFO  : file5L.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/10/14 05:37:47 INFO  : file6.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/10/14 05:37:47 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:47 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/10/14 05:37:47 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/10/14 05:37:47 INFO  : file5R.txt: Copied (new)
2025/10/14 05:37:47 INFO  : file7.txt: Copied (new)
2025/10/14 05:37:47 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/14 05:37:47 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/10/14 05:37:47 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:47 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path1: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:48 INFO  : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path2: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:49 NOTICE: checking initFs Local file system at /tmp/053350mi/initdir/test_volatile-gavimiq0
2025/10/14 05:37:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:49 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path1: Waiting for checks to finish
2025/10/14 05:37:49 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path1: Waiting for transfers to finish
2025/10/14 05:37:51 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file2.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file3.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file4.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file5.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:37:51 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:51 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file6.txt: Copied (new)
2025/10/14 05:37:51 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:51 INFO  : file7.txt: Copied (new)
2025/10/14 05:37:52 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:52 INFO  : file8.txt: Copied (new)
2025/10/14 05:37:52 NOTICE: checking Path1 B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path1
2025/10/14 05:37:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/14 05:37:52 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path2: Waiting for checks to finish
2025/10/14 05:37:52 DEBUG : B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path2: Waiting for transfers to finish
2025/10/14 05:37:53 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file2.txt: Copied (new)
2025/10/14 05:37:53 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file3.txt: Copied (new)
2025/10/14 05:37:53 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file5.txt: Copied (new)
2025/10/14 05:37:53 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file4.txt: Copied (new)
2025/10/14 05:37:53 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file6.txt: Copied (new)
2025/10/14 05:37:53 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:53 INFO  : file1.txt: Copied (new)
2025/10/14 05:37:54 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:54 INFO  : file7.txt: Copied (new)
2025/10/14 05:37:54 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/14 05:37:54 INFO  : file8.txt: Copied (new)
2025/10/14 05:37:54 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/14 05:37:54 INFO  : RCLONE_TEST: Copied (new)
2025/10/14 05:37:54 NOTICE: checking path2 B2 bucket rclone-test-soyerib3detu path 053350mi/volatile/path2
2025/10/14 05:37:54 NOTICE: (01)  : test volatile
2025/10/14 05:37:54 NOTICE: (02)  : test initial bisync
2025/10/14 05:37:54 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/10/14 05:37:54 DEBUG : B2 bucket rclone-test-soyerib3detu: Purge remote
2025/10/14 05:37:54 INFO  : B2 bucket rclone-test-soyerib3detu: cleaning bucket "rclone-test-soyerib3detu" of all files
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10461e5ec45bf1cc_d20251014_m053448_c001_v0001099_t0050_u01760420088978")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b1_d20251014_m053439_c001_v7007000_t0000_u01760420079054")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11453a3d5560db30_d20251014_m053434_c001_v0001101_t0017_u01760420074787")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101d098ce1a5b023_d20251014_m053449_c001_v0001106_t0021_u01760420089296")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b3_d20251014_m053439_c001_v7007000_t0000_u01760420079236")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f104f3c4b13b78162_d20251014_m053434_c001_v0001044_t0052_u01760420074220")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f400166412b96e79b_d20251014_m053506_c001_v7007000_t0000_u01760420106601")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11224b32ab956c76_d20251014_m053449_c001_v0001184_t0053_u01760420089350")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b4_d20251014_m053439_c001_v7007000_t0000_u01760420079419")
2025/10/14 05:37:55 DEBUG : 053350mi/resync/path1/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103f485d51c892e1_d20251014_m053434_c001_v0001040_t0006_u01760420074399")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f110f4d4a2e7f5b96_d20251014_m053449_c001_v0001044_t0052_u01760420089367")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b5_d20251014_m053439_c001_v7007000_t0000_u01760420079605")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115ddb534b155db3_d20251014_m053434_c001_v0001042_t0048_u01760420074593")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f400166412b96e7b9_d20251014_m053509_c001_v7007000_t0000_u01760420109795")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1138ce24620ee19e_d20251014_m053449_c001_v0001161_t0048_u01760420089375")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b6_d20251014_m053439_c001_v7007000_t0000_u01760420079790")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103f485d51c892e5_d20251014_m053434_c001_v0001040_t0029_u01760420074830")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105b44768b9e1c68_d20251014_m053449_c001_v0001099_t0021_u01760420089605")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b7_d20251014_m053439_c001_v7007000_t0000_u01760420079973")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f104f3c4b13b78166_d20251014_m053434_c001_v0001044_t0018_u01760420074860")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11753fcafed3cabf_d20251014_m053518_c001_v0001042_t0014_u01760420118862")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11243d6bced33859_d20251014_m053449_c001_v0001150_t0033_u01760420089729")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b8_d20251014_m053440_c001_v7007000_t0000_u01760420080159")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115ddb534b155db5_d20251014_m053435_c001_v0001042_t0017_u01760420075022")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1038b8df985c0d9b_d20251014_m053449_c001_v0001144_t0006_u01760420089990")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679b9_d20251014_m053440_c001_v7007000_t0000_u01760420080388")
2025/10/14 05:37:56 DEBUG : 053350mi/resync/path1/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11453a3d5560db32_d20251014_m053435_c001_v0001101_t0014_u01760420075248")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f420744ac7478b303_d20251014_m053528_c001_v7007000_t0000_u01760420128280")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10602c59c2b9fb49_d20251014_m053527_c001_v0001181_t0038_u01760420127066")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f104f3c4b13b781ae_d20251014_m053524_c001_v0001044_t0053_u01760420124417")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f409a344462b23e5d_d20251014_m053456_c001_v7007000_t0000_u01760420096221")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105164418e761a4a_d20251014_m053455_c001_v0001165_t0057_u01760420095005")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115ddb534b155e07_d20251014_m053452_c001_v0001042_t0028_u01760420092807")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4121dc0fd24679c0_d20251014_m053444_c001_v7007000_t0000_u01760420084122")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f113256d0f0382b54_d20251014_m053442_c001_v0001184_t0031_u01760420082872")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path1/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103f485d51c892fa_d20251014_m053440_c001_v0001040_t0003_u01760420080935")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1157f9765e4ae108_d20251014_m053501_c001_v0001154_t0003_u01760420101644")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c13_d20251014_m053450_c001_v7007000_t0000_u01760420090971")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1005a0496abf1954_d20251014_m053437_c001_v0001165_t0050_u01760420077295")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f406528ccdacbad4e_d20251014_m053504_c001_v7007000_t0000_u01760420104651")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1061843deed9ebf3_d20251014_m053502_c001_v0001183_t0052_u01760420102028")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c15_d20251014_m053451_c001_v7007000_t0000_u01760420091156")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101b7aea93c78879_d20251014_m053436_c001_v0001146_t0007_u01760420076720")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1117cf703d30cf20_d20251014_m053502_c001_v0001184_t0044_u01760420102034")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c17_d20251014_m053451_c001_v7007000_t0000_u01760420091341")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1061843deed9ea86_d20251014_m053438_c001_v0001183_t0000_u01760420078478")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f113774c697f45fb2_d20251014_m053509_c001_v0001177_t0024_u01760420109381")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1007a93f141ca4d2_d20251014_m053502_c001_v0001150_t0048_u01760420102044")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c19_d20251014_m053451_c001_v7007000_t0000_u01760420091523")
2025/10/14 05:37:57 DEBUG : 053350mi/resync/path2/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11380a40b93f2fcf_d20251014_m053437_c001_v0001098_t0045_u01760420077096")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1132a58d42854d93_d20251014_m053512_c001_v0001115_t0014_u01760420112534")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f117c63d487468b98_d20251014_m053502_c001_v0001180_t0056_u01760420102053")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c1a_d20251014_m053451_c001_v7007000_t0000_u01760420091708")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101b7aea93c7887d_d20251014_m053437_c001_v0001146_t0023_u01760420077014")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f106e1b9c4491f98c_d20251014_m053515_c001_v0001044_t0041_u01760420115901")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101a7fffe8890328_d20251014_m053502_c001_v0001042_t0015_u01760420102271")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c1b_d20251014_m053451_c001_v7007000_t0000_u01760420091891")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101b7aea93c78881_d20251014_m053438_c001_v0001146_t0002_u01760420078166")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f108207af81d909a3_d20251014_m053523_c001_v0001040_t0031_u01760420123574")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f108eb54d446cc0d5_d20251014_m053502_c001_v0001098_t0028_u01760420102291")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c1d_d20251014_m053452_c001_v7007000_t0000_u01760420092078")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11380a40b93f2fd7_d20251014_m053438_c001_v0001098_t0055_u01760420078228")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f106d0ebfedf57892_d20251014_m053502_c001_v0001036_t0045_u01760420102317")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c1f_d20251014_m053452_c001_v7007000_t0000_u01760420092261")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1005a0496abf1958_d20251014_m053438_c001_v0001165_t0037_u01760420078293")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c2c_d20251014_m053501_c001_v7007000_t0000_u01760420101001")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f106e500773538379_d20251014_m053459_c001_v0001117_t0043_u01760420099264")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f11380a40b93f303f_d20251014_m053456_c001_v0001098_t0029_u01760420096768")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f4231b7539e506c0d_d20251014_m053448_c001_v7007000_t0000_u01760420088591")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10822baf375220a3_d20251014_m053447_c001_v0001043_t0028_u01760420087069")
2025/10/14 05:37:58 DEBUG : 053350mi/resync/path2/modtime_write_test: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101b7aea93c7889f_d20251014_m053444_c001_v0001146_t0028_u01760420084665")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1034853157ce9d6d_d20251014_m053742_c001_v0001092_t0057_u01760420262894")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.copy1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10599e9319f32365_d20251014_m053742_c001_v0001116_t0028_u01760420262517")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.copy2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105a20e80f5c2f48_d20251014_m053742_c001_v0001113_t0002_u01760420262332")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.copy3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101513c157a73a45_d20251014_m053742_c001_v0001090_t0059_u01760420262738")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.copy4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105a20e80f5c2f4a_d20251014_m053742_c001_v0001113_t0048_u01760420262676")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.copy5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105a20e80f5c2f50_d20251014_m053742_c001_v0001113_t0020_u01760420262919")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f101513c157a73a47_d20251014_m053743_c001_v0001090_t0046_u01760420263101")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path1/subdir/file20.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1034853157ce9d71_d20251014_m053743_c001_v0001092_t0049_u01760420263209")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1040e4301f1246ff_d20251014_m053745_c001_v0001182_t0059_u01760420265062")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.copy1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103c1c954749a06f_d20251014_m053744_c001_v0001177_t0056_u01760420264496")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.copy2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100a5cbd345490db_d20251014_m053744_c001_v0001157_t0050_u01760420264687")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.copy3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f117d061639e71eb4_d20251014_m053744_c001_v0001130_t0026_u01760420264873")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.copy4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103c1c954749a071_d20251014_m053744_c001_v0001177_t0023_u01760420264705")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.copy5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103c1c954749a073_d20251014_m053744_c001_v0001177_t0057_u01760420264913")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f100a5cbd345490df_d20251014_m053745_c001_v0001157_t0037_u01760420265089")
2025/10/14 05:37:59 DEBUG : 053350mi/rmdirs/path2/subdir/file20.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f103c1c954749a075_d20251014_m053745_c001_v0001177_t0006_u01760420265127")
2025/10/14 05:37:59 DEBUG : 053350mi/volatile/path1/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f102cfd37e3c88fc3_d20251014_m053751_c001_v0001137_t0036_u01760420271164")
2025/10/14 05:37:59 DEBUG : 053350mi/volatile/path1/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107cca10d73f8278_d20251014_m053750_c001_v0001102_t0030_u01760420270784")
2025/10/14 05:37:59 DEBUG : 053350mi/volatile/path1/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1153595d3ce9f2b5_d20251014_m053750_c001_v0001102_t0005_u01760420270596")
2025/10/14 05:37:59 DEBUG : 053350mi/volatile/path1/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115580d1c8e758aa_d20251014_m053750_c001_v0001130_t0002_u01760420270971")
2025/10/14 05:37:59 DEBUG : 053350mi/volatile/path1/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1153595d3ce9f2b9_d20251014_m053751_c001_v0001102_t0032_u01760420271104")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path1/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107cca10d73f827e_d20251014_m053751_c001_v0001102_t0046_u01760420271194")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path1/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f115580d1c8e758ac_d20251014_m053751_c001_v0001130_t0037_u01760420271281")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path1/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f1153595d3ce9f2bb_d20251014_m053751_c001_v0001102_t0034_u01760420271521")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path1/file8.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f107cca10d73f8280_d20251014_m053751_c001_v0001102_t0034_u01760420271524")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/RCLONE_TEST: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f105568550bcf6bb6_d20251014_m053753_c001_v0001090_t0038_u01760420273622")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file1.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10725f58f5bacbaa_d20251014_m053753_c001_v0001115_t0041_u01760420273050")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file2.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111674adf1afc646_d20251014_m053753_c001_v0001099_t0011_u01760420273247")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file3.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10602c59c2ba000c_d20251014_m053753_c001_v0001181_t0025_u01760420273436")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file4.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111674adf1afc648_d20251014_m053753_c001_v0001099_t0027_u01760420273572")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file5.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10602c59c2ba0010_d20251014_m053753_c001_v0001181_t0019_u01760420273648")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file6.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10602c59c2ba0012_d20251014_m053753_c001_v0001181_t0023_u01760420273871")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file7.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f111674adf1afc64c_d20251014_m053753_c001_v0001099_t0021_u01760420273944")
2025/10/14 05:38:00 DEBUG : 053350mi/volatile/path2/file8.txt: Deleting (id "4_zbff2edf3305deb1b9f980e1b_f10602c59c2ba0014_d20251014_m053754_c001_v0001181_t0024_u01760420274077")
--- FAIL: TestBisyncRemoteRemote (251.11s)
    --- PASS: TestBisyncRemoteRemote/filters (38.44s)
    --- FAIL: TestBisyncRemoteRemote/resync (59.60s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (128.56s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.24s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.90s)
FAIL
2025/10/14 05:38:01 DEBUG : B2 bucket rclone-test-moharah6mequ: Purge remote
2025/10/14 05:38:01 INFO  : B2 bucket rclone-test-moharah6mequ: cleaning bucket "rclone-test-moharah6mequ" of all files
2025/10/14 05:38:02 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(filters|resync|resync_modes|rmdirs|volatile)$'" - Finished ERROR in 4m12.492001409s (try 2/5): exit status 1: Failed [TestBisyncRemoteRemote/resync]