"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/08/19 02:41:37 DEBUG : Creating backend with remote "TestB2:rclone-test-roroxic5cewe"
2025/08/19 02:41:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/19 02:41:37 DEBUG : Creating backend with remote "/tmp/rclone4270564116"
=== RUN   TestBisyncRemoteRemote
2025/08/19 02:41:37 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba"
2025/08/19 02:41:38 NOTICE: remote: TestB2:rclone-test-vikeyiw3wiba
=== RUN   TestBisyncRemoteRemote/resync
2025/08/19 02:41:38 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je"
2025/08/19 02:41:39 INFO  : path1: Making directory
2025/08/19 02:41:42 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1"
2025/08/19 02:41:43 INFO  : path2: Making directory
2025/08/19 02:41:43 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2"
2025/08/19 02:41:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/08/19 02:41:44 DEBUG : Creating backend with remote "/tmp/024138je/initdir/test_resync-wapurog4"
2025/08/19 02:41:44 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/19 02:41:44 INFO  : file1.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : Local file system at /tmp/024138je/initdir/test_resync-wapurog4: Waiting for checks to finish
2025/08/19 02:41:44 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : Local file system at /tmp/024138je/initdir/test_resync-wapurog4: Waiting for transfers to finish
2025/08/19 02:41:44 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/19 02:41:44 INFO  : file2.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/19 02:41:44 INFO  : file5.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/19 02:41:44 INFO  : file6.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:41:44 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/19 02:41:44 INFO  : file7.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/19 02:41:44 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/19 02:41:44 INFO  : file4.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/19 02:41:44 INFO  : file3.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/19 02:41:44 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:41:44 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/08/19 02:41:44 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9"
2025/08/19 02:41:44 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:44 DEBUG : Local file system at /tmp/024138je/datadir/test_resync-lapalik9: Waiting for checks to finish
2025/08/19 02:41:44 DEBUG : Local file system at /tmp/024138je/datadir/test_resync-lapalik9: Waiting for transfers to finish
2025/08/19 02:41:44 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:41:44 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt
2025/08/19 02:41:44 INFO  : fileA.txt: Copied (new)
2025/08/19 02:41:44 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:41:44 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt
2025/08/19 02:41:44 INFO  : fileB.txt: Copied (new)
2025/08/19 02:41:44 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:41:45 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:41:46 NOTICE: checking initFs Local file system at /tmp/024138je/initdir/test_resync-wapurog4
2025/08/19 02:41:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:46 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Waiting for checks to finish
2025/08/19 02:41:46 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Waiting for transfers to finish
2025/08/19 02:41:47 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:47 INFO  : file1.txt: Copied (new)
2025/08/19 02:41:47 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:47 INFO  : file2.txt: Copied (new)
2025/08/19 02:41:48 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:48 INFO  : file4.txt: Copied (new)
2025/08/19 02:41:48 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:48 INFO  : file3.txt: Copied (new)
2025/08/19 02:41:48 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:41:48 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:41:48 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:48 INFO  : file6.txt: Copied (new)
2025/08/19 02:41:48 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:48 INFO  : file5.txt: Copied (new)
2025/08/19 02:41:48 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:48 INFO  : file7.txt: Copied (new)
2025/08/19 02:41:48 NOTICE: checking Path1 B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1
2025/08/19 02:41:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:41:49 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Waiting for checks to finish
2025/08/19 02:41:49 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Waiting for transfers to finish
2025/08/19 02:41:50 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file1.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file2.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file4.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file3.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:41:50 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:41:50 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file5.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file7.txt: Copied (new)
2025/08/19 02:41:50 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:41:50 INFO  : file6.txt: Copied (new)
2025/08/19 02:41:50 NOTICE: checking path2 B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2
2025/08/19 02:41:50 NOTICE: (01)  : test resync
2025/08/19 02:41:50 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/08/19 02:41:50 NOTICE: (03)  : purge-children TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/
2025/08/19 02:41:51 DEBUG : RCLONE_TEST: Remove file
2025/08/19 02:41:51 DEBUG : file1.txt: Remove file
2025/08/19 02:41:51 DEBUG : file2.txt: Remove file
2025/08/19 02:41:51 DEBUG : file3.txt: Remove file
2025/08/19 02:41:51 DEBUG : file4.txt: Remove file
2025/08/19 02:41:52 DEBUG : file5.txt: Remove file
2025/08/19 02:41:52 DEBUG : file6.txt: Remove file
2025/08/19 02:41:52 DEBUG : file7.txt: Remove file
2025/08/19 02:41:52 NOTICE: (04)  : bisync resync
2025/08/19 02:42:00 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:00 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:42:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:42:00 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:00 INFO  : Copying Path2 files to Path1
2025/08/19 02:42:00 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:42:00 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/08/19 02:42:00 INFO  : file3.txt: Copied (server-side copy)
2025/08/19 02:42:00 INFO  : file2.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : file4.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : file5.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : file6.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : file7.txt: Copied (server-side copy)
2025/08/19 02:42:01 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:42:01 INFO  : Resync updating listings
2025/08/19 02:42:01 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:01 INFO  : Bisync successful
2025/08/19 02:42:01 NOTICE: (05)  : move-listings empty-path1
2025/08/19 02:42:01 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/08/19 02:42:01 NOTICE: (07)  : purge-children TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/
2025/08/19 02:42:02 DEBUG : RCLONE_TEST: Remove file
2025/08/19 02:42:02 DEBUG : file1.txt: Remove file
2025/08/19 02:42:02 DEBUG : file2.txt: Remove file
2025/08/19 02:42:02 DEBUG : file3.txt: Remove file
2025/08/19 02:42:02 DEBUG : file4.txt: Remove file
2025/08/19 02:42:03 DEBUG : file5.txt: Remove file
2025/08/19 02:42:03 DEBUG : file6.txt: Remove file
2025/08/19 02:42:03 DEBUG : file7.txt: Remove file
2025/08/19 02:42:03 NOTICE: (08)  : bisync resync
2025/08/19 02:42:12 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:12 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:12 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:42:12 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:42:12 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:12 INFO  : Copying Path2 files to Path1
2025/08/19 02:42:12 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:42:12 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:42:13 INFO  : RCLONE_TEST: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file2.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file3.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file5.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file4.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file7.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : file6.txt: Copied (server-side copy)
2025/08/19 02:42:13 INFO  : Resync updating listings
2025/08/19 02:42:13 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:13 INFO  : Bisync successful
2025/08/19 02:42:13 NOTICE: (09)  : move-listings empty-path2
2025/08/19 02:42:13 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/08/19 02:42:13 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/024138je/datadir/test_resync-lapalik9/ fileA.txt
2025/08/19 02:42:13 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/"
2025/08/19 02:42:13 DEBUG : fs cache: renaming cache item "/tmp/024138je/datadir/test_resync-lapalik9/" to be canonical "/tmp/024138je/datadir/test_resync-lapalik9"
2025/08/19 02:42:13 DEBUG : fileA.txt: Set modification time 2002-02-02
2025/08/19 02:42:13 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/024138je/datadir/test_resync-lapalik9/ fileB.txt
2025/08/19 02:42:13 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync-lapalik9/" for canonical name "/tmp/024138je/datadir/test_resync-lapalik9"
2025/08/19 02:42:13 DEBUG : fileB.txt: Set modification time 1999-09-09
2025/08/19 02:42:13 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/08/19 02:42:13 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/08/19 02:42:13 NOTICE: (15)  : delete-file TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/file1.txt
2025/08/19 02:42:13 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:15 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/" to be canonical "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2"
2025/08/19 02:42:15 INFO  : file1.txt: Deleted
2025/08/19 02:42:15 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/08/19 02:42:15 NOTICE: (17)  : delete-file TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/file2.txt
2025/08/19 02:42:15 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/"
2025/08/19 02:42:16 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" to be canonical "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1"
2025/08/19 02:42:17 DEBUG : pacer: low level retry 1/10 (error Post "https://api001.backblazeb2.com/b2api/v1/b2_hide_file": EOF)
2025/08/19 02:42:17 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2025/08/19 02:42:17 DEBUG : pacer: Reducing sleep to 10ms
2025/08/19 02:42:17 INFO  : file2.txt: Deleted
2025/08/19 02:42:17 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/08/19 02:42:17 NOTICE: (19)  : copy-as /tmp/024138je/datadir/test_resync-lapalik9/fileA.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/ file3.txt
2025/08/19 02:42:17 DEBUG : copyFile "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/" as "file3.txt"
2025/08/19 02:42:17 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt"
2025/08/19 02:42:17 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:18 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2" as "file3.txt"
2025/08/19 02:42:19 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/19 02:42:20 DEBUG : file3.txt: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/08/19 02:42:20 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/08/19 02:42:20 DEBUG : pacer: Rate limited, increasing sleep to 1s
2025/08/19 02:42:20 DEBUG : fileA.txt: Received error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 0/10
2025/08/19 02:42:20 DEBUG : pacer: Reducing sleep to 500ms
2025/08/19 02:42:22 DEBUG : pacer: Reducing sleep to 250ms
2025/08/19 02:42:22 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/19 02:42:22 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2025/08/19 02:42:22 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/08/19 02:42:22 NOTICE: (21)  : delete-file TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/file4.txt
2025/08/19 02:42:22 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" for canonical name "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1"
2025/08/19 02:42:23 INFO  : file4.txt: Deleted
2025/08/19 02:42:23 NOTICE: (22)  : copy-as /tmp/024138je/datadir/test_resync-lapalik9/fileA.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/ file4.txt
2025/08/19 02:42:23 DEBUG : copyFile "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/" as "file4.txt"
2025/08/19 02:42:23 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt"
2025/08/19 02:42:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:24 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2" as "file4.txt"
2025/08/19 02:42:24 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/19 02:42:26 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/19 02:42:26 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2025/08/19 02:42:26 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/08/19 02:42:26 NOTICE: (24)  : copy-as /tmp/024138je/datadir/test_resync-lapalik9/fileB.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/ file5.txt
2025/08/19 02:42:26 DEBUG : copyFile "/tmp/024138je/datadir/test_resync-lapalik9/fileB.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/" as "file5.txt"
2025/08/19 02:42:26 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/fileB.txt"
2025/08/19 02:42:26 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:27 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2" as "file5.txt"
2025/08/19 02:42:27 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/19 02:42:29 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/19 02:42:29 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2025/08/19 02:42:29 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/08/19 02:42:29 NOTICE: (26)  : copy-as /tmp/024138je/datadir/test_resync-lapalik9/fileB.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/ file6.txt
2025/08/19 02:42:29 DEBUG : copyFile "/tmp/024138je/datadir/test_resync-lapalik9/fileB.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" as "file6.txt"
2025/08/19 02:42:29 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/fileB.txt"
2025/08/19 02:42:29 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/"
2025/08/19 02:42:30 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1" as "file6.txt"
2025/08/19 02:42:30 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0)
2025/08/19 02:42:32 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/19 02:42:32 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2025/08/19 02:42:32 NOTICE: (27)  : copy-as /tmp/024138je/datadir/test_resync-lapalik9/fileA.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/ file6.txt
2025/08/19 02:42:32 DEBUG : copyFile "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/" as "file6.txt"
2025/08/19 02:42:32 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync-lapalik9/fileA.txt"
2025/08/19 02:42:32 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:33 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2" as "file6.txt"
2025/08/19 02:42:33 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0)
2025/08/19 02:42:35 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/08/19 02:42:35 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2025/08/19 02:42:35 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/08/19 02:42:35 NOTICE: (29)  : test run bisync with resync
2025/08/19 02:42:35 NOTICE: (30)  : bisync resync
2025/08/19 02:42:43 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:43 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:42:43 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:42:43 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:43 INFO  : Copying Path2 files to Path1
2025/08/19 02:42:43 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:42:44 INFO  : file2.txt: Copied (server-side copy)
2025/08/19 02:42:44 INFO  : file4.txt: Copied (server-side copy)
2025/08/19 02:42:44 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:42:45 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:42:45 INFO  : file5.txt: Copied (server-side copy)
2025/08/19 02:42:45 INFO  : file3.txt: Copied (server-side copy)
2025/08/19 02:42:45 INFO  : file6.txt: Updated modification time in destination
2025/08/19 02:42:45 INFO  : Resync updating listings
2025/08/19 02:42:45 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:45 INFO  : Bisync successful
2025/08/19 02:42:45 NOTICE: (31)  : copy-listings mixed-diffs
2025/08/19 02:42:45 NOTICE: (32)  : test run normal bisync
2025/08/19 02:42:45 NOTICE: (33)  : bisync
2025/08/19 02:42:53 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:53 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:42:53 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:42:53 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:42:53 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:53 INFO  : Building Path1 and Path2 listings
2025/08/19 02:42:53 INFO  : Path1 checking for diffs
2025/08/19 02:42:53 INFO  : Path2 checking for diffs
2025/08/19 02:42:53 INFO  : No changes found
2025/08/19 02:42:53 INFO  : Updating listings
2025/08/19 02:42:53 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:42:53 INFO  : Bisync successful
2025/08/19 02:42:53 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/08/19 02:42:53 NOTICE: (35)  : purge-children TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/
2025/08/19 02:42:53 DEBUG : RCLONE_TEST: Remove file
2025/08/19 02:42:54 DEBUG : file1.txt: Remove file
2025/08/19 02:42:54 DEBUG : file2.txt: Remove file
2025/08/19 02:42:54 DEBUG : file3.txt: Remove file
2025/08/19 02:42:54 DEBUG : file4.txt: Remove file
2025/08/19 02:42:54 DEBUG : file5.txt: Remove file
2025/08/19 02:42:55 DEBUG : file6.txt: Remove file
2025/08/19 02:42:55 DEBUG : file7.txt: Remove file
2025/08/19 02:42:55 NOTICE: (36)  : bisync
2025/08/19 02:43:03 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:03 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:43:03 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:43:03 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync/path2/"
2025/08/19 02:43:03 INFO  : Building Path1 and Path2 listings
2025/08/19 02:43:03 INFO  : Path1 checking for diffs
2025/08/19 02:43:03 INFO  : Path2 checking for diffs
2025/08/19 02:43:03 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/024138je/workdir/TestB2_rclone-test-vikeyiw3wiba_024138je_resync_path1..TestB2_rclone-test-vikeyiw3wiba_024138je_resync_path2.path2.lst-new
2025/08/19 02:43:03 ERROR : Bisync critical error: empty current Path2 listing: /tmp/024138je/workdir/TestB2_rclone-test-vikeyiw3wiba_024138je_resync_path1..TestB2_rclone-test-vikeyiw3wiba_024138je_resync_path2.path2.lst-new
2025/08/19 02:43:03 ERROR : Bisync aborted. Must run --resync to recover.
2025/08/19 02:43:03 NOTICE: Bisync error: bisync aborted
2025/08/19 02:43:03 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:43:04 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104afaed27ba49c8_d20250819_m024200_c001_v0001153_t0014_u01755571320383")
2025/08/19 02:43:04 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d317_d20250819_m024151_c001_v7007000_t0000_u01755571311277")
2025/08/19 02:43:04 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1088d36811998774_d20250819_m024147_c001_v0001171_t0012_u01755571307843")
2025/08/19 02:43:04 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105f512a22a49d84_d20250819_m024200_c001_v0001134_t0031_u01755571320758")
2025/08/19 02:43:04 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d318_d20250819_m024151_c001_v7007000_t0000_u01755571311460")
2025/08/19 02:43:04 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106277b2ef3b6f82_d20250819_m024147_c001_v0001036_t0015_u01755571307278")
2025/08/19 02:43:04 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113520b18a90823b_d20250819_m024244_c001_v0001137_t0019_u01755571364196")
2025/08/19 02:43:04 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4070d5d6716dba5b_d20250819_m024217_c001_v7007000_t0000_u01755571337625")
2025/08/19 02:43:04 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f110a0a6cac3e97a5_d20250819_m024200_c001_v0001176_t0058_u01755571320763")
2025/08/19 02:43:04 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d319_d20250819_m024151_c001_v7007000_t0000_u01755571311654")
2025/08/19 02:43:04 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105fd40fee65a64c_d20250819_m024147_c001_v0001093_t0025_u01755571307630")
2025/08/19 02:43:04 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1171445f66a8a6d5_d20250819_m024200_c001_v0001183_t0036_u01755571320766")
2025/08/19 02:43:04 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d31a_d20250819_m024151_c001_v7007000_t0000_u01755571311837")
2025/08/19 02:43:04 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11081ff1b43b41b8_d20250819_m024147_c001_v0001104_t0014_u01755571307440")
2025/08/19 02:43:04 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1119de6387228a74_d20250819_m024244_c001_v0001183_t0028_u01755571364570")
2025/08/19 02:43:04 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f41813bcb8bf8e785_d20250819_m024223_c001_v7007000_t0000_u01755571343493")
2025/08/19 02:43:04 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f103c9c312c52a850_d20250819_m024200_c001_v0001104_t0049_u01755571320776")
2025/08/19 02:43:04 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d31c_d20250819_m024152_c001_v7007000_t0000_u01755571312022")
2025/08/19 02:43:04 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106277b2ef3b6f84_d20250819_m024147_c001_v0001036_t0047_u01755571307703")
2025/08/19 02:43:04 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1155fcb550db1a19_d20250819_m024201_c001_v0001044_t0042_u01755571321003")
2025/08/19 02:43:05 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d31d_d20250819_m024152_c001_v7007000_t0000_u01755571312206")
2025/08/19 02:43:05 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105fd40fee65a650_d20250819_m024148_c001_v0001093_t0035_u01755571308007")
2025/08/19 02:43:05 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115aaec9ef8fcca7_d20250819_m024231_c001_v0001040_t0017_u01755571351889")
2025/08/19 02:43:05 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f107e901ef4b9e4b0_d20250819_m024201_c001_v0001117_t0045_u01755571321052")
2025/08/19 02:43:05 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d31e_d20250819_m024152_c001_v7007000_t0000_u01755571312389")
2025/08/19 02:43:05 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106277b2ef3b6f86_d20250819_m024148_c001_v0001036_t0019_u01755571308095")
2025/08/19 02:43:05 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f114e66dd40829079_d20250819_m024201_c001_v0001037_t0028_u01755571321259")
2025/08/19 02:43:05 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d31f_d20250819_m024152_c001_v7007000_t0000_u01755571312575")
2025/08/19 02:43:05 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11081ff1b43b41ba_d20250819_m024148_c001_v0001104_t0024_u01755571308121")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f40828a1d17ee0ffd_d20250819_m024259_c001_v7007000_t0000_u01755571379156")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11795a770180df67_d20250819_m024257_c001_v0001093_t0019_u01755571377723")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1088d36811998903_d20250819_m024255_c001_v0001171_t0058_u01755571375844")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f40828a1d17ee0fdb_d20250819_m024249_c001_v7007000_t0000_u01755571369702")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1015cca73e31fcc4_d20250819_m024248_c001_v0001105_t0051_u01755571368391")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11081ff1b43b4222_d20250819_m024246_c001_v0001104_t0003_u01755571366229")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4210861356e1bd96_d20250819_m024239_c001_v7007000_t0000_u01755571359613")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118eb52bb44dc597_d20250819_m024238_c001_v0001038_t0012_u01755571358226")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105fd40fee65a6c3_d20250819_m024235_c001_v0001093_t0041_u01755571355944")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f400e9330133ef868_d20250819_m024207_c001_v7007000_t0000_u01755571327651")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11927f6fce55d55c_d20250819_m024206_c001_v0001101_t0011_u01755571326212")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106277b2ef3b6f9e_d20250819_m024204_c001_v0001036_t0013_u01755571324075")
2025/08/19 02:43:05 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f415bcd026714d321_d20250819_m024155_c001_v7007000_t0000_u01755571315932")
2025/08/19 02:43:06 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f114902c654dde1e6_d20250819_m024154_c001_v0001171_t0039_u01755571314602")
2025/08/19 02:43:06 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1088d36811998792_d20250819_m024152_c001_v0001171_t0010_u01755571312759")
2025/08/19 02:43:06 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:43:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d30_d20250819_m024254_c001_v7007000_t0000_u01755571374001")
2025/08/19 02:43:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1170d32488a0a690_d20250819_m024212_c001_v0001044_t0009_u01755571332653")
2025/08/19 02:43:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d15_d20250819_m024202_c001_v7007000_t0000_u01755571322237")
2025/08/19 02:43:06 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118caedac9902ed9_d20250819_m024150_c001_v0001171_t0016_u01755571310367")
2025/08/19 02:43:06 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d31_d20250819_m024254_c001_v7007000_t0000_u01755571374181")
2025/08/19 02:43:06 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f103251c7e0ed3dbe_d20250819_m024244_c001_v0001029_t0000_u01755571364992")
2025/08/19 02:43:06 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4157d5f4e01f60f1_d20250819_m024215_c001_v7007000_t0000_u01755571335704")
2025/08/19 02:43:06 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11711b32af50e430_d20250819_m024213_c001_v0001157_t0045_u01755571333031")
2025/08/19 02:43:06 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d17_d20250819_m024202_c001_v7007000_t0000_u01755571322419")
2025/08/19 02:43:07 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10444d68b37b25cd_d20250819_m024149_c001_v0001105_t0015_u01755571309826")
2025/08/19 02:43:07 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d32_d20250819_m024254_c001_v7007000_t0000_u01755571374374")
2025/08/19 02:43:07 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f109cbf9b2322239d_d20250819_m024213_c001_v0001172_t0004_u01755571333030")
2025/08/19 02:43:07 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d18_d20250819_m024202_c001_v7007000_t0000_u01755571322610")
2025/08/19 02:43:07 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115caf6b2c6d7c3d_d20250819_m024149_c001_v0001041_t0023_u01755571309995")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d33_d20250819_m024254_c001_v7007000_t0000_u01755571374555")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106c43a293a76bc2_d20250819_m024245_c001_v0001157_t0053_u01755571365346")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1012e1e4743fefc4_d20250819_m024222_c001_v0001090_t0004_u01755571342157")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1123cfd400aea627_d20250819_m024213_c001_v0001113_t0028_u01755571333051")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d19_d20250819_m024202_c001_v7007000_t0000_u01755571322791")
2025/08/19 02:43:07 DEBUG : file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11526312b79a0f4f_d20250819_m024150_c001_v0001100_t0034_u01755571310178")
2025/08/19 02:43:07 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d34_d20250819_m024254_c001_v7007000_t0000_u01755571374749")
2025/08/19 02:43:07 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1090a3da95c52ca2_d20250819_m024226_c001_v0001182_t0019_u01755571346252")
2025/08/19 02:43:07 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10278cb85bb123f8_d20250819_m024213_c001_v0001120_t0043_u01755571333305")
2025/08/19 02:43:08 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d1a_d20250819_m024202_c001_v7007000_t0000_u01755571322972")
2025/08/19 02:43:08 DEBUG : file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10444d68b37b25cf_d20250819_m024150_c001_v0001105_t0054_u01755571310161")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d35_d20250819_m024254_c001_v7007000_t0000_u01755571374932")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f101c6eb031bfdf8a_d20250819_m024245_c001_v0001157_t0015_u01755571365361")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113a3ddb20688116_d20250819_m024229_c001_v0001183_t0033_u01755571349096")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f107c62486a07db1c_d20250819_m024213_c001_v0001183_t0051_u01755571333485")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d1b_d20250819_m024203_c001_v7007000_t0000_u01755571323154")
2025/08/19 02:43:08 DEBUG : file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115caf6b2c6d7c3f_d20250819_m024150_c001_v0001041_t0047_u01755571310397")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d36_d20250819_m024255_c001_v7007000_t0000_u01755571375112")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f117a8434d1c00966_d20250819_m024245_c001_v0001100_t0047_u01755571365544")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11923de9f6161ba7_d20250819_m024235_c001_v0001102_t0024_u01755571355138")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1041dee82fc629a6_d20250819_m024213_c001_v0001038_t0046_u01755571333530")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d1c_d20250819_m024203_c001_v7007000_t0000_u01755571323344")
2025/08/19 02:43:08 DEBUG : file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10444d68b37b25d1_d20250819_m024150_c001_v0001105_t0027_u01755571310448")
2025/08/19 02:43:08 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d38_d20250819_m024255_c001_v7007000_t0000_u01755571375293")
2025/08/19 02:43:08 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1096d34c6f8bca81_d20250819_m024213_c001_v0001150_t0013_u01755571333551")
2025/08/19 02:43:08 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d1d_d20250819_m024203_c001_v7007000_t0000_u01755571323528")
2025/08/19 02:43:08 DEBUG : file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11526312b79a0f51_d20250819_m024150_c001_v0001100_t0055_u01755571310600")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f416fa9f852a36145_d20250819_m024303_c001_v7007000_t0000_u01755571383438")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1132956f1d2504f1_d20250819_m024301_c001_v0001134_t0009_u01755571381976")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118caedac99030c3_d20250819_m024259_c001_v0001171_t0010_u01755571379711")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d2f_d20250819_m024253_c001_v7007000_t0000_u01755571373440")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118711b3efeb947c_d20250819_m024252_c001_v0001182_t0027_u01755571372225")
2025/08/19 02:43:08 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10444d68b37b2687_d20250819_m024250_c001_v0001105_t0057_u01755571370261")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f40430b240baa2131_d20250819_m024243_c001_v7007000_t0000_u01755571363808")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f112a56a5e545534f_d20250819_m024242_c001_v0001100_t0000_u01755571362401")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11526312b79a0fe3_d20250819_m024240_c001_v0001100_t0008_u01755571360163")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f400e9330133ef86d_d20250819_m024212_c001_v7007000_t0000_u01755571332054")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115caf6b2c6d7c57_d20250819_m024210_c001_v0001041_t0054_u01755571330603")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115caf6b2c6d7c51_d20250819_m024208_c001_v0001041_t0052_u01755571328209")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f412e5d075b6e2d13_d20250819_m024159_c001_v7007000_t0000_u01755571319993")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10449060a123cbc9_d20250819_m024158_c001_v0001184_t0009_u01755571318757")
2025/08/19 02:43:09 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118caedac9902f19_d20250819_m024156_c001_v0001171_t0003_u01755571316503")
    bisync_test.go:596: TEST resync PASSED
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/08/19 02:43:09 INFO  : path1: Making directory
2025/08/19 02:43:09 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1"
2025/08/19 02:43:11 INFO  : path2: Making directory
2025/08/19 02:43:11 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2"
2025/08/19 02:43:12 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/08/19 02:43:12 DEBUG : Creating backend with remote "/tmp/024138je/initdir/test_resync_modes-hubituq2"
2025/08/19 02:43:12 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:43:12 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/19 02:43:12 INFO  : file1.txt: Copied (new)
2025/08/19 02:43:12 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:43:12 DEBUG : Local file system at /tmp/024138je/initdir/test_resync_modes-hubituq2: Waiting for checks to finish
2025/08/19 02:43:12 DEBUG : Local file system at /tmp/024138je/initdir/test_resync_modes-hubituq2: Waiting for transfers to finish
2025/08/19 02:43:12 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/19 02:43:12 INFO  : file2.txt: Copied (new)
2025/08/19 02:43:12 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/19 02:43:12 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/19 02:43:12 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:43:12 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/08/19 02:43:12 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:12 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:12 DEBUG : Local file system at /tmp/024138je/datadir/test_resync_modes-budoloc1: Waiting for checks to finish
2025/08/19 02:43:12 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/08/19 02:43:12 DEBUG : file1L.txt.7d07f91a.partial: renamed to: file1L.txt
2025/08/19 02:43:12 INFO  : file1L.txt: Copied (new)
2025/08/19 02:43:12 DEBUG : Local file system at /tmp/024138je/datadir/test_resync_modes-budoloc1: Waiting for transfers to finish
2025/08/19 02:43:12 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/08/19 02:43:12 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/08/19 02:43:12 DEBUG : file2.txt.7cc9b07.partial: renamed to: file2.txt
2025/08/19 02:43:12 INFO  : file2.txt: Copied (new)
2025/08/19 02:43:12 DEBUG : file1R.txt.cb75035e.partial: renamed to: file1R.txt
2025/08/19 02:43:12 INFO  : file1R.txt: Copied (new)
2025/08/19 02:43:12 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:43:12 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:43:13 NOTICE: checking initFs Local file system at /tmp/024138je/initdir/test_resync_modes-hubituq2
2025/08/19 02:43:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:43:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:14 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Waiting for checks to finish
2025/08/19 02:43:14 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Waiting for transfers to finish
2025/08/19 02:43:14 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:43:14 INFO  : file1.txt: Copied (new)
2025/08/19 02:43:15 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:43:15 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:43:15 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:43:15 INFO  : file2.txt: Copied (new)
2025/08/19 02:43:15 NOTICE: checking Path1 B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1
2025/08/19 02:43:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:43:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:16 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:43:16 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Waiting for checks to finish
2025/08/19 02:43:16 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Waiting for transfers to finish
2025/08/19 02:43:16 DEBUG : file1.txt: Clearing upload URL because of error: no tomes available (503 service_unavailable): trying again in 1s
2025/08/19 02:43:16 DEBUG : pacer: low level retry 1/1 (error no tomes available (503 service_unavailable): trying again in 1s)
2025/08/19 02:43:16 DEBUG : pacer: Rate limited, increasing sleep to 1s
2025/08/19 02:43:16 DEBUG : file1.txt: Received error: no tomes available (503 service_unavailable): trying again in 1s - low level retry 0/10
2025/08/19 02:43:16 DEBUG : pacer: Reducing sleep to 500ms
2025/08/19 02:43:16 DEBUG : pacer: Reducing sleep to 250ms
2025/08/19 02:43:16 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:43:16 INFO  : file2.txt: Copied (new)
2025/08/19 02:43:17 DEBUG : pacer: Reducing sleep to 125ms
2025/08/19 02:43:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:43:17 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:43:18 DEBUG : pacer: Reducing sleep to 62.5ms
2025/08/19 02:43:18 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:43:18 INFO  : file1.txt: Copied (new)
2025/08/19 02:43:18 NOTICE: checking path2 B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2
2025/08/19 02:43:18 DEBUG : pacer: Reducing sleep to 31.25ms
2025/08/19 02:43:18 NOTICE: (01)  : test resync-mode
2025/08/19 02:43:18 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:43:18 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:43:18 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/"
2025/08/19 02:43:18 DEBUG : fs cache: renaming cache item "/tmp/024138je/datadir/test_resync_modes-budoloc1/" to be canonical "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:18 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/08/19 02:43:18 NOTICE: (04)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:43:18 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:43:18 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:43:18 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:19 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:43:19 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/19 02:43:21 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:43:21 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:43:21 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:43:21 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:21 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/08/19 02:43:21 NOTICE: (06)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:43:21 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:43:21 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:43:21 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:43:22 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:43:23 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/19 02:43:24 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:43:24 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:43:24 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/08/19 02:43:24 NOTICE: (08)  : bisync resync resync-mode=newer
2025/08/19 02:43:29 DEBUG : pacer: Reducing sleep to 15.625ms
2025/08/19 02:43:31 DEBUG : pacer: Reducing sleep to 10ms
2025/08/19 02:43:33 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:33 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:33 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:43:33 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:43:33 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:33 INFO  : Copying Path2 files to Path1
2025/08/19 02:43:33 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:43:33 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:43:33 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:43:33 INFO  : Resync updating listings
2025/08/19 02:43:33 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:33 INFO  : Bisync successful
2025/08/19 02:43:33 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:43:33 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:43:33 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:33 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/08/19 02:43:33 NOTICE: (11)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:43:33 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:43:33 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:43:33 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:34 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:43:35 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/19 02:43:36 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:43:36 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:43:36 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:43:36 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:36 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/08/19 02:43:36 NOTICE: (13)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:43:36 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:43:36 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:43:36 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:43:38 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:43:38 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/08/19 02:43:38 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:43:39 INFO  : file1L.txt: Updated modification time in destination
2025/08/19 02:43:39 DEBUG : file1L.txt: Unchanged skipping
2025/08/19 02:43:39 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/08/19 02:43:39 NOTICE: (15)  : bisync resync resync-mode=path2
2025/08/19 02:43:48 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:48 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:43:48 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:43: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/08/19 02:43:48 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:48 INFO  : Copying Path2 files to Path1
2025/08/19 02:43:48 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:43:49 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:43:49 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:43:49 INFO  : Resync updating listings
2025/08/19 02:43:49 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:49 INFO  : Bisync successful
2025/08/19 02:43:49 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:43:49 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:43:49 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:49 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/08/19 02:43:49 NOTICE: (18)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:43:49 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:43:49 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:43:49 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:43:50 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:43:50 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/08/19 02:43:50 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:43:52 INFO  : file1R.txt: Updated modification time in destination
2025/08/19 02:43:52 DEBUG : file1R.txt: Unchanged skipping
2025/08/19 02:43:52 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:43:52 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:43:52 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/08/19 02:43:52 NOTICE: (20)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:43:52 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:43:52 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:43:52 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:43:53 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:43:53 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/19 02:43:55 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:43:55 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:43:55 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/08/19 02:43:55 NOTICE: (22)  : bisync resync resync-mode=larger
2025/08/19 02:44:04 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:04 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:44: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/08/19 02:44:04 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:04 INFO  : Copying Path2 files to Path1
2025/08/19 02:44:04 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:44:05 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/08/19 02:44:07 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:44:07 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:44:08 INFO  : Resync updating listings
2025/08/19 02:44:08 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:08 INFO  : Bisync successful
2025/08/19 02:44:08 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:44:08 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:44:08 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:08 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/08/19 02:44:08 NOTICE: (25)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:44:08 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:44:08 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:44:08 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:09 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:44:09 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/08/19 02:44:09 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:44:10 INFO  : file1R.txt: Updated modification time in destination
2025/08/19 02:44:10 DEBUG : file1R.txt: Unchanged skipping
2025/08/19 02:44:10 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:44:10 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:10 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/08/19 02:44:10 NOTICE: (27)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:44:10 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:44:10 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:44:10 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:44:11 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:44:12 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/19 02:44:13 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:44:13 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:44:13 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/08/19 02:44:13 NOTICE: (29)  : bisync resync resync-mode=older
2025/08/19 02:44:22 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:22 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:44:22 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:44:22 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:22 INFO  : Copying Path2 files to Path1
2025/08/19 02:44:22 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:44:22 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/08/19 02:44:22 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:44:22 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:44:23 INFO  : Resync updating listings
2025/08/19 02:44:23 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:23 INFO  : Bisync successful
2025/08/19 02:44:23 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:44:23 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:44:23 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:23 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/08/19 02:44:23 NOTICE: (32)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:44:23 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:44:23 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:44:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:24 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:44:24 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/08/19 02:44:24 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:44:25 INFO  : file1R.txt: Updated modification time in destination
2025/08/19 02:44:25 DEBUG : file1R.txt: Unchanged skipping
2025/08/19 02:44:25 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:44:25 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:25 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/08/19 02:44:25 NOTICE: (34)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:44:25 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:44:25 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:44:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:44:26 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:44:27 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/19 02:44:28 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:44:28 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:44:28 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/08/19 02:44:28 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/08/19 02:44:36 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:36 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:36 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:44: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/08/19 02:44:36 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:36 INFO  : Copying Path2 files to Path1
2025/08/19 02:44:36 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:44:37 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/19 02:44:37 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:44:37 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/08/19 02:44:37 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:44:37 INFO  : Resync updating listings
2025/08/19 02:44:37 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:37 INFO  : Bisync successful
2025/08/19 02:44:37 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/08/19 02:44:37 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:44:37 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:37 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/08/19 02:44:37 NOTICE: (39)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:44:37 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:44:37 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:44:37 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:38 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:44:38 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/19 02:44:40 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:44:40 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:44:40 NOTICE: (40)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file2.txt
2025/08/19 02:44:40 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file2.txt"
2025/08/19 02:44:40 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:44:40 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:44:41 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file2.txt"
2025/08/19 02:44:41 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/08/19 02:44:43 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:44:43 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/08/19 02:44:43 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:44:43 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:43 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/08/19 02:44:43 NOTICE: (42)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:44:43 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:44:43 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:44:43 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:44:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:44:44 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/08/19 02:44:44 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:44:45 INFO  : file1L.txt: Updated modification time in destination
2025/08/19 02:44:45 DEBUG : file1L.txt: Unchanged skipping
2025/08/19 02:44:45 NOTICE: (43)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file2.txt
2025/08/19 02:44:45 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file2.txt"
2025/08/19 02:44:45 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:44:45 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:46 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file2.txt"
2025/08/19 02:44:47 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/08/19 02:44:48 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:44:48 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/19 02:44:48 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/08/19 02:44:48 NOTICE: (45)  : bisync resync resync-mode=path1
2025/08/19 02:44:57 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:57 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:44:57 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:44:57 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:44:57 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:57 INFO  : Copying Path2 files to Path1
2025/08/19 02:44:57 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:44:57 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:44:58 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:44:58 INFO  : file2.txt: Copied (server-side copy)
2025/08/19 02:44:58 INFO  : Resync updating listings
2025/08/19 02:44:58 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:58 INFO  : Bisync successful
2025/08/19 02:44:58 NOTICE: (46)  : test no winner
2025/08/19 02:44:58 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1R.txt
2025/08/19 02:44:58 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:44:58 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/08/19 02:44:58 NOTICE: (48)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file1.txt
2025/08/19 02:44:58 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file1.txt"
2025/08/19 02:44:58 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:44:58 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:44:59 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file1.txt"
2025/08/19 02:45:00 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/08/19 02:45:01 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:45:01 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/08/19 02:45:01 NOTICE: (49)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file2.txt
2025/08/19 02:45:01 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file2.txt"
2025/08/19 02:45:01 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1R.txt"
2025/08/19 02:45:01 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:45:03 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file2.txt"
2025/08/19 02:45:03 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/08/19 02:45:03 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/08/19 02:45:04 INFO  : file1R.txt: Updated modification time in destination
2025/08/19 02:45:04 DEBUG : file1R.txt: Unchanged skipping
2025/08/19 02:45:04 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/024138je/datadir/test_resync_modes-budoloc1/ file1L.txt
2025/08/19 02:45:04 DEBUG : fs cache: switching user supplied name "/tmp/024138je/datadir/test_resync_modes-budoloc1/" for canonical name "/tmp/024138je/datadir/test_resync_modes-budoloc1"
2025/08/19 02:45:04 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/08/19 02:45:04 NOTICE: (51)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/ file1.txt
2025/08/19 02:45:04 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" as "file1.txt"
2025/08/19 02:45:04 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:45:04 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/"
2025/08/19 02:45:05 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1" as "file1.txt"
2025/08/19 02:45:05 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/08/19 02:45:05 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:45:07 INFO  : file1L.txt: Updated modification time in destination
2025/08/19 02:45:07 DEBUG : file1L.txt: Unchanged skipping
2025/08/19 02:45:07 NOTICE: (52)  : copy-as /tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/ file2.txt
2025/08/19 02:45:07 DEBUG : copyFile "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt" to "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/" as "file2.txt"
2025/08/19 02:45:07 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_resync_modes-budoloc1/file1L.txt"
2025/08/19 02:45:07 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:45:08 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2" as "file2.txt"
2025/08/19 02:45:08 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/08/19 02:45:10 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/08/19 02:45:10 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/08/19 02:45:10 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/08/19 02:45:10 NOTICE: (54)  : bisync resync resync-mode=newer
2025/08/19 02:45:18 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:45:18 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/08/19 02:45:18 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/08/19 02:45:18 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/08/19 02:45:18 INFO  : Synching Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" with Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:45:18 INFO  : Copying Path2 files to Path1
2025/08/19 02:45:18 INFO  : - Path2    Resync is copying files to         - Path1
2025/08/19 02:45:18 INFO  : file1.txt: Copied (server-side copy)
2025/08/19 02:45:19 INFO  : file2.txt: Copied (server-side copy)
2025/08/19 02:45:19 INFO  : - Path1    Resync is copying files to         - Path2
2025/08/19 02:45:19 INFO  : Resync updating listings
2025/08/19 02:45:19 INFO  : Validating listings for Path1 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path1/" vs Path2 "TestB2:rclone-test-vikeyiw3wiba/024138je/resync_modes/path2/"
2025/08/19 02:45:19 INFO  : Bisync successful
2025/08/19 02:45:19 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:19 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100a1763a7150d08_d20250819_m024315_c001_v0001171_t0042_u01755571395203")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f112f2707f65689b1_d20250819_m024518_c001_v0001146_t0009_u01755571518332")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1190ff593c4475dc_d20250819_m024506_c001_v0001040_t0036_u01755571506866")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1013a9b439aab311_d20250819_m024445_c001_v0001136_t0057_u01755571485622")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f107d9791710791e2_d20250819_m024428_c001_v0001184_t0054_u01755571468519")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f116258343ecae257_d20250819_m024422_c001_v0001157_t0021_u01755571462615")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1098d58d5bd9d23e_d20250819_m024413_c001_v0001120_t0024_u01755571453565")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f102385c6f84df56e_d20250819_m024407_c001_v0001145_t0040_u01755571447255")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f112a7fdb0f0a7f61_d20250819_m024354_c001_v0001144_t0025_u01755571434767")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1074d574c84a2d9a_d20250819_m024348_c001_v0001032_t0035_u01755571428785")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f118feb22a6000104_d20250819_m024339_c001_v0001100_t0014_u01755571419247")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1176723bded8aa0e_d20250819_m024324_c001_v0001153_t0005_u01755571404437")
2025/08/19 02:45:19 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113838b4549597de_d20250819_m024314_c001_v0001182_t0031_u01755571394819")
2025/08/19 02:45:20 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106b9e15fe4f55b7_d20250819_m024518_c001_v0001038_t0015_u01755571518711")
2025/08/19 02:45:20 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f112c855f405ba7d8_d20250819_m024504_c001_v0001136_t0028_u01755571504275")
2025/08/19 02:45:20 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10663ebb6a43dc3e_d20250819_m024443_c001_v0001177_t0011_u01755571483129")
2025/08/19 02:45:20 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f109d8e0fdeaf6626_d20250819_m024315_c001_v0001144_t0024_u01755571395009")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4145fb5ce61c788c_d20250819_m024513_c001_v7007000_t0000_u01755571513978")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f110e1133946ad155_d20250819_m024512_c001_v0001179_t0046_u01755571512741")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113838b454959a8e_d20250819_m024510_c001_v0001182_t0016_u01755571510615")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4021a1ce9c2dec5b_d20250819_m024453_c001_v7007000_t0000_u01755571493195")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1008d8bdac2d8fd7_d20250819_m024451_c001_v0001100_t0017_u01755571491856")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f109d8e0fdeaf671e_d20250819_m024449_c001_v0001144_t0052_u01755571489113")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4145fb5ce61c7840_d20250819_m024432_c001_v7007000_t0000_u01755571472769")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1093d4ed8c742ea3_d20250819_m024431_c001_v0001099_t0028_u01755571471327")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100a1763a7150e90_d20250819_m024429_c001_v0001171_t0051_u01755571469078")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4114cc1e369eaec9_d20250819_m024417_c001_v7007000_t0000_u01755571457942")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f106a273459a32e2e_d20250819_m024416_c001_v0001137_t0035_u01755571456525")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113838b454959947_d20250819_m024414_c001_v0001182_t0001_u01755571454317")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f41376fab2f530a3b_d20250819_m024400_c001_v7007000_t0000_u01755571440603")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f108e0ce91a71628e_d20250819_m024359_c001_v0001184_t0047_u01755571439403")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f109d8e0fdeaf669a_d20250819_m024356_c001_v0001144_t0037_u01755571436327")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f401545a95a44204d_d20250819_m024343_c001_v7007000_t0000_u01755571423981")
2025/08/19 02:45:20 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f114cb674b8316e78_d20250819_m024342_c001_v0001109_t0038_u01755571422243")
2025/08/19 02:45:21 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100a1763a7150d9a_d20250819_m024339_c001_v0001171_t0056_u01755571419904")
2025/08/19 02:45:21 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f40430b240baa2147_d20250819_m024328_c001_v7007000_t0000_u01755571408702")
2025/08/19 02:45:21 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10135604f94b0fb6_d20250819_m024327_c001_v0001098_t0024_u01755571407341")
2025/08/19 02:45:21 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f113838b454959822_d20250819_m024325_c001_v0001182_t0008_u01755571405208")
2025/08/19 02:45:21 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/resync_modes/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:21 DEBUG : RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ab0f57c04bf8c_d20250819_m024317_c001_v0001036_t0034_u01755571397028")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105f512a22a49f5c_d20250819_m024501_c001_v0001134_t0020_u01755571501488")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10026a92d90a5f48_d20250819_m024458_c001_v0001161_t0019_u01755571498182")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1094458ba1873466_d20250819_m024440_c001_v0001134_t0027_u01755571480177")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11600fee8f1bb533_d20250819_m024437_c001_v0001184_t0008_u01755571477346")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f117d9d344e6807c4_d20250819_m024425_c001_v0001041_t0011_u01755571465542")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f105889993fb98417_d20250819_m024410_c001_v0001104_t0045_u01755571450414")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10725662c8ff42f2_d20250819_m024351_c001_v0001036_t0037_u01755571431701")
2025/08/19 02:45:21 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f114521529fae9412_d20250819_m024336_c001_v0001091_t0043_u01755571416492")
2025/08/19 02:45:22 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f107555faa1a40c18_d20250819_m024333_c001_v0001037_t0059_u01755571413512")
2025/08/19 02:45:22 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10259e6332ef67da_d20250819_m024321_c001_v0001150_t0009_u01755571401422")
2025/08/19 02:45:22 DEBUG : file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1067eb98dbf281af_d20250819_m024318_c001_v0001154_t0020_u01755571398242")
2025/08/19 02:45:22 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1177a686d997fe7c_d20250819_m024509_c001_v0001105_t0052_u01755571509967")
2025/08/19 02:45:22 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1036e57a2dc310c1_d20250819_m024458_c001_v0001150_t0013_u01755571498547")
2025/08/19 02:45:22 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1056e5145d7b315b_d20250819_m024448_c001_v0001171_t0020_u01755571488497")
2025/08/19 02:45:22 DEBUG : file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115374cd96927828_d20250819_m024316_c001_v0001180_t0054_u01755571396846")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f41813bcb8bf8e8ac_d20250819_m024517_c001_v7007000_t0000_u01755571517952")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f112fd6c83acacddf_d20250819_m024516_c001_v0001180_t0004_u01755571516708")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115374cd96927a45_d20250819_m024514_c001_v0001180_t0058_u01755571514565")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f409e81a20501a686_d20250819_m024457_c001_v7007000_t0000_u01755571497574")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f116945b99da64ba0_d20250819_m024456_c001_v0001130_t0048_u01755571496173")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1067eb98dbf28245_d20250819_m024453_c001_v0001154_t0019_u01755571493778")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f416e654b225024dd_d20250819_m024436_c001_v7007000_t0000_u01755571476787")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f11521d38f9c6ca69_d20250819_m024435_c001_v0001178_t0000_u01755571475576")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ab0f57c04bff2_d20250819_m024433_c001_v0001036_t0054_u01755571473315")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f405e2f9d17ee1e6c_d20250819_m024422_c001_v7007000_t0000_u01755571462227")
2025/08/19 02:45:22 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f102ef31e07b58d0a_d20250819_m024420_c001_v0001106_t0051_u01755571460731")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115374cd9692794a_d20250819_m024418_c001_v0001180_t0040_u01755571458521")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4105d0699dbf4f51_d20250819_m024404_c001_v7007000_t0000_u01755571444732")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1104cd9eb32a0cd6_d20250819_m024403_c001_v0001172_t0001_u01755571443442")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1067eb98dbf28203_d20250819_m024401_c001_v0001154_t0021_u01755571441158")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f4176b152a80e3650_d20250819_m024348_c001_v7007000_t0000_u01755571428396")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f116ffe25ebdcc8f7_d20250819_m024347_c001_v0001165_t0033_u01755571427077")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ab0f57c04bfac_d20250819_m024344_c001_v0001036_t0054_u01755571424531")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f419259e82468d81f_d20250819_m024332_c001_v7007000_t0000_u01755571412944")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1196ed6f60ccec8e_d20250819_m024331_c001_v0001106_t0052_u01755571411508")
2025/08/19 02:45:23 DEBUG : modtime_write_test: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f115374cd96927856_d20250819_m024329_c001_v0001180_t0039_u01755571409247")
    bisync_test.go:596: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/08/19 02:45:23 INFO  : path1: Making directory
