"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^resync$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/11/09 03:20:35 DEBUG : Creating backend with remote "TestB2:rclone-test-cosebad4tija"
2025/11/09 03:20:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/09 03:20:35 DEBUG : Creating backend with remote "/tmp/rclone2912200222"
=== RUN   TestBisyncLocalRemote
2025/11/09 03:20:35 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye"
2025/11/09 03:20:36 NOTICE: remote: TestB2:rclone-test-roqaxew5huye
=== RUN   TestBisyncLocalRemote/resync
2025/11/09 03:20:36 DEBUG : Creating backend with remote "/tmp/032036ye"
2025/11/09 03:20:36 DEBUG : Creating backend with remote "/tmp/032036ye/resync/path1"
2025/11/09 03:20:36 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye"
2025/11/09 03:20:37 INFO  : path2: Making directory
2025/11/09 03:20:38 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2"
2025/11/09 03:20:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/11/09 03:20:39 DEBUG : Creating backend with remote "/tmp/032036ye/initdir/test_resync-voriqep3"
2025/11/09 03:20:39 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/09 03:20:39 INFO  : file2.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : Local file system at /tmp/032036ye/initdir/test_resync-voriqep3: Waiting for checks to finish
2025/11/09 03:20:39 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/09 03:20:39 INFO  : file4.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : Local file system at /tmp/032036ye/initdir/test_resync-voriqep3: Waiting for transfers to finish
2025/11/09 03:20:39 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:20:39 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:20:39 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:20:39 INFO  : file1.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:20:39 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/09 03:20:39 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:20:39 INFO  : file3.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/11/09 03:20:39 INFO  : file5.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/11/09 03:20:39 INFO  : file6.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/11/09 03:20:39 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:39 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/11/09 03:20:39 INFO  : file7.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/11/09 03:20:39 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4"
2025/11/09 03:20:39 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:39 DEBUG : Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4: Waiting for checks to finish
2025/11/09 03:20:39 DEBUG : Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4: Waiting for transfers to finish
2025/11/09 03:20:39 DEBUG : fileA.txt.04c13af3.partial: size = 19 OK
2025/11/09 03:20:39 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:20:39 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/11/09 03:20:39 INFO  : fileA.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : fileB.txt.b8ec97e6.partial: size = 19 OK
2025/11/09 03:20:39 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:20:39 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/11/09 03:20:39 INFO  : fileB.txt: Copied (new)
2025/11/09 03:20:39 DEBUG : Waiting for deletions to finish
2025/11/09 03:20:39 ERROR : error listing: directory not found
2025/11/09 03:20:39 ERROR : Local file system at /tmp/032036ye/resync/path1: Failed to list "": directory not found
2025/11/09 03:20:39 DEBUG : removing 1 level 0 directories
2025/11/09 03:20:39 INFO  : Local file system at /tmp/032036ye/resync/path1: Removing directory
2025/11/09 03:20:39 ERROR : Failed to rmdir: stat /tmp/032036ye/resync/path1: no such file or directory
2025/11/09 03:20:39 INFO  : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: cleaning bucket "rclone-test-roqaxew5huye" of all files
2025/11/09 03:20:40 ERROR : error listing: directory not found
2025/11/09 03:20:40 NOTICE: checking initFs Local file system at /tmp/032036ye/initdir/test_resync-voriqep3
2025/11/09 03:20:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:20:40 INFO  : file1.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/09 03:20:40 INFO  : file2.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/09 03:20:40 INFO  : file4.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : Local file system at /tmp/032036ye/resync/path1: Waiting for checks to finish
2025/11/09 03:20:40 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/09 03:20:40 INFO  : file3.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : Local file system at /tmp/032036ye/resync/path1: Waiting for transfers to finish
2025/11/09 03:20:40 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/11/09 03:20:40 INFO  : file7.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/11/09 03:20:40 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:20:40 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:20:40 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/11/09 03:20:40 INFO  : file6.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/11/09 03:20:40 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:20:40 INFO  : file5.txt: Copied (new)
2025/11/09 03:20:40 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:20:40 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:20:40 NOTICE: checking Path1 Local file system at /tmp/032036ye/resync/path1
2025/11/09 03:20:40 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:20:40 DEBUG : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: Waiting for checks to finish
2025/11/09 03:20:40 DEBUG : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: Waiting for transfers to finish
2025/11/09 03:20:41 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:20:41 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:41 INFO  : file1.txt: Copied (new)
2025/11/09 03:20:41 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:20:41 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:41 INFO  : file2.txt: Copied (new)
2025/11/09 03:20:41 DEBUG : file4.txt: size = 0 OK
2025/11/09 03:20:41 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:41 INFO  : file4.txt: Copied (new)
2025/11/09 03:20:41 DEBUG : file3.txt: size = 0 OK
2025/11/09 03:20:41 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:41 INFO  : file3.txt: Copied (new)
2025/11/09 03:20:41 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:20:41 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:20:41 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:20:42 DEBUG : file6.txt: size = 0 OK
2025/11/09 03:20:42 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:42 INFO  : file6.txt: Copied (new)
2025/11/09 03:20:42 DEBUG : file5.txt: size = 0 OK
2025/11/09 03:20:42 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:42 INFO  : file5.txt: Copied (new)
2025/11/09 03:20:42 DEBUG : file7.txt: size = 0 OK
2025/11/09 03:20:42 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:20:42 INFO  : file7.txt: Copied (new)
2025/11/09 03:20:42 NOTICE: checking path2 B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2
2025/11/09 03:20:42 NOTICE: (01)  : test resync
2025/11/09 03:20:42 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/11/09 03:20:42 NOTICE: (03)  : purge-children /tmp/032036ye/resync/path1/
2025/11/09 03:20:42 DEBUG : file6.txt: Remove file
2025/11/09 03:20:42 DEBUG : file4.txt: Remove file
2025/11/09 03:20:42 DEBUG : file1.txt: Remove file
2025/11/09 03:20:42 DEBUG : file3.txt: Remove file
2025/11/09 03:20:42 DEBUG : RCLONE_TEST: Remove file
2025/11/09 03:20:42 DEBUG : file5.txt: Remove file
2025/11/09 03:20:42 DEBUG : file7.txt: Remove file
2025/11/09 03:20:42 DEBUG : file2.txt: Remove file
2025/11/09 03:20:42 NOTICE: (04)  : bisync resync
2025/11/09 03:20:45 DEBUG : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:20:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:20:45 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/09 03:20:45 INFO  : Synching Path1 "/tmp/032036ye/resync/path1/" with Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:45 INFO  : Copying Path2 files to Path1
2025/11/09 03:20:45 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:20:45 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:20:46 INFO  : file1.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file2.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file5.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file4.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file3.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file6.txt: Copied (new)
2025/11/09 03:20:46 INFO  : file7.txt: Copied (new)
2025/11/09 03:20:46 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:20:46 INFO  : Resync updating listings
2025/11/09 03:20:46 INFO  : Validating listings for Path1 "/tmp/032036ye/resync/path1/" vs Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:46 INFO  : Bisync successful
2025/11/09 03:20:46 NOTICE: (05)  : move-listings empty-path1
2025/11/09 03:20:46 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/11/09 03:20:46 NOTICE: (07)  : purge-children TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/
2025/11/09 03:20:46 DEBUG : RCLONE_TEST: Remove file
2025/11/09 03:20:47 DEBUG : file1.txt: Remove file
2025/11/09 03:20:47 DEBUG : file2.txt: Remove file
2025/11/09 03:20:47 DEBUG : file3.txt: Remove file
2025/11/09 03:20:47 DEBUG : file4.txt: Remove file
2025/11/09 03:20:47 DEBUG : file5.txt: Remove file
2025/11/09 03:20:47 DEBUG : file6.txt: Remove file
2025/11/09 03:20:48 DEBUG : file7.txt: Remove file
2025/11/09 03:20:48 NOTICE: (08)  : bisync resync
2025/11/09 03:20:51 DEBUG : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:20:51 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:20:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/09 03:20:51 INFO  : Synching Path1 "/tmp/032036ye/resync/path1/" with Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:51 INFO  : Copying Path2 files to Path1
2025/11/09 03:20:51 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:20:51 INFO  : There was nothing to transfer
2025/11/09 03:20:51 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:20:52 INFO  : file3.txt: Copied (new)
2025/11/09 03:20:52 INFO  : file2.txt: Copied (new)
2025/11/09 03:20:52 INFO  : file1.txt: Copied (new)
2025/11/09 03:20:52 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:20:52 INFO  : file5.txt: Copied (new)
2025/11/09 03:20:52 INFO  : file4.txt: Copied (new)
2025/11/09 03:20:53 INFO  : file6.txt: Copied (new)
2025/11/09 03:20:53 INFO  : file7.txt: Copied (new)
2025/11/09 03:20:53 INFO  : Resync updating listings
2025/11/09 03:20:53 INFO  : Validating listings for Path1 "/tmp/032036ye/resync/path1/" vs Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:53 INFO  : Bisync successful
2025/11/09 03:20:53 NOTICE: (09)  : move-listings empty-path2
2025/11/09 03:20:53 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/11/09 03:20:53 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/032036ye/datadir/test_resync-gapacoj4/ fileA.txt
2025/11/09 03:20:53 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/"
2025/11/09 03:20:53 DEBUG : fs cache: renaming cache item "/tmp/032036ye/datadir/test_resync-gapacoj4/" to be canonical "/tmp/032036ye/datadir/test_resync-gapacoj4"
2025/11/09 03:20:53 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/11/09 03:20:53 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/032036ye/datadir/test_resync-gapacoj4/ fileB.txt
2025/11/09 03:20:53 DEBUG : fs cache: switching user supplied name "/tmp/032036ye/datadir/test_resync-gapacoj4/" for canonical name "/tmp/032036ye/datadir/test_resync-gapacoj4"
2025/11/09 03:20:53 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/11/09 03:20:53 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/11/09 03:20:53 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/11/09 03:20:53 NOTICE: (15)  : delete-file TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/file1.txt
2025/11/09 03:20:53 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:54 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/" to be canonical "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2"
2025/11/09 03:20:54 INFO  : file1.txt: Deleted
2025/11/09 03:20:54 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/11/09 03:20:54 NOTICE: (17)  : delete-file /tmp/032036ye/resync/path1/file2.txt
2025/11/09 03:20:54 DEBUG : Creating backend with remote "/tmp/032036ye/resync/path1/"
2025/11/09 03:20:54 DEBUG : fs cache: renaming cache item "/tmp/032036ye/resync/path1/" to be canonical "/tmp/032036ye/resync/path1"
2025/11/09 03:20:54 INFO  : file2.txt: Deleted
2025/11/09 03:20:54 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/11/09 03:20:54 NOTICE: (19)  : copy-as /tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/ file3.txt
2025/11/09 03:20:54 DEBUG : copyFile "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt" to "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/" as "file3.txt"
2025/11/09 03:20:54 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt"
2025/11/09 03:20:54 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:55 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2" as "file3.txt"
2025/11/09 03:20:56 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4)
2025/11/09 03:20:56 DEBUG : file3.txt: size = 0 (B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2)
2025/11/09 03:20:56 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:20:58 DEBUG : file3.txt: size = 19 OK
2025/11/09 03:20:58 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:20:58 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/11/09 03:20:58 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/11/09 03:20:58 NOTICE: (21)  : delete-file /tmp/032036ye/resync/path1/file4.txt
2025/11/09 03:20:58 DEBUG : fs cache: switching user supplied name "/tmp/032036ye/resync/path1/" for canonical name "/tmp/032036ye/resync/path1"
2025/11/09 03:20:58 INFO  : file4.txt: Deleted
2025/11/09 03:20:58 NOTICE: (22)  : copy-as /tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/ file4.txt
2025/11/09 03:20:58 DEBUG : copyFile "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt" to "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/" as "file4.txt"
2025/11/09 03:20:58 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt"
2025/11/09 03:20:58 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:20:59 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2" as "file4.txt"
2025/11/09 03:20:59 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4)
2025/11/09 03:20:59 DEBUG : file4.txt: size = 0 (B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2)
2025/11/09 03:20:59 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:21:00 DEBUG : file4.txt: size = 19 OK
2025/11/09 03:21:00 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:21:00 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/11/09 03:21:00 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/11/09 03:21:00 NOTICE: (24)  : copy-as /tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/ file5.txt
2025/11/09 03:21:00 DEBUG : copyFile "/tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt" to "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/" as "file5.txt"
2025/11/09 03:21:00 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt"
2025/11/09 03:21:00 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:21:02 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2" as "file5.txt"
2025/11/09 03:21:02 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4)
2025/11/09 03:21:02 DEBUG : file5.txt: size = 0 (B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2)
2025/11/09 03:21:02 DEBUG : fileB.txt: Sizes differ
2025/11/09 03:21:03 DEBUG : file5.txt: size = 19 OK
2025/11/09 03:21:03 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:21:03 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/11/09 03:21:03 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/11/09 03:21:03 NOTICE: (26)  : copy-as /tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt /tmp/032036ye/resync/path1/ file6.txt
2025/11/09 03:21:03 DEBUG : copyFile "/tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt" to "/tmp/032036ye/resync/path1/" as "file6.txt"
2025/11/09 03:21:03 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/fileB.txt"
2025/11/09 03:21:03 DEBUG : Creating backend with remote "/tmp/032036ye/resync/path1/"
2025/11/09 03:21:03 DEBUG : operations.CopyFile "fileB.txt" to "Local file system at /tmp/032036ye/resync/path1" as "file6.txt"
2025/11/09 03:21:03 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4)
2025/11/09 03:21:03 DEBUG : file6.txt: size = 0 (Local file system at /tmp/032036ye/resync/path1)
2025/11/09 03:21:03 DEBUG : fileB.txt: Sizes differ
2025/11/09 03:21:03 DEBUG : file6.txt.237b502b.partial: size = 19 OK
2025/11/09 03:21:03 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:21:03 DEBUG : file6.txt.237b502b.partial: renamed to: file6.txt
2025/11/09 03:21:03 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/11/09 03:21:03 NOTICE: (27)  : copy-as /tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/ file6.txt
2025/11/09 03:21:03 DEBUG : copyFile "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt" to "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/" as "file6.txt"
2025/11/09 03:21:03 DEBUG : Creating backend with remote "/tmp/032036ye/datadir/test_resync-gapacoj4/fileA.txt"
2025/11/09 03:21:03 DEBUG : Creating backend with remote "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:21:05 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2" as "file6.txt"
2025/11/09 03:21:05 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032036ye/datadir/test_resync-gapacoj4)
2025/11/09 03:21:05 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2)
2025/11/09 03:21:05 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:21:07 DEBUG : file6.txt: size = 19 OK
2025/11/09 03:21:07 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:21:07 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/11/09 03:21:07 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/11/09 03:21:07 NOTICE: (29)  : test run bisync with resync
2025/11/09 03:21:07 NOTICE: (30)  : bisync resync
2025/11/09 03:21:11 DEBUG : B2 bucket rclone-test-roqaxew5huye path 032036ye/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:21:11 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:21:11 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/11/09 03:21:11 INFO  : Synching Path1 "/tmp/032036ye/resync/path1/" with Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:21:11 INFO  : Copying Path2 files to Path1
2025/11/09 03:21:11 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:21:11 INFO  : file2.txt: Copied (new)
2025/11/09 03:21:11 INFO  : file4.txt: Copied (new)
2025/11/09 03:21:11 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:21:12 INFO  : file6.txt: Updated modification time in destination
2025/11/09 03:21:12 INFO  : file3.txt: Copied (replaced existing)
2025/11/09 03:21:12 INFO  : file1.txt: Copied (new)
2025/11/09 03:21:12 INFO  : file5.txt: Copied (replaced existing)
2025/11/09 03:21:12 INFO  : Resync updating listings
2025/11/09 03:21:12 INFO  : Validating listings for Path1 "/tmp/032036ye/resync/path1/" vs Path2 "TestB2:rclone-test-roqaxew5huye/032036ye/resync/path2/"
2025/11/09 03:21:12 INFO  : Bisync successful
2025/11/09 03:21:12 NOTICE: (31)  : copy-listings mixed-diffs
2025/11/09 03:21:12 NOTICE: (32)  : test run normal bisync
2025/11/09 03:21:12 NOTICE: (33)  : bisync
2025/11/09 03:21:12 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1176-10.backblaze.com/b2api/v1/b2_upload_file/8fa24db3a01d6b6b9fa80e1b/c001_v0001176_t0001": EOF
2025/11/09 03:21:12 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1176-10.backblaze.com/b2api/v1/b2_upload_file/8fa24db3a01d6b6b9fa80e1b/c001_v0001176_t0001": EOF)
2025/11/09 03:21:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    bisync_test.go:1025: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1025
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1046
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/usr/local/go/src/strings/iter.go:61
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	Post "https://pod-000-1176-10.backblaze.com/b2api/v1/b2_upload_file/8fa24db3a01d6b6b9fa80e1b/c001_v0001176_t0001": EOF
        	Test:       	TestBisyncLocalRemote/resync
