"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(check_sync|resync)$'" - Starting (try 2/5)
2024/04/11 05:17:43 DEBUG : Creating backend with remote "TestB2:rclone-test-bojoxex5ruwayov1xuveraf3"
2024/04/11 05:17:43 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/11 05:17:44 DEBUG : Creating backend with remote "/tmp/rclone1795791058"
=== RUN   TestBisyncLocalRemote
2024/04/11 05:17:44 DEBUG : Creating backend with remote "TestB2:rclone-test-cehacul9hipiler6bizexap4"
2024/04/11 05:17:44 remote: TestB2:rclone-test-cehacul9hipiler6bizexap4
=== RUN   TestBisyncLocalRemote/check_sync
2024/04/11 05:17:44 DEBUG : Creating backend with remote "/tmp/bs-051744-ka"
2024/04/11 05:17:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/04/11 05:17:44 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/check_sync/path1"
2024/04/11 05:17:44 DEBUG : Creating backend with remote "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka"
2024/04/11 05:17:45 DEBUG : path2: Making directory
2024/04/11 05:17:46 DEBUG : Creating backend with remote "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2"
2024/04/11 05:17:46 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_sync/initial"
2024/04/11 05:17:46 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/initdir/test_check_sync-zelarus6"
2024/04/11 05:17:46 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file1.txt.ruzegeg8.partial: renamed to: file1.txt
2024/04/11 05:17:46 INFO  : file1.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file2.txt.miwonoj8.partial: renamed to: file2.txt
2024/04/11 05:17:46 INFO  : file2.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : file4.txt.qokakux9.partial: renamed to: file4.txt
2024/04/11 05:17:46 INFO  : file4.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file3.txt.xemuwik3.partial: renamed to: file3.txt
2024/04/11 05:17:46 INFO  : file3.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file6.txt.gunanav1.partial: renamed to: file6.txt
2024/04/11 05:17:46 INFO  : file6.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : Local file system at /tmp/bs-051744-ka/initdir/test_check_sync-zelarus6: Waiting for checks to finish
2024/04/11 05:17:46 DEBUG : Local file system at /tmp/bs-051744-ka/initdir/test_check_sync-zelarus6: Waiting for transfers to finish
2024/04/11 05:17:46 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file5.txt.fobunub5.partial: renamed to: file5.txt
2024/04/11 05:17:46 INFO  : file5.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:46 DEBUG : file8.txt.saforiv6.partial: renamed to: file8.txt
2024/04/11 05:17:46 INFO  : file8.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/11 05:17:46 DEBUG : RCLONE_TEST.zutokub9.partial: renamed to: RCLONE_TEST
2024/04/11 05:17:46 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:17:46 DEBUG : file7.txt.yefunad0.partial: renamed to: file7.txt
2024/04/11 05:17:46 INFO  : file7.txt: Copied (new)
2024/04/11 05:17:46 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_sync/modfiles"
2024/04/11 05:17:46 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/datadir/test_check_sync-xebogad0"
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path1.lst: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path2.lst: Need to transfer - File not found at Destination
2024/04/11 05:17:46 DEBUG : Local file system at /tmp/bs-051744-ka/datadir/test_check_sync-xebogad0: Waiting for checks to finish
2024/04/11 05:17:46 DEBUG : Local file system at /tmp/bs-051744-ka/datadir/test_check_sync-xebogad0: Waiting for transfers to finish
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path2.lst: md5 = d6ce44a26af9c2110b1750fe88dc4b69 OK
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path2.lst.heyemoh5.partial: renamed to: _testdir_path1.._testdir_path2.path2.lst
2024/04/11 05:17:46 INFO  : _testdir_path1.._testdir_path2.path2.lst: Copied (new)
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path1.lst: md5 = fdb18e0dff6961813e12c57227d2e854 OK
2024/04/11 05:17:46 DEBUG : _testdir_path1.._testdir_path2.path1.lst.setepus7.partial: renamed to: _testdir_path1.._testdir_path2.path1.lst
2024/04/11 05:17:46 INFO  : _testdir_path1.._testdir_path2.path1.lst: Copied (new)
2024/04/11 05:17:47 ERROR : : error listing: directory not found
2024/04/11 05:17:47 checking initFs Local file system at /tmp/bs-051744-ka/initdir/test_check_sync-zelarus6
2024/04/11 05:17:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : Local file system at /tmp/bs-051744-ka/check_sync/path1: Waiting for checks to finish
2024/04/11 05:17:47 DEBUG : Local file system at /tmp/bs-051744-ka/check_sync/path1: Waiting for transfers to finish
2024/04/11 05:17:47 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/11 05:17:47 DEBUG : RCLONE_TEST.docayah4.partial: renamed to: RCLONE_TEST
2024/04/11 05:17:47 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:17:47 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file2.txt.xosihoj9.partial: renamed to: file2.txt
2024/04/11 05:17:47 INFO  : file2.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file4.txt.xatijaz7.partial: renamed to: file4.txt
2024/04/11 05:17:47 INFO  : file4.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file5.txt.rikejil8.partial: renamed to: file5.txt
2024/04/11 05:17:47 INFO  : file5.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file6.txt.penopud4.partial: renamed to: file6.txt
2024/04/11 05:17:47 INFO  : file6.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file1.txt.mucukod0.partial: renamed to: file1.txt
2024/04/11 05:17:47 INFO  : file1.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file7.txt.wekevod5.partial: renamed to: file7.txt
2024/04/11 05:17:47 INFO  : file7.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:17:47 DEBUG : file3.txt.wisosub9.partial: renamed to: file3.txt
2024/04/11 05:17:47 INFO  : file3.txt: Copied (new)
2024/04/11 05:17:47 DEBUG : file8.txt.dimebol5.partial: renamed to: file8.txt
2024/04/11 05:17:47 INFO  : file8.txt: Copied (new)
2024/04/11 05:17:47 checking Path1 Local file system at /tmp/bs-051744-ka/check_sync/path1
2024/04/11 05:17:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : file8.txt: Need to transfer - File not found at Destination
2024/04/11 05:17:47 DEBUG : B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/check_sync/path2: Waiting for checks to finish
2024/04/11 05:17:47 DEBUG : B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/check_sync/path2: Waiting for transfers to finish
2024/04/11 05:17:48 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:48 INFO  : file1.txt: Copied (new)
2024/04/11 05:17:48 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:48 INFO  : file4.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file2.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file6.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file5.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2024/04/11 05:17:49 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:17:49 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file7.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file8.txt: Copied (new)
2024/04/11 05:17:49 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:17:49 INFO  : file3.txt: Copied (new)
2024/04/11 05:17:49 checking path2 B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/check_sync/path2
2024/04/11 05:17:50 (01)  : test check-sync
2024/04/11 05:17:50 (02)  : test initial bisync
2024/04/11 05:17:50 (03)  : bisync resync
2024/04/11 05:17:51 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:17:51 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:17:51 INFO  : Synching Path1 "/tmp/bs-051744-ka/check_sync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:17:51 INFO  : Copying Path2 files to Path1
2024/04/11 05:17:51 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/11 05:17:51 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/11 05:17:51 INFO  : Resync updating listings
2024/04/11 05:17:51 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/check_sync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:17:51 INFO  : Bisync successful
2024/04/11 05:17:51 (04)  : test 1. run check-sync-only on a clean sync
2024/04/11 05:17:51 (05)  : bisync check-sync-only
2024/04/11 05:17:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:17:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:17:54 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/check_sync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:17:54 INFO  : Bisync successful
2024/04/11 05:17:54 (06)  : test 2. inject modified listings into the workdir
2024/04/11 05:17:54 (07)  : copy-as /tmp/bs-051744-ka/datadir/test_check_sync-xebogad0/_testdir_path1.._testdir_path2.path1.lst /tmp/bs-051744-ka/workdir/ tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path1.lst
2024/04/11 05:17:54 INFO  : _testdir_path1.._testdir_path2.path1.lst: Copied (replaced existing) to: tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path1.lst
2024/04/11 05:17:54 (08)  : copy-as /tmp/bs-051744-ka/datadir/test_check_sync-xebogad0/_testdir_path1.._testdir_path2.path2.lst /tmp/bs-051744-ka/workdir/ tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path2.lst
2024/04/11 05:17:54 INFO  : _testdir_path1.._testdir_path2.path2.lst: Copied (replaced existing) to: tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path2.lst
2024/04/11 05:17:54 (09)  : test 3. run check-sync-only on modified listings
2024/04/11 05:17:54 (10)  : bisync check-sync-only
2024/04/11 05:17:56 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:17:56 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:17:56 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/check_sync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:17:56 ERROR : -          Path1 file not found in Path2      - file2.txt
2024/04/11 05:17:56 ERROR : -          Path2 file not found in Path1      - file1.txt
2024/04/11 05:17:56 ERROR : Bisync critical error: path1 and path2 are out of sync, run --resync to recover
2024/04/11 05:17:56 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/11 05:17:56 Bisync error: bisync aborted
2024/04/11 05:17:56 (11)  : copy-listings check-sync-only
2024/04/11 05:17:56 (12)  : test 4. run normal sync to check that it aborts
2024/04/11 05:17:56 (13)  : bisync
2024/04/11 05:18:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:00 INFO  : Synching Path1 "/tmp/bs-051744-ka/check_sync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:00 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run 
Tip: here are the filenames we were looking for. Do they exist? 
Path1: /tmp/bs-051744-ka/workdir/tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path1.lst
Path2: /tmp/bs-051744-ka/workdir/tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/bs-051744-ka/workdir"
2024/04/11 05:18:00 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/11 05:18:00 Bisync error: bisync aborted
2024/04/11 05:18:00 (14)  : test 5. prune failure listings after critical abort
2024/04/11 05:18:00 (15)  : delete-glob /tmp/bs-051744-ka/workdir/ *.lst
2024/04/11 05:18:00 (16)  : delete-glob /tmp/bs-051744-ka/workdir/ *.lst-err
2024/04/11 05:18:00 INFO  : tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path1.lst-err: Deleted
2024/04/11 05:18:00 INFO  : tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path2.lst-err: Deleted
2024/04/11 05:18:00 (17)  : delete-glob /tmp/bs-051744-ka/workdir/ *.lst-new
2024/04/11 05:18:00 INFO  : tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path1.lst-new: Deleted
2024/04/11 05:18:00 INFO  : tmp_bs-051744-ka_check_sync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_check_sync_path2.path2.lst-new: Deleted
2024/04/11 05:18:00 (18)  : test 6. run resync
2024/04/11 05:18:00 (19)  : bisync resync
2024/04/11 05:18:02 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:02 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:02 INFO  : Synching Path1 "/tmp/bs-051744-ka/check_sync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:02 INFO  : Copying Path2 files to Path1
2024/04/11 05:18:02 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/11 05:18:02 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/11 05:18:03 INFO  : Resync updating listings
2024/04/11 05:18:03 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/check_sync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:03 INFO  : Bisync successful
2024/04/11 05:18:03 (20)  : test 7. run normal sync with check-sync enabled (default)
2024/04/11 05:18:03 (21)  : bisync
2024/04/11 05:18:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:04 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:04 INFO  : Synching Path1 "/tmp/bs-051744-ka/check_sync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:04 INFO  : Building Path1 and Path2 listings
2024/04/11 05:18:05 INFO  : Path1 checking for diffs
2024/04/11 05:18:05 INFO  : Path2 checking for diffs
2024/04/11 05:18:05 INFO  : No changes found
2024/04/11 05:18:05 INFO  : Updating listings
2024/04/11 05:18:05 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/check_sync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:05 INFO  : Bisync successful
2024/04/11 05:18:05 (22)  : test 8. run normal sync with no-check-sync
2024/04/11 05:18:05 (23)  : bisync no-check-sync
2024/04/11 05:18:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:06 INFO  : Synching Path1 "/tmp/bs-051744-ka/check_sync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/check_sync/path2/"
2024/04/11 05:18:06 INFO  : Building Path1 and Path2 listings
2024/04/11 05:18:06 INFO  : Path1 checking for diffs
2024/04/11 05:18:06 INFO  : Path2 checking for diffs
2024/04/11 05:18:06 INFO  : No changes found
2024/04/11 05:18:06 INFO  : Updating listings
2024/04/11 05:18:06 INFO  : Bisync successful
    bisync_test.go:539: TEST check_sync PASSED