2025/08/19 02:45:23 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/rmdirs/path1"
2025/08/19 02:45:25 INFO  : path2: Making directory
2025/08/19 02:45:25 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/rmdirs/path2"
2025/08/19 02:45:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/08/19 02:45:26 DEBUG : Creating backend with remote "/tmp/024138je/initdir/test_rmdirs-qoweqil5"
2025/08/19 02:45:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.copy1.txt.5028a97.partial: renamed to: file1.copy1.txt
2025/08/19 02:45:26 INFO  : file1.copy1.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/19 02:45:26 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/19 02:45:26 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:26 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : subdir: Making directory with metadata
2025/08/19 02:45:26 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/08/19 02:45:26 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/08/19 02:45:26 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.copy2.txt.5028a97.partial: renamed to: file1.copy2.txt
2025/08/19 02:45:26 INFO  : file1.copy2.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : Local file system at /tmp/024138je/initdir/test_rmdirs-qoweqil5: Waiting for checks to finish
2025/08/19 02:45:26 DEBUG : Local file system at /tmp/024138je/initdir/test_rmdirs-qoweqil5: Waiting for transfers to finish
2025/08/19 02:45:26 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.copy5.txt.5028a97.partial: renamed to: file1.copy5.txt
2025/08/19 02:45:26 INFO  : file1.copy5.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.copy4.txt.5028a97.partial: renamed to: file1.copy4.txt
2025/08/19 02:45:26 INFO  : file1.copy4.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/19 02:45:26 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : file1.copy3.txt.5028a97.partial: renamed to: file1.copy3.txt
2025/08/19 02:45:26 INFO  : file1.copy3.txt: Copied (new)
2025/08/19 02:45:26 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:26 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/08/19 02:45:26 INFO  : subdir/file20.txt: Copied (new)
2025/08/19 02:45:26 INFO  : subdir: Set directory modification time (using SetModTime)
2025/08/19 02:45:26 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/08/19 02:45:26 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_rmdirs-muferah5"
2025/08/19 02:45:26 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:26 DEBUG : Local file system at /tmp/024138je/datadir/test_rmdirs-muferah5: Waiting for checks to finish
2025/08/19 02:45:26 DEBUG : Local file system at /tmp/024138je/datadir/test_rmdirs-muferah5: Waiting for transfers to finish
2025/08/19 02:45:26 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:26 DEBUG : file1.txt.13f42ab5.partial: renamed to: file1.txt
2025/08/19 02:45:26 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:26 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:26 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:28 NOTICE: checking initFs Local file system at /tmp/024138je/initdir/test_rmdirs-qoweqil5
2025/08/19 02:45:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 INFO  : subdir: Making directory
2025/08/19 02:45:28 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:28 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path1: Waiting for checks to finish
2025/08/19 02:45:28 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path1: Waiting for transfers to finish
2025/08/19 02:45:29 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.copy1.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.copy3.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.copy2.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.copy5.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : file1.copy4.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:29 INFO  : subdir/file20.txt: Copied (new)
2025/08/19 02:45:29 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:45:29 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:29 NOTICE: checking Path1 B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path1
2025/08/19 02:45:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 INFO  : subdir: Making directory
2025/08/19 02:45:30 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:30 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path2: Waiting for checks to finish
2025/08/19 02:45:30 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path2: Waiting for transfers to finish
2025/08/19 02:45:30 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:30 INFO  : file1.copy1.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : file1.copy4.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : file1.copy2.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : file1.copy5.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : subdir/file20.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : file1.copy3.txt: Copied (new)
2025/08/19 02:45:31 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:31 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:32 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:45:32 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:32 NOTICE: checking path2 B2 bucket rclone-test-vikeyiw3wiba path 024138je/rmdirs/path2
2025/08/19 02:45:32 NOTICE: (01)  : test rmdirs
2025/08/19 02:45:32 NOTICE: (02)  : test initial bisync
2025/08/19 02:45:32 NOTICE: (03)  : bisync resync
    bisync_test.go:996: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/08/19 02:45:32 INFO  : path1: Making directory