2025/11/09 03:21:12 DEBUG : B2 bucket rclone-test-roqaxew5huye: Purge remote
2025/11/09 03:21:12 INFO  : B2 bucket rclone-test-roqaxew5huye: cleaning bucket "rclone-test-roqaxew5huye" of all files
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/RCLONE_TEST: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f111c3cf4d1c60bc1_d20251109_m032052_c001_v0001029_t0000_u01762658452502")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/RCLONE_TEST: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130902_d20251109_m032046_c001_v7007000_t0000_u01762658446915")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/RCLONE_TEST: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1109237d2b36fead_d20251109_m032041_c001_v0001176_t0054_u01762658441753")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file1.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c0e3_d20251109_m032112_c001_v0001145_t0031_u01762658472075")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file1.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130910_d20251109_m032054_c001_v7007000_t0000_u01762658454684")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file1.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1172af2ed3e13bf3_d20251109_m032052_c001_v0001105_t0055_u01762658452522")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file1.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130904_d20251109_m032047_c001_v7007000_t0000_u01762658447097")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file1.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c051_d20251109_m032041_c001_v0001145_t0024_u01762658441182")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file2.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1109237d2b36fefa_d20251109_m032052_c001_v0001176_t0051_u01762658452528")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file2.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130905_d20251109_m032047_c001_v7007000_t0000_u01762658447280")
2025/11/09 03:21:13 DEBUG : 032036ye/resync/path2/file2.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f111c3cf4d1c60b8b_d20251109_m032041_c001_v0001029_t0022_u01762658441370")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file3.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1172af2ed3e13c50_d20251109_m032112_c001_v0001105_t0033_u01762658472083")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file3.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1058a03afcf808ee_d20251109_m032057_c001_v0001032_t0028_u01762658457446")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file3.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c087_d20251109_m032052_c001_v0001145_t0013_u01762658452173")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file3.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130906_d20251109_m032047_c001_v7007000_t0000_u01762658447462")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file3.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1172af2ed3e13bc8_d20251109_m032041_c001_v0001105_t0025_u01762658441563")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file4.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f10363217ef76592b_d20251109_m032100_c001_v0001137_t0053_u01762658460789")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file4.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c08b_d20251109_m032052_c001_v0001145_t0036_u01762658452732")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file4.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130907_d20251109_m032047_c001_v7007000_t0000_u01762658447643")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file4.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c053_d20251109_m032041_c001_v0001145_t0048_u01762658441618")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file5.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f111c3cf4d1c60c3c_d20251109_m032112_c001_v0001029_t0009_u01762658472101")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file5.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f10964910c2ee5a48_d20251109_m032103_c001_v0001093_t0037_u01762658463693")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file5.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1109237d2b36fefc_d20251109_m032052_c001_v0001176_t0023_u01762658452749")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file5.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130909_d20251109_m032047_c001_v7007000_t0000_u01762658447826")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file5.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f111c3cf4d1c60b91_d20251109_m032041_c001_v0001029_t0027_u01762658441778")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file6.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1128965c84d79097_d20251109_m032111_c001_v0001113_t0032_u01762658471998")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file6.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f100e8f896ae6ad12_d20251109_m032106_c001_v0001040_t0006_u01762658466663")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file6.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1172af2ed3e13bf5_d20251109_m032052_c001_v0001105_t0025_u01762658452878")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file6.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da13090a_d20251109_m032048_c001_v7007000_t0000_u01762658448027")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file6.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c056_d20251109_m032041_c001_v0001145_t0049_u01762658441897")
2025/11/09 03:21:14 DEBUG : 032036ye/resync/path2/file7.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f111c3cf4d1c60bc5_d20251109_m032052_c001_v0001029_t0034_u01762658452942")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/file7.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da13090b_d20251109_m032048_c001_v7007000_t0000_u01762658448209")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/file7.txt: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1172af2ed3e13bca_d20251109_m032042_c001_v0001105_t0035_u01762658442017")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4083fd133a28dc4d_d20251109_m032110_c001_v7007000_t0000_u01762658470905")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f11119a2248cf8bda_d20251109_m032109_c001_v0001161_t0023_u01762658469504")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1109237d2b36ff3c_d20251109_m032107_c001_v0001176_t0038_u01762658467564")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da13090e_d20251109_m032051_c001_v7007000_t0000_u01762658451594")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f103c91378f4b406e_d20251109_m032050_c001_v0001105_t0040_u01762658450342")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1044ee2f3513c079_d20251109_m032048_c001_v0001145_t0026_u01762658448770")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f4120efd2da130901_d20251109_m032045_c001_v7007000_t0000_u01762658445376")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f10067513ce5758d4_d20251109_m032044_c001_v0001184_t0002_u01762658444134")
2025/11/09 03:21:15 DEBUG : 032036ye/resync/path2/modtime_write_test: Deleting (id "4_z8fa24db3a01d6b6b9fa80e1b_f1109237d2b36feb5_d20251109_m032042_c001_v0001176_t0041_u01762658442514")
--- FAIL: TestBisyncLocalRemote (40.66s)
    --- FAIL: TestBisyncLocalRemote/resync (36.29s)