=== RUN   TestBisyncLocalRemote/resync
2024/04/11 05:18:09 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/resync/path1"
2024/04/11 05:18:09 DEBUG : path2: Making directory
2024/04/11 05:18:09 DEBUG : Creating backend with remote "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2"
2024/04/11 05:18:10 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2024/04/11 05:18:10 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/initdir/test_resync-loyizah6"
2024/04/11 05:18:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/initdir/test_resync-loyizah6: Waiting for checks to finish
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/initdir/test_resync-loyizah6: Waiting for transfers to finish
2024/04/11 05:18:10 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file1.txt.wisuduj4.partial: renamed to: file1.txt
2024/04/11 05:18:10 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file2.txt.vakoruv7.partial: renamed to: file2.txt
2024/04/11 05:18:10 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/11 05:18:10 DEBUG : file5.txt.ronihob8.partial: renamed to: file5.txt
2024/04/11 05:18:10 INFO  : file5.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : RCLONE_TEST.luzosuz3.partial: renamed to: RCLONE_TEST
2024/04/11 05:18:10 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:18:10 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file6.txt.wevamuk8.partial: renamed to: file6.txt
2024/04/11 05:18:10 INFO  : file6.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file7.txt.cegewow0.partial: renamed to: file7.txt
2024/04/11 05:18:10 INFO  : file7.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file3.txt.nevofin6.partial: renamed to: file3.txt
2024/04/11 05:18:10 INFO  : file3.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file4.txt.hegevux5.partial: renamed to: file4.txt
2024/04/11 05:18:10 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2024/04/11 05:18:10 DEBUG : Creating backend with remote "/tmp/bs-051744-ka/datadir/test_resync-mevinuf4"
2024/04/11 05:18:10 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/datadir/test_resync-mevinuf4: Waiting for checks to finish
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/datadir/test_resync-mevinuf4: Waiting for transfers to finish
2024/04/11 05:18:10 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/11 05:18:10 DEBUG : fileA.txt.careyij4.partial: renamed to: fileA.txt
2024/04/11 05:18:10 INFO  : fileA.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2024/04/11 05:18:10 DEBUG : fileB.txt.wakeyiw5.partial: renamed to: fileB.txt
2024/04/11 05:18:10 INFO  : fileB.txt: Copied (new)
2024/04/11 05:18:10 ERROR : : error listing: directory not found
2024/04/11 05:18:10 checking initFs Local file system at /tmp/bs-051744-ka/initdir/test_resync-loyizah6
2024/04/11 05:18:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2024/04/11 05:18:10 DEBUG : RCLONE_TEST.hehugar7.partial: renamed to: RCLONE_TEST
2024/04/11 05:18:10 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/resync/path1: Waiting for checks to finish
2024/04/11 05:18:10 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file1.txt.difiwus5.partial: renamed to: file1.txt
2024/04/11 05:18:10 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : Local file system at /tmp/bs-051744-ka/resync/path1: Waiting for transfers to finish
2024/04/11 05:18:10 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file2.txt.xocoxep4.partial: renamed to: file2.txt
2024/04/11 05:18:10 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file3.txt.viteziz5.partial: renamed to: file3.txt
2024/04/11 05:18:10 INFO  : file3.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file4.txt.qocudem1.partial: renamed to: file4.txt
2024/04/11 05:18:10 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file5.txt.jalemic8.partial: renamed to: file5.txt
2024/04/11 05:18:10 INFO  : file5.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file6.txt.qejecuq7.partial: renamed to: file6.txt
2024/04/11 05:18:10 INFO  : file6.txt: Copied (new)
2024/04/11 05:18:10 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2024/04/11 05:18:10 DEBUG : file7.txt.higoroc2.partial: renamed to: file7.txt
2024/04/11 05:18:10 INFO  : file7.txt: Copied (new)
2024/04/11 05:18:10 checking Path1 Local file system at /tmp/bs-051744-ka/resync/path1
2024/04/11 05:18:10 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file1.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file2.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file3.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file4.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file5.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file6.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : file7.txt: Need to transfer - File not found at Destination
2024/04/11 05:18:10 DEBUG : B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/resync/path2: Waiting for checks to finish
2024/04/11 05:18:10 DEBUG : B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/resync/path2: Waiting for transfers to finish
2024/04/11 05:18:11 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2024/04/11 05:18:11 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:18:12 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file3.txt: Copied (new)
2024/04/11 05:18:12 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:12 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:12 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file6.txt: Copied (new)
2024/04/11 05:18:12 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file5.txt: Copied (new)
2024/04/11 05:18:12 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:12 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:13 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2024/04/11 05:18:13 INFO  : file7.txt: Copied (new)
2024/04/11 05:18:13 checking path2 B2 bucket rclone-test-cehacul9hipiler6bizexap4 path bs-051744-ka/resync/path2
2024/04/11 05:18:13 (01)  : test resync
2024/04/11 05:18:13 (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2024/04/11 05:18:13 (03)  : purge-children /tmp/bs-051744-ka/resync/path1/
2024/04/11 05:18:13 (04)  : bisync resync
2024/04/11 05:18:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:14 INFO  : Synching Path1 "/tmp/bs-051744-ka/resync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:14 INFO  : Copying Path2 files to Path1
2024/04/11 05:18:14 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/11 05:18:15 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:18:15 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:15 INFO  : file3.txt: Copied (new)
2024/04/11 05:18:15 INFO  : file6.txt: Copied (new)
2024/04/11 05:18:15 INFO  : file5.txt: Copied (new)
2024/04/11 05:18:15 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:15 INFO  : file7.txt: Copied (new)
2024/04/11 05:18:20 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:20 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/11 05:18:21 INFO  : Resync updating listings
2024/04/11 05:18:21 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/resync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:21 INFO  : Bisync successful
2024/04/11 05:18:21 (05)  : move-listings empty-path1
2024/04/11 05:18:21 (06)  : test 2. resync with empty path2, resulting in synching all content to path2.
2024/04/11 05:18:21 (07)  : purge-children TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/
2024/04/11 05:18:25 (08)  : bisync resync
2024/04/11 05:18:27 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:27 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:27 INFO  : Synching Path1 "/tmp/bs-051744-ka/resync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:27 INFO  : Copying Path2 files to Path1
2024/04/11 05:18:27 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/11 05:18:27 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/11 05:18:28 INFO  : file3.txt: Copied (new)
2024/04/11 05:18:28 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:28 INFO  : file5.txt: Copied (new)
2024/04/11 05:18:28 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:28 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:29 INFO  : RCLONE_TEST: Copied (new)
2024/04/11 05:18:29 INFO  : file7.txt: Copied (new)
2024/04/11 05:18:29 INFO  : file6.txt: Copied (new)
2024/04/11 05:18:29 INFO  : Resync updating listings
2024/04/11 05:18:29 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/resync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:29 INFO  : Bisync successful
2024/04/11 05:18:29 (09)  : move-listings empty-path2
2024/04/11 05:18:29 (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2024/04/11 05:18:29 (11)  : touch-glob 2002-02-02 /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/ fileA.txt
2024/04/11 05:18:29 (12)  : touch-glob 1999-09-09 /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/ fileB.txt
2024/04/11 05:18:29 (13)  : test = file      - path1      - path2      - expected action    - who wins
2024/04/11 05:18:29 (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2024/04/11 05:18:29 (15)  : delete-file TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/file1.txt
2024/04/11 05:18:30 INFO  : file1.txt: Deleted
2024/04/11 05:18:30 (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2024/04/11 05:18:30 (17)  : delete-file /tmp/bs-051744-ka/resync/path1/file2.txt
2024/04/11 05:18:30 INFO  : file2.txt: Deleted
2024/04/11 05:18:30 (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2024/04/11 05:18:30 (19)  : copy-as /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/fileA.txt TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/ file3.txt
2024/04/11 05:18:32 INFO  : fileA.txt: Copied (replaced existing) to: file3.txt
2024/04/11 05:18:32 (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2024/04/11 05:18:32 (21)  : delete-file /tmp/bs-051744-ka/resync/path1/file4.txt
2024/04/11 05:18:32 INFO  : file4.txt: Deleted
2024/04/11 05:18:32 (22)  : copy-as /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/fileA.txt TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/ file4.txt
2024/04/11 05:18:35 INFO  : fileA.txt: Copied (replaced existing) to: file4.txt
2024/04/11 05:18:35 (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2024/04/11 05:18:35 (24)  : copy-as /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/fileB.txt TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/ file5.txt
2024/04/11 05:18:37 INFO  : fileB.txt: Copied (replaced existing) to: file5.txt
2024/04/11 05:18:37 (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2024/04/11 05:18:37 (26)  : copy-as /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/fileB.txt /tmp/bs-051744-ka/resync/path1/ file6.txt
2024/04/11 05:18:37 INFO  : fileB.txt: Copied (replaced existing) to: file6.txt
2024/04/11 05:18:37 (27)  : copy-as /tmp/bs-051744-ka/datadir/test_resync-mevinuf4/fileA.txt TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/ file6.txt
2024/04/11 05:18:41 INFO  : fileA.txt: Copied (replaced existing) to: file6.txt
2024/04/11 05:18:41 (28)  : test - file7.txt - exists     - exists (same) - none            - same
2024/04/11 05:18:41 (29)  : test run bisync with resync
2024/04/11 05:18:41 (30)  : bisync resync
2024/04/11 05:18:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:43 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:43 INFO  : Synching Path1 "/tmp/bs-051744-ka/resync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:43 INFO  : Copying Path2 files to Path1
2024/04/11 05:18:43 INFO  : - Path2    Resync is copying files to         - Path1
2024/04/11 05:18:44 INFO  : file2.txt: Copied (new)
2024/04/11 05:18:44 INFO  : file4.txt: Copied (new)
2024/04/11 05:18:44 INFO  : - Path1    Resync is copying files to         - Path2
2024/04/11 05:18:45 INFO  : file3.txt: Copied (replaced existing)
2024/04/11 05:18:45 INFO  : file5.txt: Copied (replaced existing)
2024/04/11 05:18:46 INFO  : file1.txt: Copied (new)
2024/04/11 05:18:46 INFO  : file6.txt: Updated modification time in destination
2024/04/11 05:18:46 INFO  : Resync updating listings
2024/04/11 05:18:46 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/resync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:46 INFO  : Bisync successful
2024/04/11 05:18:46 (31)  : copy-listings mixed-diffs
2024/04/11 05:18:46 (32)  : test run normal bisync
2024/04/11 05:18:46 (33)  : bisync
2024/04/11 05:18:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:49 INFO  : Synching Path1 "/tmp/bs-051744-ka/resync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:49 INFO  : Building Path1 and Path2 listings
2024/04/11 05:18:50 INFO  : Path1 checking for diffs
2024/04/11 05:18:50 INFO  : Path2 checking for diffs
2024/04/11 05:18:50 INFO  : No changes found
2024/04/11 05:18:50 INFO  : Updating listings
2024/04/11 05:18:50 INFO  : Validating listings for Path1 "/tmp/bs-051744-ka/resync/path1/" vs Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:50 INFO  : Bisync successful
2024/04/11 05:18:50 (34)  : test 4. confirm critical error on normal sync of empty path.
2024/04/11 05:18:50 (35)  : purge-children TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/
2024/04/11 05:18:52 (36)  : bisync
2024/04/11 05:18:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2024/04/11 05:18:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2024/04/11 05:18:54 INFO  : Synching Path1 "/tmp/bs-051744-ka/resync/path1/" with Path2 "TestB2:rclone-test-cehacul9hipiler6bizexap4/bs-051744-ka/resync/path2/"
2024/04/11 05:18:54 INFO  : Building Path1 and Path2 listings
2024/04/11 05:18:55 INFO  : Path1 checking for diffs
2024/04/11 05:18:55 INFO  : Path2 checking for diffs
2024/04/11 05:18:55 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/bs-051744-ka/workdir/tmp_bs-051744-ka_resync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_resync_path2.path2.lst-new
2024/04/11 05:18:55 ERROR : Bisync critical error: empty current Path2 listing: /tmp/bs-051744-ka/workdir/tmp_bs-051744-ka_resync_path1..TestB2_rclone-test-cehacul9hipiler6bizexap4_bs-051744-ka_resync_path2.path2.lst-new
2024/04/11 05:18:55 ERROR : Bisync aborted. Must run --resync to recover.
2024/04/11 05:18:55 Bisync error: bisync aborted
    bisync_test.go:539: TEST resync PASSED
2024/04/11 05:18:59 DEBUG : B2 bucket rclone-test-cehacul9hipiler6bizexap4: Purge remote
2024/04/11 05:18:59 INFO  : B2 bucket rclone-test-cehacul9hipiler6bizexap4: cleaning bucket "rclone-test-cehacul9hipiler6bizexap4" of all files
--- PASS: TestBisyncLocalRemote (76.45s)
    --- PASS: TestBisyncLocalRemote/check_sync (24.99s)
    --- PASS: TestBisyncLocalRemote/resync (50.32s)
PASS
2024/04/11 05:19:00 DEBUG : B2 bucket rclone-test-bojoxex5ruwayov1xuveraf3: Purge remote
2024/04/11 05:19:00 INFO  : B2 bucket rclone-test-bojoxex5ruwayov1xuveraf3: cleaning bucket "rclone-test-bojoxex5ruwayov1xuveraf3" of all files
2024/04/11 05:19:01 purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(check_sync|resync)$'" - Finished OK in 1m17.527727216s (try 2/5)