2025/08/19 02:45:32 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/volatile/path1"
2025/08/19 02:45:33 INFO  : path2: Making directory
2025/08/19 02:45:33 DEBUG : Creating backend with remote "TestB2:rclone-test-vikeyiw3wiba/024138je/volatile/path2"
2025/08/19 02:45:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/08/19 02:45:34 DEBUG : Creating backend with remote "/tmp/024138je/initdir/test_volatile-dululuv5"
2025/08/19 02:45:34 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : Local file system at /tmp/024138je/initdir/test_volatile-dululuv5: Waiting for checks to finish
2025/08/19 02:45:34 DEBUG : Local file system at /tmp/024138je/initdir/test_volatile-dululuv5: Waiting for transfers to finish
2025/08/19 02:45:34 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/08/19 02:45:34 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/08/19 02:45:34 INFO  : file2.txt: Copied (new)
2025/08/19 02:45:34 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/08/19 02:45:34 INFO  : file3.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/08/19 02:45:34 INFO  : file4.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt
2025/08/19 02:45:34 INFO  : file5.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt
2025/08/19 02:45:34 INFO  : file6.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt
2025/08/19 02:45:34 INFO  : file7.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/08/19 02:45:34 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/08/19 02:45:34 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:34 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : file8.txt.5028a97.partial: renamed to: file8.txt
2025/08/19 02:45:34 INFO  : file8.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/08/19 02:45:34 DEBUG : Creating backend with remote "/tmp/024138je/datadir/test_volatile-pidehik1"
2025/08/19 02:45:34 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:34 DEBUG : Local file system at /tmp/024138je/datadir/test_volatile-pidehik1: Waiting for checks to finish
2025/08/19 02:45:34 DEBUG : Local file system at /tmp/024138je/datadir/test_volatile-pidehik1: Waiting for transfers to finish
2025/08/19 02:45:34 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:34 DEBUG : file11.txt.ae546193.partial: renamed to: file11.txt
2025/08/19 02:45:34 INFO  : file11.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:34 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:34 DEBUG : file1.txt.ae546193.partial: renamed to: file1.txt
2025/08/19 02:45:34 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file10.txt.ae546193.partial: renamed to: file10.txt
2025/08/19 02:45:34 INFO  : file10.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/08/19 02:45:34 DEBUG : dummy.txt.75bafa33.partial: renamed to: dummy.txt
2025/08/19 02:45:34 INFO  : dummy.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/08/19 02:45:34 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/08/19 02:45:34 DEBUG : file5L.txt.c32cc336.partial: renamed to: file5L.txt
2025/08/19 02:45:34 INFO  : file5L.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file5R.txt.aa998d0a.partial: renamed to: file5R.txt
2025/08/19 02:45:34 INFO  : file5R.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/08/19 02:45:34 DEBUG : file2.txt.8872da9c.partial: renamed to: file2.txt
2025/08/19 02:45:34 INFO  : file2.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:34 DEBUG : file7.txt.6bedbe24.partial: renamed to: file7.txt
2025/08/19 02:45:34 INFO  : file7.txt: Copied (new)
2025/08/19 02:45:34 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/08/19 02:45:34 DEBUG : file6.txt.ae546193.partial: renamed to: file6.txt
2025/08/19 02:45:34 INFO  : file6.txt: Copied (new)
2025/08/19 02:45:34 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path1: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:35 INFO  : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path2: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:36 NOTICE: checking initFs Local file system at /tmp/024138je/initdir/test_volatile-dululuv5
2025/08/19 02:45:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:36 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path1: Waiting for checks to finish
2025/08/19 02:45:36 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path1: Waiting for transfers to finish
2025/08/19 02:45:37 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:37 INFO  : file2.txt: Copied (new)
2025/08/19 02:45:37 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:37 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:37 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:37 INFO  : file3.txt: Copied (new)
2025/08/19 02:45:38 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:38 INFO  : file4.txt: Copied (new)
2025/08/19 02:45:38 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:38 INFO  : file6.txt: Copied (new)
2025/08/19 02:45:38 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:45:38 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:38 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:38 INFO  : file5.txt: Copied (new)
2025/08/19 02:45:38 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:38 INFO  : file8.txt: Copied (new)
2025/08/19 02:45:38 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:38 INFO  : file7.txt: Copied (new)
2025/08/19 02:45:38 NOTICE: checking Path1 B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path1
2025/08/19 02:45:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/08/19 02:45:38 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path2: Waiting for checks to finish
2025/08/19 02:45:38 DEBUG : B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path2: Waiting for transfers to finish
2025/08/19 02:45:40 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file2.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file3.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file1.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/08/19 02:45:40 INFO  : RCLONE_TEST: Copied (new)
2025/08/19 02:45:40 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file5.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file4.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file8.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file7.txt: Copied (new)
2025/08/19 02:45:40 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/08/19 02:45:40 INFO  : file6.txt: Copied (new)
2025/08/19 02:45:40 NOTICE: checking path2 B2 bucket rclone-test-vikeyiw3wiba path 024138je/volatile/path2
2025/08/19 02:45:41 NOTICE: (01)  : test volatile
2025/08/19 02:45:41 NOTICE: (02)  : test initial bisync
2025/08/19 02:45:41 NOTICE: (03)  : bisync resync
    bisync_test.go:967: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/08/19 02:45:41 DEBUG : B2 bucket rclone-test-vikeyiw3wiba: Purge remote