=== RUN   TestBisyncRemoteRemote
2025/11/09 03:21:16 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga"
2025/11/09 03:21:17 NOTICE: remote: TestB2:rclone-test-xefocad6voga
=== RUN   TestBisyncRemoteRemote/resync
2025/11/09 03:21:17 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me"
2025/11/09 03:21:18 INFO  : path1: Making directory
2025/11/09 03:21:19 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path1"
2025/11/09 03:21:20 INFO  : path2: Making directory
2025/11/09 03:21:20 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2"
2025/11/09 03:21:21 DEBUG : Creating backend with remote "/tmp/032117me/initdir/test_resync-vigapud3"
2025/11/09 03:21:21 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:21:21 INFO  : file1.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : Local file system at /tmp/032117me/initdir/test_resync-vigapud3: Waiting for checks to finish
2025/11/09 03:21:21 DEBUG : Local file system at /tmp/032117me/initdir/test_resync-vigapud3: Waiting for transfers to finish
2025/11/09 03:21:21 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/09 03:21:21 INFO  : file3.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/11/09 03:21:21 INFO  : file5.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/09 03:21:21 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:21:21 INFO  : file4.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:21:21 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:21:21 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:21:21 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/11/09 03:21:21 INFO  : file6.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/11/09 03:21:21 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:21:21 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/11/09 03:21:21 INFO  : file7.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/09 03:21:21 INFO  : file2.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5"
2025/11/09 03:21:21 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:21 DEBUG : fileB.txt.b8ec97e6.partial: size = 19 OK
2025/11/09 03:21:21 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:21:21 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/11/09 03:21:21 DEBUG : fileA.txt.04c13af3.partial: size = 19 OK
2025/11/09 03:21:21 INFO  : fileB.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:21:21 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/11/09 03:21:21 INFO  : fileA.txt: Copied (new)
2025/11/09 03:21:21 DEBUG : Local file system at /tmp/032117me/datadir/test_resync-himujiq5: Waiting for checks to finish
2025/11/09 03:21:21 DEBUG : Local file system at /tmp/032117me/datadir/test_resync-himujiq5: Waiting for transfers to finish
2025/11/09 03:21:21 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:21:21 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:21:23 NOTICE: checking initFs Local file system at /tmp/032117me/initdir/test_resync-vigapud3
2025/11/09 03:21:23 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:23 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Waiting for checks to finish
2025/11/09 03:21:23 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Waiting for transfers to finish
2025/11/09 03:21:24 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:21:24 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:24 INFO  : file1.txt: Copied (new)
2025/11/09 03:21:24 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:21:24 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:24 INFO  : file2.txt: Copied (new)
2025/11/09 03:21:24 DEBUG : file3.txt: size = 0 OK
2025/11/09 03:21:24 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:24 INFO  : file3.txt: Copied (new)
2025/11/09 03:21:24 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:21:24 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:21:24 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:21:24 DEBUG : file7.txt: size = 0 OK
2025/11/09 03:21:24 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:24 INFO  : file7.txt: Copied (new)
2025/11/09 03:21:25 DEBUG : file5.txt: size = 0 OK
2025/11/09 03:21:25 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:25 INFO  : file5.txt: Copied (new)
2025/11/09 03:21:25 DEBUG : file4.txt: size = 0 OK
2025/11/09 03:21:25 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:25 INFO  : file4.txt: Copied (new)
2025/11/09 03:21:25 DEBUG : file6.txt: size = 0 OK
2025/11/09 03:21:25 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:25 INFO  : file6.txt: Copied (new)
2025/11/09 03:21:25 NOTICE: checking Path1 B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1
2025/11/09 03:21:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:21:25 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Waiting for checks to finish
2025/11/09 03:21:25 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Waiting for transfers to finish
2025/11/09 03:21:26 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:21:26 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:26 INFO  : file1.txt: Copied (new)
2025/11/09 03:21:26 DEBUG : file4.txt: size = 0 OK
2025/11/09 03:21:26 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:26 INFO  : file4.txt: Copied (new)
2025/11/09 03:21:26 DEBUG : file3.txt: size = 0 OK
2025/11/09 03:21:26 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:26 INFO  : file3.txt: Copied (new)
2025/11/09 03:21:26 DEBUG : file5.txt: size = 0 OK
2025/11/09 03:21:26 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:26 INFO  : file5.txt: Copied (new)
2025/11/09 03:21:27 DEBUG : file6.txt: size = 0 OK
2025/11/09 03:21:27 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:27 INFO  : file6.txt: Copied (new)
2025/11/09 03:21:27 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:21:27 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:27 INFO  : file2.txt: Copied (new)
2025/11/09 03:21:27 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:21:27 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:21:27 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:21:27 DEBUG : file7.txt: size = 0 OK
2025/11/09 03:21:27 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:21:27 INFO  : file7.txt: Copied (new)
2025/11/09 03:21:27 NOTICE: checking path2 B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2
2025/11/09 03:21:27 NOTICE: (01)  : test resync
2025/11/09 03:21:27 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/11/09 03:21:27 NOTICE: (03)  : purge-children TestB2:rclone-test-xefocad6voga/032117me/resync/path1/
2025/11/09 03:21:27 DEBUG : RCLONE_TEST: Remove file
2025/11/09 03:21:27 DEBUG : file1.txt: Remove file
2025/11/09 03:21:27 DEBUG : file2.txt: Remove file
2025/11/09 03:21:28 DEBUG : file3.txt: Remove file
2025/11/09 03:21:28 DEBUG : file4.txt: Remove file
2025/11/09 03:21:28 DEBUG : file5.txt: Remove file
2025/11/09 03:21:28 DEBUG : file6.txt: Remove file
2025/11/09 03:21:28 DEBUG : file7.txt: Remove file
2025/11/09 03:21:29 NOTICE: (04)  : bisync resync
2025/11/09 03:21:36 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:21:36 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:21:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:21:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:21:36 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:36 INFO  : Copying Path2 files to Path1
2025/11/09 03:21:36 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:21:37 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file2.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file4.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file5.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file6.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file7.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : file3.txt: Copied (server-side copy)
2025/11/09 03:21:37 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:21:38 INFO  : Resync updating listings
2025/11/09 03:21:38 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:38 INFO  : Bisync successful
2025/11/09 03:21:38 NOTICE: (05)  : move-listings empty-path1
2025/11/09 03:21:38 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/11/09 03:21:38 NOTICE: (07)  : purge-children TestB2:rclone-test-xefocad6voga/032117me/resync/path2/
2025/11/09 03:21:38 DEBUG : RCLONE_TEST: Remove file
2025/11/09 03:21:38 DEBUG : file1.txt: Remove file
2025/11/09 03:21:38 DEBUG : file2.txt: Remove file
2025/11/09 03:21:38 DEBUG : file3.txt: Remove file
2025/11/09 03:21:38 DEBUG : file4.txt: Remove file
2025/11/09 03:21:39 DEBUG : file5.txt: Remove file
2025/11/09 03:21:39 DEBUG : file6.txt: Remove file
2025/11/09 03:21:39 DEBUG : file7.txt: Remove file
2025/11/09 03:21:39 NOTICE: (08)  : bisync resync
2025/11/09 03:21:47 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:21:47 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:21:47 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:21:47 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:21:47 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:47 INFO  : Copying Path2 files to Path1
2025/11/09 03:21:47 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:21:47 INFO  : There was nothing to transfer
2025/11/09 03:21:47 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:21:48 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/11/09 03:21:48 INFO  : file2.txt: Copied (server-side copy)
2025/11/09 03:21:48 INFO  : file4.txt: Copied (server-side copy)
2025/11/09 03:21:48 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:21:48 INFO  : file3.txt: Copied (server-side copy)
2025/11/09 03:21:49 INFO  : file6.txt: Copied (server-side copy)
2025/11/09 03:21:49 INFO  : file5.txt: Copied (server-side copy)
2025/11/09 03:21:49 INFO  : file7.txt: Copied (server-side copy)
2025/11/09 03:21:49 INFO  : Resync updating listings
2025/11/09 03:21:49 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:49 INFO  : Bisync successful
2025/11/09 03:21:49 NOTICE: (09)  : move-listings empty-path2
2025/11/09 03:21:49 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/11/09 03:21:49 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/032117me/datadir/test_resync-himujiq5/ fileA.txt
2025/11/09 03:21:49 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/"
2025/11/09 03:21:49 DEBUG : Config file has changed externally - reloading
2025/11/09 03:21:49 DEBUG : fs cache: renaming cache item "/tmp/032117me/datadir/test_resync-himujiq5/" to be canonical "/tmp/032117me/datadir/test_resync-himujiq5"
2025/11/09 03:21:49 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/11/09 03:21:49 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/032117me/datadir/test_resync-himujiq5/ fileB.txt
2025/11/09 03:21:49 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync-himujiq5/" for canonical name "/tmp/032117me/datadir/test_resync-himujiq5"
2025/11/09 03:21:49 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/11/09 03:21:49 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/11/09 03:21:49 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/11/09 03:21:49 NOTICE: (15)  : delete-file TestB2:rclone-test-xefocad6voga/032117me/resync/path2/file1.txt
2025/11/09 03:21:49 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:50 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/" to be canonical "TestB2:rclone-test-xefocad6voga/032117me/resync/path2"
2025/11/09 03:21:50 INFO  : file1.txt: Deleted
2025/11/09 03:21:50 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/11/09 03:21:50 NOTICE: (17)  : delete-file TestB2:rclone-test-xefocad6voga/032117me/resync/path1/file2.txt
2025/11/09 03:21:50 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/"
2025/11/09 03:21:51 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" to be canonical "TestB2:rclone-test-xefocad6voga/032117me/resync/path1"
2025/11/09 03:21:52 INFO  : file2.txt: Deleted
2025/11/09 03:21:52 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/11/09 03:21:52 NOTICE: (19)  : copy-as /tmp/032117me/datadir/test_resync-himujiq5/fileA.txt TestB2:rclone-test-xefocad6voga/032117me/resync/path2/ file3.txt
2025/11/09 03:21:52 DEBUG : copyFile "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/" as "file3.txt"
2025/11/09 03:21:52 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt"
2025/11/09 03:21:52 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:53 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2" as "file3.txt"
2025/11/09 03:21:53 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032117me/datadir/test_resync-himujiq5)
2025/11/09 03:21:53 DEBUG : file3.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2)
2025/11/09 03:21:53 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:21:56 DEBUG : file3.txt: size = 19 OK
2025/11/09 03:21:56 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:21:56 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/11/09 03:21:56 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/11/09 03:21:56 NOTICE: (21)  : delete-file TestB2:rclone-test-xefocad6voga/032117me/resync/path1/file4.txt
2025/11/09 03:21:56 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" for canonical name "TestB2:rclone-test-xefocad6voga/032117me/resync/path1"
2025/11/09 03:21:56 INFO  : file4.txt: Deleted
2025/11/09 03:21:56 NOTICE: (22)  : copy-as /tmp/032117me/datadir/test_resync-himujiq5/fileA.txt TestB2:rclone-test-xefocad6voga/032117me/resync/path2/ file4.txt
2025/11/09 03:21:56 DEBUG : copyFile "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/" as "file4.txt"
2025/11/09 03:21:56 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt"
2025/11/09 03:21:56 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:21:57 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2" as "file4.txt"
2025/11/09 03:21:57 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032117me/datadir/test_resync-himujiq5)
2025/11/09 03:21:57 DEBUG : file4.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2)
2025/11/09 03:21:57 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:21:59 DEBUG : file4.txt: size = 19 OK
2025/11/09 03:21:59 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:21:59 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/11/09 03:21:59 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/11/09 03:21:59 NOTICE: (24)  : copy-as /tmp/032117me/datadir/test_resync-himujiq5/fileB.txt TestB2:rclone-test-xefocad6voga/032117me/resync/path2/ file5.txt
2025/11/09 03:21:59 DEBUG : copyFile "/tmp/032117me/datadir/test_resync-himujiq5/fileB.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/" as "file5.txt"
2025/11/09 03:21:59 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/fileB.txt"
2025/11/09 03:21:59 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:00 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2" as "file5.txt"
2025/11/09 03:22:00 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/032117me/datadir/test_resync-himujiq5)
2025/11/09 03:22:00 DEBUG : file5.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2)
2025/11/09 03:22:00 DEBUG : fileB.txt: Sizes differ
2025/11/09 03:22:02 DEBUG : file5.txt: size = 19 OK
2025/11/09 03:22:02 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:22:02 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/11/09 03:22:02 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/11/09 03:22:02 NOTICE: (26)  : copy-as /tmp/032117me/datadir/test_resync-himujiq5/fileB.txt TestB2:rclone-test-xefocad6voga/032117me/resync/path1/ file6.txt
2025/11/09 03:22:02 DEBUG : copyFile "/tmp/032117me/datadir/test_resync-himujiq5/fileB.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" as "file6.txt"
2025/11/09 03:22:02 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/fileB.txt"
2025/11/09 03:22:02 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/"
2025/11/09 03:22:03 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1" as "file6.txt"
2025/11/09 03:22:03 DEBUG : fileB.txt: size = 19 (Local file system at /tmp/032117me/datadir/test_resync-himujiq5)
2025/11/09 03:22:03 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1)
2025/11/09 03:22:03 DEBUG : fileB.txt: Sizes differ
2025/11/09 03:22:05 DEBUG : file6.txt: size = 19 OK
2025/11/09 03:22:05 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:22:05 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/11/09 03:22:05 NOTICE: (27)  : copy-as /tmp/032117me/datadir/test_resync-himujiq5/fileA.txt TestB2:rclone-test-xefocad6voga/032117me/resync/path2/ file6.txt
2025/11/09 03:22:05 DEBUG : copyFile "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/" as "file6.txt"
2025/11/09 03:22:05 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync-himujiq5/fileA.txt"
2025/11/09 03:22:05 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:06 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2" as "file6.txt"
2025/11/09 03:22:06 DEBUG : fileA.txt: size = 19 (Local file system at /tmp/032117me/datadir/test_resync-himujiq5)
2025/11/09 03:22:06 DEBUG : file6.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2)
2025/11/09 03:22:06 DEBUG : fileA.txt: Sizes differ
2025/11/09 03:22:08 DEBUG : file6.txt: size = 19 OK
2025/11/09 03:22:08 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/11/09 03:22:08 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/11/09 03:22:08 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/11/09 03:22:08 NOTICE: (29)  : test run bisync with resync
2025/11/09 03:22:08 NOTICE: (30)  : bisync resync
2025/11/09 03:22:16 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:16 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:16 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:22:16 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:22:16 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:16 INFO  : Copying Path2 files to Path1
2025/11/09 03:22:16 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:22:16 INFO  : file4.txt: Copied (server-side copy)
2025/11/09 03:22:17 INFO  : file2.txt: Copied (server-side copy)
2025/11/09 03:22:17 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:22:17 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:22:17 INFO  : file5.txt: Copied (server-side copy)
2025/11/09 03:22:17 INFO  : file6.txt: Updated modification time in destination
2025/11/09 03:22:17 INFO  : file3.txt: Copied (server-side copy)
2025/11/09 03:22:17 INFO  : Resync updating listings
2025/11/09 03:22:17 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:17 INFO  : Bisync successful
2025/11/09 03:22:17 NOTICE: (31)  : copy-listings mixed-diffs
2025/11/09 03:22:17 NOTICE: (32)  : test run normal bisync
2025/11/09 03:22:17 NOTICE: (33)  : bisync
2025/11/09 03:22:25 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:25 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:22: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/11/09 03:22:25 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:25 INFO  : Building Path1 and Path2 listings
2025/11/09 03:22:25 INFO  : Path1 checking for diffs
2025/11/09 03:22:25 INFO  : Path2 checking for diffs
2025/11/09 03:22:25 INFO  : No changes found
2025/11/09 03:22:25 INFO  : Updating listings
2025/11/09 03:22:25 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:25 INFO  : Bisync successful
2025/11/09 03:22:25 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/11/09 03:22:25 NOTICE: (35)  : purge-children TestB2:rclone-test-xefocad6voga/032117me/resync/path2/
2025/11/09 03:22:25 DEBUG : RCLONE_TEST: Remove file
2025/11/09 03:22:25 DEBUG : file1.txt: Remove file
2025/11/09 03:22:26 DEBUG : file2.txt: Remove file
2025/11/09 03:22:26 DEBUG : file3.txt: Remove file
2025/11/09 03:22:26 DEBUG : file4.txt: Remove file
2025/11/09 03:22:26 DEBUG : file5.txt: Remove file
2025/11/09 03:22:27 DEBUG : file6.txt: Remove file
2025/11/09 03:22:27 DEBUG : file7.txt: Remove file
2025/11/09 03:22:27 NOTICE: (36)  : bisync
2025/11/09 03:22:35 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:35 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:22:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:22:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:22:35 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync/path2/"
2025/11/09 03:22:35 INFO  : Building Path1 and Path2 listings
2025/11/09 03:22:35 INFO  : Path1 checking for diffs
2025/11/09 03:22:35 INFO  : Path2 checking for diffs
2025/11/09 03:22:35 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/032117me/workdir/TestB2_rclone-test-xefocad6voga_032117me_resync_path1..TestB2_rclone-test-xefocad6voga_032117me_resync_path2.path2.lst-new
2025/11/09 03:22:35 ERROR : Bisync critical error: empty current Path2 listing: /tmp/032117me/workdir/TestB2_rclone-test-xefocad6voga_032117me_resync_path1..TestB2_rclone-test-xefocad6voga_032117me_resync_path2.path2.lst-new
2025/11/09 03:22:35 ERROR : Bisync aborted. Must run --resync to recover.
2025/11/09 03:22:35 NOTICE: Bisync error: bisync aborted
2025/11/09 03:22:35 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:22:35 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1156e87ecbed7ef6_d20251109_m032136_c001_v0001178_t0055_u01762658496864")
2025/11/09 03:22:35 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545b2_d20251109_m032127_c001_v7007000_t0000_u01762658487696")
2025/11/09 03:22:35 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1195dc7d6d9a6c06_d20251109_m032124_c001_v0001176_t0015_u01762658484512")
2025/11/09 03:22:35 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11179dfc9006adc3_d20251109_m032137_c001_v0001099_t0058_u01762658497179")
2025/11/09 03:22:35 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545b5_d20251109_m032127_c001_v7007000_t0000_u01762658487880")
2025/11/09 03:22:35 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107799bc84538954_d20251109_m032123_c001_v0001040_t0054_u01762658483992")
2025/11/09 03:22:35 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1137ccdef498f197_d20251109_m032216_c001_v0001165_t0015_u01762658536851")
2025/11/09 03:22:35 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f407ae72636f46d19_d20251109_m032152_c001_v7007000_t0000_u01762658512560")
2025/11/09 03:22:35 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1087e4ce33a8e57e_d20251109_m032137_c001_v0001179_t0059_u01762658497238")
2025/11/09 03:22:36 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545b8_d20251109_m032128_c001_v7007000_t0000_u01762658488069")
2025/11/09 03:22:36 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1147147b69c6a2a7_d20251109_m032124_c001_v0001136_t0050_u01762658484169")
2025/11/09 03:22:36 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11123be7dfab5b28_d20251109_m032137_c001_v0001104_t0052_u01762658497270")
2025/11/09 03:22:36 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545bb_d20251109_m032128_c001_v7007000_t0000_u01762658488267")
2025/11/09 03:22:36 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1188c7e809be3250_d20251109_m032124_c001_v0001029_t0012_u01762658484327")
2025/11/09 03:22:36 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107b8179a26a2695_d20251109_m032216_c001_v0001184_t0034_u01762658536508")
2025/11/09 03:22:36 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f407ae72636f46d1a_d20251109_m032156_c001_v7007000_t0000_u01762658516332")
2025/11/09 03:22:36 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f104a12b6e09077fa_d20251109_m032137_c001_v0001181_t0053_u01762658497278")
2025/11/09 03:22:36 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545c1_d20251109_m032128_c001_v7007000_t0000_u01762658488480")
2025/11/09 03:22:36 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107799bc84538956_d20251109_m032124_c001_v0001040_t0050_u01762658484342")
2025/11/09 03:22:36 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10162af07a5fb342_d20251109_m032137_c001_v0001165_t0014_u01762658497486")
2025/11/09 03:22:36 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545c6_d20251109_m032128_c001_v7007000_t0000_u01762658488663")
2025/11/09 03:22:36 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1147147b69c6a2ae_d20251109_m032124_c001_v0001136_t0042_u01762658484807")
2025/11/09 03:22:36 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1065eb93cc67c824_d20251109_m032204_c001_v0001044_t0021_u01762658524779")
2025/11/09 03:22:36 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f109fdb670c2bb4eb_d20251109_m032137_c001_v0001161_t0045_u01762658497552")
2025/11/09 03:22:37 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545cc_d20251109_m032128_c001_v7007000_t0000_u01762658488851")
2025/11/09 03:22:37 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1188c7e809be3252_d20251109_m032124_c001_v0001029_t0019_u01762658484843")
2025/11/09 03:22:37 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11149f08aeadcc80_d20251109_m032137_c001_v0001179_t0003_u01762658497641")
2025/11/09 03:22:37 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e048545d0_d20251109_m032129_c001_v7007000_t0000_u01762658489034")
2025/11/09 03:22:37 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1195dc7d6d9a6c0a_d20251109_m032124_c001_v0001176_t0028_u01762658484854")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f415873665321c353_d20251109_m032231_c001_v7007000_t0000_u01762658551202")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f106e884b8118d663_d20251109_m032229_c001_v0001176_t0041_u01762658549965")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1195dc7d6d9a6f5a_d20251109_m032227_c001_v0001176_t0030_u01762658547864")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f415873665321c31b_d20251109_m032221_c001_v7007000_t0000_u01762658541726")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f109b558e0b57ff25_d20251109_m032220_c001_v0001179_t0006_u01762658540506")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1188c7e809be32da_d20251109_m032218_c001_v0001029_t0003_u01762658538402")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f415873665321c2d6_d20251109_m032212_c001_v7007000_t0000_u01762658532206")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f106c3857c72805be_d20251109_m032210_c001_v0001178_t0002_u01762658530983")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107799bc845389b5_d20251109_m032208_c001_v0001040_t0003_u01762658528897")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f407ae72636f46d06_d20251109_m032143_c001_v7007000_t0000_u01762658503385")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f100960c40c734029_d20251109_m032142_c001_v0001182_t0019_u01762658502146")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1147147b69c6a317_d20251109_m032140_c001_v0001136_t0026_u01762658500231")
2025/11/09 03:22:37 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854611_d20251109_m032132_c001_v7007000_t0000_u01762658492273")
2025/11/09 03:22:38 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1155ff84e88b8442_d20251109_m032131_c001_v0001183_t0033_u01762658491008")
2025/11/09 03:22:38 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1195dc7d6d9a6c4a_d20251109_m032129_c001_v0001176_t0038_u01762658489221")
2025/11/09 03:22:38 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:22:38 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e0485486d_d20251109_m032225_c001_v7007000_t0000_u01762658545842")
2025/11/09 03:22:38 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118565838ff8764b_d20251109_m032148_c001_v0001098_t0023_u01762658508186")
2025/11/09 03:22:38 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a71_d20251109_m032138_c001_v7007000_t0000_u01762658498335")
2025/11/09 03:22:38 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113d0ed1140f7300_d20251109_m032127_c001_v0001178_t0056_u01762658487031")
2025/11/09 03:22:38 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e0485486f_d20251109_m032226_c001_v7007000_t0000_u01762658546075")
2025/11/09 03:22:38 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1146119e2e9c06b2_d20251109_m032217_c001_v0001113_t0030_u01762658537306")
2025/11/09 03:22:38 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f413f2cbeee6c9ae7_d20251109_m032150_c001_v7007000_t0000_u01762658510670")
2025/11/09 03:22:38 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f110bd1866d72361c_d20251109_m032148_c001_v0001161_t0011_u01762658508618")
2025/11/09 03:22:38 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a73_d20251109_m032138_c001_v7007000_t0000_u01762658498531")
2025/11/09 03:22:39 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10234aa023129848_d20251109_m032126_c001_v0001182_t0030_u01762658486460")
2025/11/09 03:22:39 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854871_d20251109_m032226_c001_v7007000_t0000_u01762658546258")
2025/11/09 03:22:39 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1073dc067ed730f8_d20251109_m032148_c001_v0001183_t0017_u01762658508608")
2025/11/09 03:22:39 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a74_d20251109_m032138_c001_v7007000_t0000_u01762658498715")
2025/11/09 03:22:39 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1118a248f13106c7_d20251109_m032126_c001_v0001098_t0002_u01762658486831")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854874_d20251109_m032226_c001_v7007000_t0000_u01762658546505")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11266841466f07f5_d20251109_m032217_c001_v0001137_t0013_u01762658537658")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f117c855070492285_d20251109_m032155_c001_v0001172_t0012_u01762658515363")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1160282322e9eef8_d20251109_m032148_c001_v0001098_t0047_u01762658508602")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a75_d20251109_m032138_c001_v7007000_t0000_u01762658498898")
2025/11/09 03:22:39 DEBUG : file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f104fc66c6a0a6311_d20251109_m032126_c001_v0001180_t0026_u01762658486654")
2025/11/09 03:22:39 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854876_d20251109_m032226_c001_v7007000_t0000_u01762658546743")
2025/11/09 03:22:39 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1166b159416651d9_d20251109_m032159_c001_v0001178_t0000_u01762658519146")
2025/11/09 03:22:39 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111364b7ec240560_d20251109_m032148_c001_v0001177_t0013_u01762658508619")
2025/11/09 03:22:39 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a76_d20251109_m032139_c001_v7007000_t0000_u01762658499085")
2025/11/09 03:22:39 DEBUG : file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10234aa02312984e_d20251109_m032126_c001_v0001182_t0004_u01762658486667")
2025/11/09 03:22:39 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854879_d20251109_m032226_c001_v7007000_t0000_u01762658546925")
2025/11/09 03:22:40 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118dafeae5ac6a23_d20251109_m032217_c001_v0001181_t0054_u01762658537690")
2025/11/09 03:22:40 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11354fd1740dfbe5_d20251109_m032201_c001_v0001183_t0057_u01762658521959")
2025/11/09 03:22:40 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f105fc3642f747f76_d20251109_m032148_c001_v0001178_t0037_u01762658508840")
2025/11/09 03:22:40 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a77_d20251109_m032139_c001_v7007000_t0000_u01762658499302")
2025/11/09 03:22:40 DEBUG : file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10234aa023129850_d20251109_m032126_c001_v0001182_t0032_u01762658486884")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e0485487b_d20251109_m032227_c001_v7007000_t0000_u01762658547121")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112aae0420100cd4_d20251109_m032217_c001_v0001153_t0000_u01762658537701")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1016905f20b502b4_d20251109_m032208_c001_v0001136_t0012_u01762658528053")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f101fc83ec69c12e7_d20251109_m032148_c001_v0001179_t0051_u01762658508912")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a78_d20251109_m032139_c001_v7007000_t0000_u01762658499486")
2025/11/09 03:22:40 DEBUG : file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f104fc66c6a0a6317_d20251109_m032126_c001_v0001180_t0023_u01762658486894")
2025/11/09 03:22:40 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e0485487d_d20251109_m032227_c001_v7007000_t0000_u01762658547302")
2025/11/09 03:22:40 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11739b7c0c6f5806_d20251109_m032149_c001_v0001134_t0030_u01762658509018")
2025/11/09 03:22:40 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a79_d20251109_m032139_c001_v7007000_t0000_u01762658499684")
2025/11/09 03:22:40 DEBUG : file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10234aa023129852_d20251109_m032127_c001_v0001182_t0022_u01762658487087")
2025/11/09 03:22:40 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f414136f5a56bb26f_d20251109_m032235_c001_v7007000_t0000_u01762658555315")
2025/11/09 03:22:40 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f115530a73083c16a_d20251109_m032233_c001_v0001105_t0009_u01762658553902")
2025/11/09 03:22:40 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f104fc66c6a0a64a0_d20251109_m032231_c001_v0001180_t0039_u01762658551770")
2025/11/09 03:22:40 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f417ea52e04854868_d20251109_m032225_c001_v7007000_t0000_u01762658545283")
2025/11/09 03:22:40 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1073dc067ed731fb_d20251109_m032224_c001_v0001183_t0047_u01762658544076")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10234aa023129a87_d20251109_m032222_c001_v0001182_t0056_u01762658542289")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4098ef171826d406_d20251109_m032216_c001_v7007000_t0000_u01762658536115")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f105b7dd526e46be6_d20251109_m032214_c001_v0001177_t0044_u01762658534897")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113d0ed1140f73cf_d20251109_m032212_c001_v0001178_t0035_u01762658532760")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4083fd133a28dc90_d20251109_m032147_c001_v7007000_t0000_u01762658507597")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f101c7f96dc752e68_d20251109_m032146_c001_v0001134_t0052_u01762658506156")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1118a248f131071f_d20251109_m032143_c001_v0001098_t0039_u01762658503934")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4103e53acd156a70_d20251109_m032136_c001_v7007000_t0000_u01762658496487")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112286ac3f9411e4_d20251109_m032135_c001_v0001040_t0022_u01762658494999")
2025/11/09 03:22:41 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f104fc66c6a0a633f_d20251109_m032132_c001_v0001180_t0037_u01762658492842")
    bisync_test.go:608: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/11/09 03:22:41 INFO  : path1: Making directory
2025/11/09 03:22:41 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1"
2025/11/09 03:22:43 INFO  : path2: Making directory
2025/11/09 03:22:43 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2"
2025/11/09 03:22:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/11/09 03:22:44 DEBUG : Creating backend with remote "/tmp/032117me/initdir/test_resync_modes-tehepeb3"
2025/11/09 03:22:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : Local file system at /tmp/032117me/initdir/test_resync_modes-tehepeb3: Waiting for checks to finish
2025/11/09 03:22:44 DEBUG : Local file system at /tmp/032117me/initdir/test_resync_modes-tehepeb3: Waiting for transfers to finish
2025/11/09 03:22:44 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:22:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:22:44 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:22:44 INFO  : file1.txt: Copied (new)
2025/11/09 03:22:44 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:22:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:22:44 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:22:44 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:22:44 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/09 03:22:44 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:22:44 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/09 03:22:44 INFO  : file2.txt: Copied (new)
2025/11/09 03:22:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/11/09 03:22:44 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:22:44 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:44 DEBUG : Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0: Waiting for checks to finish
2025/11/09 03:22:44 DEBUG : Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0: Waiting for transfers to finish
2025/11/09 03:22:44 DEBUG : file1R.txt.d467e63a.partial: size = 42 OK
2025/11/09 03:22:44 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/11/09 03:22:44 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/11/09 03:22:44 INFO  : file1R.txt: Copied (new)
2025/11/09 03:22:44 DEBUG : file1L.txt.684d937d.partial: size = 33 OK
2025/11/09 03:22:44 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/11/09 03:22:44 DEBUG : file2.txt.ac4156f2.partial: size = 37 OK
2025/11/09 03:22:44 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/11/09 03:22:44 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/11/09 03:22:44 INFO  : file1L.txt: Copied (new)
2025/11/09 03:22:44 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/11/09 03:22:44 INFO  : file2.txt: Copied (new)
2025/11/09 03:22:44 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:22:44 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:22:46 NOTICE: checking initFs Local file system at /tmp/032117me/initdir/test_resync_modes-tehepeb3
2025/11/09 03:22:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:22:46 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:46 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:46 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Waiting for checks to finish
2025/11/09 03:22:46 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Waiting for transfers to finish
2025/11/09 03:22:47 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:22:47 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:22:47 INFO  : file1.txt: Copied (new)
2025/11/09 03:22:47 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:22:47 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:22:47 INFO  : file2.txt: Copied (new)
2025/11/09 03:22:47 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:22:47 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:22:47 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:22:47 NOTICE: checking Path1 B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1
2025/11/09 03:22:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:22:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:47 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:22:47 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Waiting for checks to finish
2025/11/09 03:22:47 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Waiting for transfers to finish
2025/11/09 03:22:48 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:22:48 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:22:48 INFO  : file2.txt: Copied (new)
2025/11/09 03:22:49 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:22:49 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:22:49 INFO  : file1.txt: Copied (new)
2025/11/09 03:22:49 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:22:49 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:22:49 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:22:49 NOTICE: checking path2 B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2
2025/11/09 03:22:49 NOTICE: (01)  : test resync-mode
2025/11/09 03:22:49 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:22:49 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:22:49 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/"
2025/11/09 03:22:49 DEBUG : fs cache: renaming cache item "/tmp/032117me/datadir/test_resync_modes-mogewad0/" to be canonical "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:22:49 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/11/09 03:22:49 NOTICE: (04)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:22:49 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:22:49 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:22:49 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:22:50 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:22:50 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:22:50 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:22:50 DEBUG : file1R.txt: Sizes differ
2025/11/09 03:22:52 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:22:52 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:22:52 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:22:52 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:22:52 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:22:52 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/11/09 03:22:52 NOTICE: (06)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:22:52 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:22:52 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:22:52 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:22:53 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:22:53 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:22:53 DEBUG : file1.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1)
2025/11/09 03:22:53 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:22:55 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:22:55 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:22:55 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:22:55 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/11/09 03:22:55 NOTICE: (08)  : bisync resync resync-mode=newer
2025/11/09 03:23:04 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:04 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:23:04 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:23:04 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:04 INFO  : Copying Path2 files to Path1
2025/11/09 03:23:04 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:23:04 INFO  : There was nothing to transfer
2025/11/09 03:23:04 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:23:05 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:23:05 INFO  : Resync updating listings
2025/11/09 03:23:05 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:05 INFO  : Bisync successful
2025/11/09 03:23:05 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:23:05 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:23:05 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:05 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/11/09 03:23:05 NOTICE: (11)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:23:05 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:23:05 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:23:05 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:06 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:23:06 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:23:06 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:23:06 DEBUG : file1R.txt: Sizes differ
2025/11/09 03:23:07 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:23:07 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:23:07 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:23:07 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:23:07 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:07 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/11/09 03:23:07 NOTICE: (13)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:23:07 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:23:07 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:23:07 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:23:09 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:23:09 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:23:09 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/11/09 03:23:09 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:23:10 INFO  : file1L.txt: Updated modification time in destination
2025/11/09 03:23:10 DEBUG : file1L.txt: Unchanged skipping
2025/11/09 03:23:10 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/11/09 03:23:10 NOTICE: (15)  : bisync resync resync-mode=path2
2025/11/09 03:23:19 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:19 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:23: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/11/09 03:23:19 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:19 INFO  : Copying Path2 files to Path1
2025/11/09 03:23:19 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:23:20 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:23:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:23:20 INFO  : Resync updating listings
2025/11/09 03:23:20 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:20 INFO  : Bisync successful
2025/11/09 03:23:20 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:23:20 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:23:20 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:20 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/11/09 03:23:20 NOTICE: (18)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:23:20 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:23:20 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:23:20 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:21 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:23:22 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:23:22 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/11/09 03:23:22 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:23:23 INFO  : file1R.txt: Updated modification time in destination
2025/11/09 03:23:23 DEBUG : file1R.txt: Unchanged skipping
2025/11/09 03:23:23 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:23:23 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:23 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/11/09 03:23:23 NOTICE: (20)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:23:23 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:23:23 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:23:23 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:23:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:23:24 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:23:24 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1)
2025/11/09 03:23:24 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:23:26 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:23:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:23:26 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:23:26 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/11/09 03:23:26 NOTICE: (22)  : bisync resync resync-mode=larger
2025/11/09 03:23:35 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:35 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:23:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:23:35 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:35 INFO  : Copying Path2 files to Path1
2025/11/09 03:23:35 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:23:35 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/11/09 03:23:35 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:23:35 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:23:36 INFO  : Resync updating listings
2025/11/09 03:23:36 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:36 INFO  : Bisync successful
2025/11/09 03:23:36 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:23:36 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:23:36 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:36 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/11/09 03:23:36 NOTICE: (25)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:23:36 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:23:36 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:23:36 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:37 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:23:37 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:23:37 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/11/09 03:23:37 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:23:38 INFO  : file1R.txt: Updated modification time in destination
2025/11/09 03:23:38 DEBUG : file1R.txt: Unchanged skipping
2025/11/09 03:23:38 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:23:38 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:38 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/11/09 03:23:38 NOTICE: (27)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:23:38 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:23:38 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:23:38 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:23:40 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:23:40 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:23:40 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1)
2025/11/09 03:23:40 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:23:41 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:23:41 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:23:41 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:23:41 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/11/09 03:23:41 NOTICE: (29)  : bisync resync resync-mode=older
2025/11/09 03:23:50 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:50 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:23:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:23: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/11/09 03:23:50 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:50 INFO  : Copying Path2 files to Path1
2025/11/09 03:23:50 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:23:50 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/11/09 03:23:50 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:23:50 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:23:51 INFO  : Resync updating listings
2025/11/09 03:23:51 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:51 INFO  : Bisync successful
2025/11/09 03:23:51 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:23:51 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:23:51 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:51 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/11/09 03:23:51 NOTICE: (32)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:23:51 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:23:51 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:23:51 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:23:52 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:23:52 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:23:52 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/11/09 03:23:52 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:23:53 INFO  : file1R.txt: Updated modification time in destination
2025/11/09 03:23:53 DEBUG : file1R.txt: Unchanged skipping
2025/11/09 03:23:53 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:23:53 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:23:53 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/11/09 03:23:53 NOTICE: (34)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:23:53 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:23:53 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:23:53 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:23:54 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:23:55 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:23:55 DEBUG : file1.txt: size = 42 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1)
2025/11/09 03:23:55 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:23:56 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:23:56 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:23:56 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:23:56 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/11/09 03:23:56 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/11/09 03:24:05 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:05 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:05 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:24: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/11/09 03:24:05 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:05 INFO  : Copying Path2 files to Path1
2025/11/09 03:24:05 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:24:05 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/11/09 03:24:05 INFO  : There was nothing to transfer
2025/11/09 03:24:05 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:24:06 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/11/09 03:24:06 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:24:06 INFO  : Resync updating listings
2025/11/09 03:24:06 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:06 INFO  : Bisync successful
2025/11/09 03:24:06 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/11/09 03:24:06 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:24:06 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:24:06 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/11/09 03:24:06 NOTICE: (39)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:24:06 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:24:06 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:24:06 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:07 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:24:07 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:24:07 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:24:07 DEBUG : file1R.txt: Sizes differ
2025/11/09 03:24:09 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:24:09 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:24:09 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:24:09 NOTICE: (40)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file2.txt
2025/11/09 03:24:09 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file2.txt"
2025/11/09 03:24:09 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:24:09 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:24:10 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file2.txt"
2025/11/09 03:24:10 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:24:10 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1)
2025/11/09 03:24:10 DEBUG : file1R.txt: Sizes differ
2025/11/09 03:24:12 DEBUG : file2.txt: size = 42 OK
2025/11/09 03:24:12 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:24:12 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/11/09 03:24:12 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:24:12 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:24:12 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/11/09 03:24:12 NOTICE: (42)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:24:12 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:24:12 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:24:12 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:24:13 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:24:13 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:24:13 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/11/09 03:24:13 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:24:15 INFO  : file1L.txt: Updated modification time in destination
2025/11/09 03:24:15 DEBUG : file1L.txt: Unchanged skipping
2025/11/09 03:24:15 NOTICE: (43)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file2.txt
2025/11/09 03:24:15 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file2.txt"
2025/11/09 03:24:15 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:24:15 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:16 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file2.txt"
2025/11/09 03:24:16 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:24:16 DEBUG : file2.txt: size = 0 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:24:16 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:24:18 DEBUG : file2.txt: size = 33 OK
2025/11/09 03:24:18 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:24:18 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/11/09 03:24:18 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/11/09 03:24:18 NOTICE: (45)  : bisync resync resync-mode=path1
2025/11/09 03:24:26 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:26 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:26 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:24:26 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/11/09 03:24:26 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:26 INFO  : Copying Path2 files to Path1
2025/11/09 03:24:26 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:24:27 INFO  : There was nothing to transfer
2025/11/09 03:24:27 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:24:28 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:24:28 INFO  : file2.txt: Copied (server-side copy)
2025/11/09 03:24:28 INFO  : Resync updating listings
2025/11/09 03:24:28 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:28 INFO  : Bisync successful
2025/11/09 03:24:28 NOTICE: (46)  : test no winner
2025/11/09 03:24:28 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1R.txt
2025/11/09 03:24:28 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:24:28 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/11/09 03:24:28 NOTICE: (48)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file1.txt
2025/11/09 03:24:28 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file1.txt"
2025/11/09 03:24:28 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:24:28 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:29 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file1.txt"
2025/11/09 03:24:29 DEBUG : file1R.txt: size = 42 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:24:29 DEBUG : file1.txt: size = 33 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:24:29 DEBUG : file1R.txt: Sizes differ
2025/11/09 03:24:31 DEBUG : file1.txt: size = 42 OK
2025/11/09 03:24:31 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:24:31 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/11/09 03:24:31 NOTICE: (49)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file2.txt
2025/11/09 03:24:31 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file2.txt"
2025/11/09 03:24:31 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1R.txt"
2025/11/09 03:24:31 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:24:32 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file2.txt"
2025/11/09 03:24:32 DEBUG : file2.txt: size = 42 OK
2025/11/09 03:24:32 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/11/09 03:24:32 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/11/09 03:24:34 INFO  : file1R.txt: Updated modification time in destination
2025/11/09 03:24:34 DEBUG : file1R.txt: Unchanged skipping
2025/11/09 03:24:34 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/032117me/datadir/test_resync_modes-mogewad0/ file1L.txt
2025/11/09 03:24:34 DEBUG : fs cache: switching user supplied name "/tmp/032117me/datadir/test_resync_modes-mogewad0/" for canonical name "/tmp/032117me/datadir/test_resync_modes-mogewad0"
2025/11/09 03:24:34 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/11/09 03:24:34 NOTICE: (51)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/ file1.txt
2025/11/09 03:24:34 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" as "file1.txt"
2025/11/09 03:24:34 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:24:34 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/"
2025/11/09 03:24:35 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1" as "file1.txt"
2025/11/09 03:24:35 DEBUG : file1.txt: size = 33 OK
2025/11/09 03:24:35 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/11/09 03:24:35 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:24:37 INFO  : file1L.txt: Updated modification time in destination
2025/11/09 03:24:37 DEBUG : file1L.txt: Unchanged skipping
2025/11/09 03:24:37 NOTICE: (52)  : copy-as /tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/ file2.txt
2025/11/09 03:24:37 DEBUG : copyFile "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt" to "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/" as "file2.txt"
2025/11/09 03:24:37 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_resync_modes-mogewad0/file1L.txt"
2025/11/09 03:24:37 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:38 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2" as "file2.txt"
2025/11/09 03:24:38 DEBUG : file1L.txt: size = 33 (Local file system at /tmp/032117me/datadir/test_resync_modes-mogewad0)
2025/11/09 03:24:38 DEBUG : file2.txt: size = 42 (B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2)
2025/11/09 03:24:38 DEBUG : file1L.txt: Sizes differ
2025/11/09 03:24:40 DEBUG : file2.txt: size = 33 OK
2025/11/09 03:24:40 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/11/09 03:24:40 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/11/09 03:24:40 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/11/09 03:24:40 NOTICE: (54)  : bisync resync resync-mode=newer
2025/11/09 03:24:48 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:48 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/11/09 03:24:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/11/09 03:24: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/11/09 03:24:48 INFO  : Synching Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" with Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:48 INFO  : Copying Path2 files to Path1
2025/11/09 03:24:48 INFO  : - Path2    Resync is copying files to         - Path1
2025/11/09 03:24:49 INFO  : file1.txt: Copied (server-side copy)
2025/11/09 03:24:49 INFO  : file2.txt: Copied (server-side copy)
2025/11/09 03:24:49 INFO  : - Path1    Resync is copying files to         - Path2
2025/11/09 03:24:50 INFO  : Resync updating listings
2025/11/09 03:24:50 INFO  : Validating listings for Path1 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path1/" vs Path2 "TestB2:rclone-test-xefocad6voga/032117me/resync_modes/path2/"
2025/11/09 03:24:50 INFO  : Bisync successful
2025/11/09 03:24:50 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:24:50 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ae82e935b2e8e_d20251109_m032247_c001_v0001102_t0033_u01762658567283")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11665341631a344f_d20251109_m032449_c001_v0001182_t0014_u01762658689303")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1086bfbf52a9123a_d20251109_m032436_c001_v0001040_t0016_u01762658676778")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1041cc1f4fdb80bc_d20251109_m032414_c001_v0001136_t0003_u01762658654849")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1005f114ab0ab4e3_d20251109_m032356_c001_v0001120_t0009_u01762658636405")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f101fc83ec69c155a_d20251109_m032350_c001_v0001179_t0026_u01762658630757")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1178e8e090a7474c_d20251109_m032341_c001_v0001136_t0027_u01762658621581")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f102667a235c5e402_d20251109_m032335_c001_v0001117_t0012_u01762658615371")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f114957c7c8583eb4_d20251109_m032326_c001_v0001134_t0016_u01762658606216")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f117c5b57656cb397_d20251109_m032320_c001_v0001093_t0039_u01762658600335")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11454401866f660b_d20251109_m032310_c001_v0001176_t0025_u01762658590374")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11046b2841020940_d20251109_m032255_c001_v0001157_t0005_u01762658575269")
2025/11/09 03:24:50 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1090aad162ed8488_d20251109_m032246_c001_v0001040_t0002_u01762658566857")
2025/11/09 03:24:50 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10286526a715d19c_d20251109_m032449_c001_v0001136_t0042_u01762658689588")
2025/11/09 03:24:50 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107e1996b7c258f0_d20251109_m032433_c001_v0001093_t0030_u01762658673852")
2025/11/09 03:24:50 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f114316a8a6f4fe09_d20251109_m032412_c001_v0001181_t0014_u01762658652277")
2025/11/09 03:24:50 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113254a276b1e269_d20251109_m032247_c001_v0001101_t0043_u01762658567049")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f40108f13c7036e44_d20251109_m032444_c001_v7007000_t0000_u01762658684785")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f114db0dadee8a31c_d20251109_m032443_c001_v0001165_t0059_u01762658683541")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1090aad162ed85f0_d20251109_m032441_c001_v0001040_t0020_u01762658681134")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f415071e978c1d607_d20251109_m032422_c001_v7007000_t0000_u01762658662613")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1181dfef5bc8c7e2_d20251109_m032421_c001_v0001100_t0001_u01762658661086")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ae82e935b300f_d20251109_m032418_c001_v0001102_t0037_u01762658658654")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4120efd2da130cff_d20251109_m032400_c001_v7007000_t0000_u01762658640948")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11576cff42849655_d20251109_m032359_c001_v0001092_t0010_u01762658639566")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113254a276b1e359_d20251109_m032357_c001_v0001101_t0052_u01762658637273")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f402687500d8a5668_d20251109_m032346_c001_v7007000_t0000_u01762658626250")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11656f155c7b93be_d20251109_m032344_c001_v0001040_t0005_u01762658624780")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1090aad162ed8543_d20251109_m032342_c001_v0001040_t0006_u01762658622310")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f415873665321c4fb_d20251109_m032330_c001_v7007000_t0000_u01762658610758")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f108444b168bc02ff_d20251109_m032329_c001_v0001183_t0009_u01762658609546")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ae82e935b2f3f_d20251109_m032327_c001_v0001102_t0046_u01762658607171")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f402687500d8a560a_d20251109_m032314_c001_v7007000_t0000_u01762658594588")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1037614369f3a5b4_d20251109_m032313_c001_v0001183_t0041_u01762658593381")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113254a276b1e2b9_d20251109_m032311_c001_v0001101_t0040_u01762658591123")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f422c1db6894b3002_d20251109_m032259_c001_v7007000_t0000_u01762658579635")
2025/11/09 03:24:51 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11945e0f0a3f8c89_d20251109_m032258_c001_v0001178_t0023_u01762658578392")
2025/11/09 03:24:52 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1090aad162ed84a4_d20251109_m032256_c001_v0001040_t0037_u01762658576012")
2025/11/09 03:24:52 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/resync_modes/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:24:52 DEBUG : RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f100c0ba1b49ead43_d20251109_m032248_c001_v0001102_t0030_u01762658568878")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f116a95c635367f30_d20251109_m032431_c001_v0001036_t0021_u01762658671138")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10332d71363b170c_d20251109_m032427_c001_v0001101_t0059_u01762658667841")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f102d3e6547b040e5_d20251109_m032409_c001_v0001130_t0004_u01762658649229")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1078d815876f3616_d20251109_m032406_c001_v0001092_t0053_u01762658646131")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10980a30aa9a0f3f_d20251109_m032353_c001_v0001134_t0036_u01762658633520")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112a7e94f3600fa6_d20251109_m032338_c001_v0001117_t0016_u01762658618422")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f101a5a55e241041f_d20251109_m032323_c001_v0001042_t0042_u01762658603200")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f108da0ab0c0f097d_d20251109_m032307_c001_v0001177_t0005_u01762658587775")
2025/11/09 03:24:52 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1115c1fee085ac64_d20251109_m032304_c001_v0001146_t0047_u01762658584747")
2025/11/09 03:24:53 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1097fe9b45bd9c7c_d20251109_m032252_c001_v0001109_t0049_u01762658572005")
2025/11/09 03:24:53 DEBUG : file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1032bcebdf3e5b00_d20251109_m032248_c001_v0001038_t0009_u01762658568497")
2025/11/09 03:24:53 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f113b350e78d1cfbb_d20251109_m032440_c001_v0001093_t0016_u01762658680285")
2025/11/09 03:24:53 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1072dfcf3ae60159_d20251109_m032427_c001_v0001130_t0041_u01762658667535")
2025/11/09 03:24:53 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f114ced9b979cc864_d20251109_m032417_c001_v0001101_t0019_u01762658657813")
2025/11/09 03:24:53 DEBUG : file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ee3ec0420931b_d20251109_m032248_c001_v0001183_t0002_u01762658568724")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f420ad3cb32a6f102_d20251109_m032448_c001_v7007000_t0000_u01762658688861")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f105d7d078e986283_d20251109_m032447_c001_v0001157_t0019_u01762658687480")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ee3ec04209676_d20251109_m032445_c001_v0001183_t0044_u01762658685360")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f405cd22c8924bd8a_d20251109_m032426_c001_v7007000_t0000_u01762658666897")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1147905102b612e4_d20251109_m032425_c001_v0001184_t0005_u01762658665644")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f100c0ba1b49eaeee_d20251109_m032423_c001_v0001102_t0031_u01762658663158")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f406602b1927a023e_d20251109_m032405_c001_v7007000_t0000_u01762658645506")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f109d5557509d77ee_d20251109_m032403_c001_v0001157_t0047_u01762658643899")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1032bcebdf3e5b95_d20251109_m032401_c001_v0001038_t0034_u01762658641497")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4192668b7fa880db_d20251109_m032350_c001_v7007000_t0000_u01762658630344")
2025/11/09 03:24:53 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10038880623cbff3_d20251109_m032349_c001_v0001137_t0032_u01762658629042")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ee3ec0420949f_d20251109_m032346_c001_v0001183_t0053_u01762658626822")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f4098ef171826d4f1_d20251109_m032334_c001_v7007000_t0000_u01762658614977")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1037275c40911468_d20251109_m032333_c001_v0001130_t0037_u01762658613591")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f100c0ba1b49eae07_d20251109_m032331_c001_v0001102_t0058_u01762658611301")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f405f627b9fadedf5_d20251109_m032319_c001_v7007000_t0000_u01762658599516")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11219fb1646f1393_d20251109_m032317_c001_v0001109_t0058_u01762658597873")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1032bcebdf3e5b28_d20251109_m032315_c001_v0001038_t0003_u01762658595137")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f422c1db6894b3012_d20251109_m032304_c001_v7007000_t0000_u01762658584173")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1085ca64eab48314_d20251109_m032302_c001_v0001157_t0046_u01762658582338")
2025/11/09 03:24:54 DEBUG : modtime_write_test: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f111ee3ec04209358_d20251109_m032300_c001_v0001183_t0041_u01762658580177")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/11/09 03:24:54 INFO  : path1: Making directory
2025/11/09 03:24:54 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/rmdirs/path1"
2025/11/09 03:24:56 INFO  : path2: Making directory
2025/11/09 03:24:56 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/rmdirs/path2"
2025/11/09 03:24:57 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/11/09 03:24:57 DEBUG : Creating backend with remote "/tmp/032117me/initdir/test_rmdirs-dokodup0"
2025/11/09 03:24:57 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : subdir: Making directory with metadata
2025/11/09 03:24:57 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/09 03:24:57 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/09 03:24:57 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : file1.copy1.txt.21b8fa2b.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : file1.copy2.txt.455881d5.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : Local file system at /tmp/032117me/initdir/test_rmdirs-dokodup0: Waiting for checks to finish
2025/11/09 03:24:57 DEBUG : Local file system at /tmp/032117me/initdir/test_rmdirs-dokodup0: Waiting for transfers to finish
2025/11/09 03:24:57 DEBUG : file1.copy3.txt.d0285540.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/09 03:24:57 INFO  : file1.copy1.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/09 03:24:57 INFO  : file1.copy3.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/09 03:24:57 INFO  : file1.copy2.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : file1.copy5.txt.19e8a2bc.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/09 03:24:57 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:24:57 INFO  : file1.copy5.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : file1.copy4.txt.8c987629.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:24:57 INFO  : file1.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/09 03:24:57 INFO  : file1.copy4.txt: Copied (new)
2025/11/09 03:24:57 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:24:57 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:24:57 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:24:57 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:24:57 DEBUG : subdir/file20.txt.1ac3db2f.partial: size = 0 OK
2025/11/09 03:24:57 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:24:57 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/09 03:24:57 INFO  : subdir/file20.txt: Copied (new)
2025/11/09 03:24:57 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/09 03:24:57 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/11/09 03:24:57 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_rmdirs-fisuvoj7"
2025/11/09 03:24:57 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:57 DEBUG : Local file system at /tmp/032117me/datadir/test_rmdirs-fisuvoj7: Waiting for checks to finish
2025/11/09 03:24:57 DEBUG : Local file system at /tmp/032117me/datadir/test_rmdirs-fisuvoj7: Waiting for transfers to finish
2025/11/09 03:24:57 DEBUG : file1.txt.f41fd5ee.partial: size = 19 OK
2025/11/09 03:24:57 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:24:57 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/11/09 03:24:57 INFO  : file1.txt: Copied (new)
2025/11/09 03:24:57 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:24:57 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:24:59 NOTICE: checking initFs Local file system at /tmp/032117me/initdir/test_rmdirs-dokodup0
2025/11/09 03:24:59 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 INFO  : subdir: Making directory
2025/11/09 03:24:59 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/09 03:24:59 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path1: Waiting for checks to finish
2025/11/09 03:24:59 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path1: Waiting for transfers to finish
2025/11/09 03:25:00 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.copy2.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.copy1.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.copy4.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.copy5.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.copy3.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : subdir/file20.txt: Copied (new)
2025/11/09 03:25:00 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:25:00 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:00 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:01 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:25:01 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:25:01 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:25:01 NOTICE: checking Path1 B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path1
2025/11/09 03:25:01 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 INFO  : subdir: Making directory
2025/11/09 03:25:01 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:01 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path2: Waiting for checks to finish
2025/11/09 03:25:01 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path2: Waiting for transfers to finish
2025/11/09 03:25:02 DEBUG : file1.copy2.txt: size = 0 OK
2025/11/09 03:25:02 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:02 INFO  : file1.copy2.txt: Copied (new)
2025/11/09 03:25:02 DEBUG : file1.copy4.txt: size = 0 OK
2025/11/09 03:25:02 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:02 INFO  : file1.copy4.txt: Copied (new)
2025/11/09 03:25:02 DEBUG : file1.copy5.txt: size = 0 OK
2025/11/09 03:25:02 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:02 INFO  : file1.copy5.txt: Copied (new)
2025/11/09 03:25:02 DEBUG : file1.copy1.txt: size = 0 OK
2025/11/09 03:25:02 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:02 INFO  : file1.copy1.txt: Copied (new)
2025/11/09 03:25:02 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:25:02 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:02 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:03 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:25:03 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:25:03 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:25:03 DEBUG : file1.copy3.txt: size = 0 OK
2025/11/09 03:25:03 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:03 INFO  : file1.copy3.txt: Copied (new)
2025/11/09 03:25:03 DEBUG : subdir/file20.txt: size = 0 OK
2025/11/09 03:25:03 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:03 INFO  : subdir/file20.txt: Copied (new)
2025/11/09 03:25:03 NOTICE: checking path2 B2 bucket rclone-test-xefocad6voga path 032117me/rmdirs/path2
2025/11/09 03:25:03 NOTICE: (01)  : test rmdirs
2025/11/09 03:25:03 NOTICE: (02)  : test initial bisync
2025/11/09 03:25:03 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/11/09 03:25:03 INFO  : path1: Making directory
2025/11/09 03:25:03 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/volatile/path1"
2025/11/09 03:25:04 INFO  : path2: Making directory
2025/11/09 03:25:04 DEBUG : Creating backend with remote "TestB2:rclone-test-xefocad6voga/032117me/volatile/path2"
2025/11/09 03:25:05 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/11/09 03:25:05 DEBUG : Creating backend with remote "/tmp/032117me/initdir/test_volatile-hutemot2"
2025/11/09 03:25:05 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file1.txt.56d0d699.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/09 03:25:05 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : Local file system at /tmp/032117me/initdir/test_volatile-hutemot2: Waiting for checks to finish
2025/11/09 03:25:05 DEBUG : Local file system at /tmp/032117me/initdir/test_volatile-hutemot2: Waiting for transfers to finish
2025/11/09 03:25:05 DEBUG : file2.txt.3230ad67.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file4.txt.fbf05a9b.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file3.txt.a74079f2.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/11/09 03:25:05 INFO  : file4.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/11/09 03:25:05 INFO  : file3.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file5.txt.6e808e0e.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/11/09 03:25:05 INFO  : file5.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file6.txt.0a60f5f0.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file7.txt.9f102165.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/11/09 03:25:05 INFO  : file6.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/11/09 03:25:05 INFO  : file7.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file8.txt.b300b322.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/11/09 03:25:05 INFO  : file8.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : RCLONE_TEST.abb09051.partial: size = 109 OK
2025/11/09 03:25:05 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/09 03:25:05 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/11/09 03:25:05 INFO  : file2.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/09 03:25:05 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:25:05 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/11/09 03:25:05 DEBUG : Creating backend with remote "/tmp/032117me/datadir/test_volatile-tuwexun2"
2025/11/09 03:25:05 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:05 DEBUG : Local file system at /tmp/032117me/datadir/test_volatile-tuwexun2: Waiting for checks to finish
2025/11/09 03:25:05 DEBUG : Local file system at /tmp/032117me/datadir/test_volatile-tuwexun2: Waiting for transfers to finish
2025/11/09 03:25:05 DEBUG : dummy.txt.b3782999.partial: size = 0 OK
2025/11/09 03:25:05 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/09 03:25:05 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/11/09 03:25:05 INFO  : dummy.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file11.txt.d4a7309d.partial: size = 19 OK
2025/11/09 03:25:05 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:25:05 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/11/09 03:25:05 INFO  : file11.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file1.txt.be52b1c9.partial: size = 19 OK
2025/11/09 03:25:05 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:25:05 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/11/09 03:25:05 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file5R.txt.ee3b9525.partial: size = 39 OK
2025/11/09 03:25:05 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/11/09 03:25:05 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/11/09 03:25:05 INFO  : file5R.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file10.txt.bdde3bf8.partial: size = 19 OK
2025/11/09 03:25:05 DEBUG : file5L.txt.8dd6541a.partial: size = 39 OK
2025/11/09 03:25:05 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/11/09 03:25:05 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/11/09 03:25:05 INFO  : file5L.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:25:05 DEBUG : file6.txt.7b4c87b3.partial: size = 19 OK
2025/11/09 03:25:05 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:25:05 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/11/09 03:25:05 INFO  : file10.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/11/09 03:25:05 INFO  : file6.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file7.txt.38c43fbf.partial: size = 19 OK
2025/11/09 03:25:05 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/09 03:25:05 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/11/09 03:25:05 INFO  : file7.txt: Copied (new)
2025/11/09 03:25:05 DEBUG : file2.txt.23ff1769.partial: size = 13 OK
2025/11/09 03:25:05 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/11/09 03:25:05 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/11/09 03:25:05 INFO  : file2.txt: Copied (new)
2025/11/09 03:25:05 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path1: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:25:06 INFO  : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path2: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:25:07 NOTICE: checking initFs Local file system at /tmp/032117me/initdir/test_volatile-hutemot2
2025/11/09 03:25:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:07 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path1: Waiting for checks to finish
2025/11/09 03:25:07 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path1: Waiting for transfers to finish
2025/11/09 03:25:08 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:25:08 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:08 INFO  : file2.txt: Copied (new)
2025/11/09 03:25:08 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:25:08 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:08 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:09 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:25:09 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:25:09 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:25:09 DEBUG : file4.txt: size = 0 OK
2025/11/09 03:25:09 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:09 INFO  : file4.txt: Copied (new)
2025/11/09 03:25:09 DEBUG : file7.txt: size = 0 OK
2025/11/09 03:25:09 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:09 INFO  : file7.txt: Copied (new)
2025/11/09 03:25:09 DEBUG : file6.txt: size = 0 OK
2025/11/09 03:25:09 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:09 INFO  : file6.txt: Copied (new)
2025/11/09 03:25:10 DEBUG : file3.txt: size = 0 OK
2025/11/09 03:25:10 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:10 INFO  : file3.txt: Copied (new)
2025/11/09 03:25:10 DEBUG : file8.txt: size = 0 OK
2025/11/09 03:25:10 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:10 INFO  : file8.txt: Copied (new)
2025/11/09 03:25:10 DEBUG : file5.txt: size = 0 OK
2025/11/09 03:25:10 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:10 INFO  : file5.txt: Copied (new)
2025/11/09 03:25:10 NOTICE: checking Path1 B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path1
2025/11/09 03:25:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/11/09 03:25:10 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path2: Waiting for checks to finish
2025/11/09 03:25:10 DEBUG : B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path2: Waiting for transfers to finish
2025/11/09 03:25:11 DEBUG : file2.txt: size = 0 OK
2025/11/09 03:25:11 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:11 INFO  : file2.txt: Copied (new)
2025/11/09 03:25:11 DEBUG : file3.txt: size = 0 OK
2025/11/09 03:25:11 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:11 INFO  : file3.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : file5.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file5.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : file4.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file4.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : RCLONE_TEST: size = 109 OK
2025/11/09 03:25:12 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/09 03:25:12 INFO  : RCLONE_TEST: Copied (new)
2025/11/09 03:25:12 DEBUG : file6.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file6.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : file1.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file1.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : file7.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file7.txt: Copied (new)
2025/11/09 03:25:12 DEBUG : file8.txt: size = 0 OK
2025/11/09 03:25:12 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/09 03:25:12 INFO  : file8.txt: Copied (new)
2025/11/09 03:25:12 NOTICE: checking path2 B2 bucket rclone-test-xefocad6voga path 032117me/volatile/path2
2025/11/09 03:25:12 NOTICE: (01)  : test volatile
2025/11/09 03:25:12 NOTICE: (02)  : test initial bisync
2025/11/09 03:25:12 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/11/09 03:25:12 DEBUG : B2 bucket rclone-test-xefocad6voga: Purge remote
2025/11/09 03:25:12 INFO  : B2 bucket rclone-test-xefocad6voga: cleaning bucket "rclone-test-xefocad6voga" of all files
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f115ae5552fec4e2b_d20251109_m032500_c001_v0001134_t0019_u01762658700474")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.copy1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1174e4811d76d669_d20251109_m032459_c001_v0001177_t0023_u01762658699950")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.copy2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f102fd490f2bd4f81_d20251109_m032500_c001_v0001178_t0054_u01762658700072")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.copy3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f106f761f20bcf966_d20251109_m032500_c001_v0001136_t0059_u01762658700277")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.copy4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f102fd490f2bd4f83_d20251109_m032500_c001_v0001178_t0004_u01762658700339")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.copy5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1174e4811d76d671_d20251109_m032500_c001_v0001177_t0000_u01762658700347")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f102fd490f2bd4f87_d20251109_m032500_c001_v0001178_t0048_u01762658700566")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path1/subdir/file20.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1174e4811d76d675_d20251109_m032500_c001_v0001177_t0055_u01762658700574")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path2/RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112fc94fd98cdb9f_d20251109_m032502_c001_v0001150_t0017_u01762658702734")
2025/11/09 03:25:13 DEBUG : 032117me/rmdirs/path2/file1.copy1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112286ac3f941731_d20251109_m032502_c001_v0001040_t0015_u01762658702319")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/file1.copy2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118ccee1c5184862_d20251109_m032502_c001_v0001183_t0024_u01762658702113")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/file1.copy3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107efcf78c9ef8b9_d20251109_m032502_c001_v0001172_t0030_u01762658702557")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/file1.copy4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118ccee1c5184866_d20251109_m032502_c001_v0001183_t0056_u01762658702353")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/file1.copy5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118ccee1c5184868_d20251109_m032502_c001_v0001183_t0047_u01762658702562")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f118ccee1c518486c_d20251109_m032502_c001_v0001183_t0037_u01762658702779")
2025/11/09 03:25:14 DEBUG : 032117me/rmdirs/path2/subdir/file20.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f112286ac3f941737_d20251109_m032502_c001_v0001040_t0027_u01762658702963")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1146ae28eb6323fd_d20251109_m032509_c001_v0001038_t0004_u01762658709031")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10472c7a9524acc4_d20251109_m032508_c001_v0001106_t0045_u01762658708467")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107e1996b7c25967_d20251109_m032508_c001_v0001093_t0004_u01762658708626")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f114fa29d5474581b_d20251109_m032508_c001_v0001115_t0046_u01762658708824")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107e1996b7c2596b_d20251109_m032509_c001_v0001093_t0055_u01762658709058")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10472c7a9524acc8_d20251109_m032509_c001_v0001106_t0053_u01762658709087")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1146ae28eb632401_d20251109_m032509_c001_v0001038_t0014_u01762658709394")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107e1996b7c2596d_d20251109_m032509_c001_v0001093_t0046_u01762658709527")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path1/file8.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f107e1996b7c25971_d20251109_m032509_c001_v0001093_t0052_u01762658709853")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path2/RCLONE_TEST: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11093297cd8df30b_d20251109_m032512_c001_v0001100_t0047_u01762658712000")
2025/11/09 03:25:14 DEBUG : 032117me/volatile/path2/file1.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11191af0d07f970c_d20251109_m032511_c001_v0001172_t0054_u01762658711435")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file2.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10136ba971fb5df8_d20251109_m032511_c001_v0001099_t0048_u01762658711631")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file3.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1059beca45dece9e_d20251109_m032511_c001_v0001182_t0046_u01762658711811")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file4.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10136ba971fb5dfc_d20251109_m032511_c001_v0001099_t0004_u01762658711954")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file5.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1059beca45decea2_d20251109_m032512_c001_v0001182_t0010_u01762658712019")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file6.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f1059beca45decea8_d20251109_m032512_c001_v0001182_t0054_u01762658712260")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file7.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f10136ba971fb5dfe_d20251109_m032512_c001_v0001099_t0027_u01762658712273")
2025/11/09 03:25:15 DEBUG : 032117me/volatile/path2/file8.txt: Deleting (id "4_z4f32eda3a01d6b6b9fa80e1b_f11093297cd8df30f_d20251109_m032512_c001_v0001100_t0046_u01762658712372")
--- PASS: TestBisyncRemoteRemote (239.70s)
    --- PASS: TestBisyncRemoteRemote/resync (84.81s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (133.05s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.54s)
    --- SKIP: TestBisyncRemoteRemote/volatile (9.40s)
FAIL
2025/11/09 03:25:16 DEBUG : B2 bucket rclone-test-cosebad4tija: Purge remote
2025/11/09 03:25:16 INFO  : B2 bucket rclone-test-cosebad4tija: cleaning bucket "rclone-test-cosebad4tija" of all files
2025/11/09 03:25:16 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^resync$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished ERROR in 4m41.771742737s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/resync]