2025/08/19 02:45:41 INFO  : B2 bucket rclone-test-vikeyiw3wiba: cleaning bucket "rclone-test-vikeyiw3wiba" of all files
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1167ead2add74d04_d20250819_m024529_c001_v0001154_t0009_u01755571529463")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.copy1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1063dbe4302ec4a5_d20250819_m024528_c001_v0001040_t0010_u01755571528873")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.copy2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10449060a123d2d0_d20250819_m024529_c001_v0001184_t0023_u01755571529277")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.copy3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100af76b9fd63895_d20250819_m024529_c001_v0001181_t0009_u01755571529102")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.copy4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1063dbe4302ec4a9_d20250819_m024529_c001_v0001040_t0044_u01755571529304")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.copy5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100af76b9fd6389b_d20250819_m024529_c001_v0001181_t0020_u01755571529371")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10449060a123d2d4_d20250819_m024529_c001_v0001184_t0051_u01755571529521")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path1/subdir/file20.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100af76b9fd638a1_d20250819_m024529_c001_v0001181_t0055_u01755571529579")
2025/08/19 02:45:41 DEBUG : 024138je/rmdirs/path2/RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100fb57354a22d95_d20250819_m024531_c001_v0001172_t0059_u01755571531280")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.copy1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1027a204c673efb7_d20250819_m024530_c001_v0001181_t0009_u01755571530708")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.copy2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f116ebbd11712adb8_d20250819_m024530_c001_v0001137_t0022_u01755571530921")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.copy3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f10982c68a61132d4_d20250819_m024531_c001_v0001106_t0026_u01755571531126")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.copy4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1027a204c673efb9_d20250819_m024530_c001_v0001181_t0001_u01755571530980")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.copy5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1027a204c673efbd_d20250819_m024531_c001_v0001181_t0038_u01755571531189")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f116ebbd11712adba_d20250819_m024531_c001_v0001137_t0009_u01755571531235")
2025/08/19 02:45:42 DEBUG : 024138je/rmdirs/path2/subdir/file20.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1027a204c673efc5_d20250819_m024531_c001_v0001181_t0036_u01755571531396")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f114e48f404d9bf45_d20250819_m024537_c001_v0001098_t0019_u01755571537811")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1000505bda74ecaf_d20250819_m024537_c001_v0001037_t0008_u01755571537240")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1176300143f88377_d20250819_m024537_c001_v0001038_t0049_u01755571537430")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ac50175a7438d_d20250819_m024537_c001_v0001182_t0043_u01755571537618")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1176300143f88379_d20250819_m024537_c001_v0001038_t0016_u01755571537759")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1000505bda74ecb1_d20250819_m024537_c001_v0001037_t0039_u01755571537768")
2025/08/19 02:45:42 DEBUG : 024138je/volatile/path1/file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ac50175a74391_d20250819_m024537_c001_v0001182_t0011_u01755571537892")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path1/file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1176300143f8837b_d20250819_m024538_c001_v0001038_t0046_u01755571538118")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path1/file8.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f104ac50175a74397_d20250819_m024538_c001_v0001182_t0046_u01755571538139")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/RCLONE_TEST: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100a1763a715102d_d20250819_m024540_c001_v0001171_t0035_u01755571540034")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file1.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1103b4ab3f1c9a74_d20250819_m024539_c001_v0001117_t0027_u01755571539439")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file2.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f111eb50c11843e39_d20250819_m024539_c001_v0001146_t0001_u01755571539626")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file3.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1178a75dc69ae3af_d20250819_m024539_c001_v0001165_t0035_u01755571539845")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file4.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f111eb50c11843e3d_d20250819_m024540_c001_v0001146_t0056_u01755571540140")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file5.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1178a75dc69ae3b3_d20250819_m024540_c001_v0001165_t0013_u01755571540156")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file6.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1103b4ab3f1c9a7c_d20250819_m024540_c001_v0001117_t0059_u01755571540290")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file7.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f100a1763a715102f_d20250819_m024540_c001_v0001171_t0013_u01755571540410")
2025/08/19 02:45:43 DEBUG : 024138je/volatile/path2/file8.txt: Deleting (id "4_zcf422d63304dcb0b9f880e1b_f1178a75dc69ae3b5_d20250819_m024540_c001_v0001165_t0059_u01755571540427")
--- PASS: TestBisyncRemoteRemote (249.73s)
    --- PASS: TestBisyncRemoteRemote/resync (91.55s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (133.97s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.34s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.83s)
PASS
2025/08/19 02:45:47 DEBUG : B2 bucket rclone-test-roroxic5cewe: Purge remote
2025/08/19 02:45:47 INFO  : B2 bucket rclone-test-roroxic5cewe: cleaning bucket "rclone-test-roroxic5cewe" of all files
2025/08/19 02:45:48 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 4m11.079245554s (try 2/5)