"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(filters|resync)$|^TestBisyncRemoteRemote$/^(check_filename|check_sync)$'" - Starting (try 2/5)
=== RUN   TestBisyncLocalRemote
2025/05/30 02:31:38 NOTICE: remote: TestB2:rclone-test-qafunez0howe
=== RUN   TestBisyncLocalRemote/filters
2025/05/30 02:31:42 ERROR : error listing: directory not found
2025/05/30 02:31:42 NOTICE: checking initFs Local file system at /tmp/023138we/initdir/test_filters-sasajah1
2025/05/30 02:31:42 NOTICE: checking Path1 Local file system at /tmp/023138we/filters/path1
2025/05/30 02:31:44 NOTICE: checking path2 B2 bucket rclone-test-qafunez0howe path 023138we/filters/path2
2025/05/30 02:31:44 NOTICE: (01)  : test filters
2025/05/30 02:31:44 NOTICE: (02)  : copy-file /tmp/023138we/datadir/test_filters-qerovop2/filtersfile.flt /tmp/023138we/workdir/
2025/05/30 02:31:44 NOTICE: (03)  : test resync to force building of the filters md5 hash
2025/05/30 02:31:44 NOTICE: (04)  : bisync filters-file=/tmp/023138we/workdir/filtersfile.flt resync
2025/05/30 02:31:45 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:31:45 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:31:45 INFO  : Synching Path1 "/tmp/023138we/filters/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/filters/path2/"
2025/05/30 02:31:45 INFO  : Using filters file /tmp/023138we/workdir/filtersfile.flt
2025/05/30 02:31:45 INFO  : Storing filters file hash to /tmp/023138we/workdir/filtersfile.flt.md5
2025/05/30 02:31:45 INFO  : Copying Path2 files to Path1
2025/05/30 02:31:45 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:31:46 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:31:46 INFO  : Resync updating listings
2025/05/30 02:31:46 INFO  : Validating listings for Path1 "/tmp/023138we/filters/path1/" vs Path2 "TestB2:rclone-test-qafunez0howe/023138we/filters/path2/"
2025/05/30 02:31:46 INFO  : Bisync successful
2025/05/30 02:31:46 NOTICE: (05)  : copy-listings resync
2025/05/30 02:31:46 NOTICE: (06)  : test place new files on the remote
2025/05/30 02:31:46 NOTICE: (07)  : touch-glob 2001-01-02 /tmp/023138we/datadir/test_filters-qerovop2/ fileZ.txt
2025/05/30 02:31:46 NOTICE: (08)  : copy-as /tmp/023138we/datadir/test_filters-qerovop2/fileZ.txt TestB2:rclone-test-qafunez0howe/023138we/filters/path2/ fileZ.txt
2025/05/30 02:31:49 NOTICE: (09)  : copy-as /tmp/023138we/datadir/test_filters-qerovop2/fileZ.txt /tmp/023138we/filters/path1/subdir fileZ.txt
2025/05/30 02:31:49 NOTICE: (10)  : test bisync with filters-file. path2-side fileZ.txt will be filtered.
2025/05/30 02:31:49 NOTICE: (11)  : bisync filters-file=/tmp/023138we/workdir/filtersfile.flt
    bisync_test.go:977: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:977
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:988
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1052
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:747
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:494
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358
        	Error:      	Received unexpected error:
        	            	Post "https://pod-000-1182-15.backblaze.com/b2api/v1/b2_upload_file/1fb2ed43007d1bfb9f780e1b/c001_v0001182_t0032": EOF
        	Test:       	TestBisyncLocalRemote/filters
=== RUN   TestBisyncLocalRemote/resync
2025/05/30 02:31:51 ERROR : error listing: directory not found
2025/05/30 02:31:51 NOTICE: checking initFs Local file system at /tmp/023138we/initdir/test_resync-qizovan5
2025/05/30 02:31:51 NOTICE: checking Path1 Local file system at /tmp/023138we/resync/path1
2025/05/30 02:31:53 NOTICE: checking path2 B2 bucket rclone-test-qafunez0howe path 023138we/resync/path2
2025/05/30 02:31:53 NOTICE: (01)  : test resync
2025/05/30 02:31:53 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/05/30 02:31:53 NOTICE: (03)  : purge-children /tmp/023138we/resync/path1/
2025/05/30 02:31:53 NOTICE: (04)  : bisync resync
2025/05/30 02:31:54 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:31:54 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:31:54 INFO  : Synching Path1 "/tmp/023138we/resync/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:31:54 INFO  : Copying Path2 files to Path1
2025/05/30 02:31:54 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:31:54 INFO  : RCLONE_TEST: Copied (new)
2025/05/30 02:31:55 INFO  : file1.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file2.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file4.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file3.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file5.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file6.txt: Copied (new)
2025/05/30 02:31:55 INFO  : file7.txt: Copied (new)
2025/05/30 02:31:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:31:55 INFO  : Resync updating listings
2025/05/30 02:31:55 INFO  : Validating listings for Path1 "/tmp/023138we/resync/path1/" vs Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:31:55 INFO  : Bisync successful
2025/05/30 02:31:55 NOTICE: (05)  : move-listings empty-path1
2025/05/30 02:31:55 NOTICE: (06)  : test 2. resync with empty path2, resulting in syncing all content to path2.
2025/05/30 02:31:55 NOTICE: (07)  : purge-children TestB2:rclone-test-qafunez0howe/023138we/resync/path2/
2025/05/30 02:31:57 NOTICE: (08)  : bisync resync
2025/05/30 02:31:58 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:31:58 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:31:58 INFO  : Synching Path1 "/tmp/023138we/resync/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:31:58 INFO  : Copying Path2 files to Path1
2025/05/30 02:31:58 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:31:58 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:31:59 INFO  : file3.txt: Copied (new)
2025/05/30 02:31:59 INFO  : RCLONE_TEST: Copied (new)
2025/05/30 02:31:59 INFO  : file1.txt: Copied (new)
2025/05/30 02:31:59 INFO  : file5.txt: Copied (new)
2025/05/30 02:31:59 INFO  : file4.txt: Copied (new)
2025/05/30 02:31:59 INFO  : file2.txt: Copied (new)
2025/05/30 02:31:59 INFO  : file7.txt: Copied (new)
2025/05/30 02:31:59 INFO  : file6.txt: Copied (new)
2025/05/30 02:31:59 INFO  : Resync updating listings
2025/05/30 02:31:59 INFO  : Validating listings for Path1 "/tmp/023138we/resync/path1/" vs Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:31:59 INFO  : Bisync successful
2025/05/30 02:31:59 NOTICE: (09)  : move-listings empty-path2
2025/05/30 02:31:59 NOTICE: (10)  : test 3. exercise all of the various file difference scenarios during a resync.
2025/05/30 02:31:59 NOTICE: (11)  : touch-glob 2002-02-02 /tmp/023138we/datadir/test_resync-rigoguh8/ fileA.txt
2025/05/30 02:31:59 NOTICE: (12)  : touch-glob 1999-09-09 /tmp/023138we/datadir/test_resync-rigoguh8/ fileB.txt
2025/05/30 02:31:59 NOTICE: (13)  : test = file      - path1      - path2      - expected action    - who wins
2025/05/30 02:31:59 NOTICE: (14)  : test - file1.txt - exists     - missing    - sync path1 > path2 - path1
2025/05/30 02:31:59 NOTICE: (15)  : delete-file TestB2:rclone-test-qafunez0howe/023138we/resync/path2/file1.txt
2025/05/30 02:32:01 NOTICE: (16)  : test - file2.txt - missing    - exists     - copy path2 > path1 - path2
2025/05/30 02:32:01 NOTICE: (17)  : delete-file /tmp/023138we/resync/path1/file2.txt
2025/05/30 02:32:01 NOTICE: (18)  : test - file3.txt - exists     - newer date - sync path1 > path2 - path1
2025/05/30 02:32:01 NOTICE: (19)  : copy-as /tmp/023138we/datadir/test_resync-rigoguh8/fileA.txt TestB2:rclone-test-qafunez0howe/023138we/resync/path2/ file3.txt
2025/05/30 02:32:04 NOTICE: (20)  : test - file4.txt - missing    - newer date - copy path2 > path1 - path2
2025/05/30 02:32:04 NOTICE: (21)  : delete-file /tmp/023138we/resync/path1/file4.txt
2025/05/30 02:32:04 NOTICE: (22)  : copy-as /tmp/023138we/datadir/test_resync-rigoguh8/fileA.txt TestB2:rclone-test-qafunez0howe/023138we/resync/path2/ file4.txt
2025/05/30 02:32:07 NOTICE: (23)  : test - file5.txt - exists     - older date - sync path1 > path2 - path1
2025/05/30 02:32:07 NOTICE: (24)  : copy-as /tmp/023138we/datadir/test_resync-rigoguh8/fileB.txt TestB2:rclone-test-qafunez0howe/023138we/resync/path2/ file5.txt
2025/05/30 02:32:09 NOTICE: (25)  : test - file6.txt - older date - newer date - sync path1 > path2	- path1
2025/05/30 02:32:09 NOTICE: (26)  : copy-as /tmp/023138we/datadir/test_resync-rigoguh8/fileB.txt /tmp/023138we/resync/path1/ file6.txt
2025/05/30 02:32:09 NOTICE: (27)  : copy-as /tmp/023138we/datadir/test_resync-rigoguh8/fileA.txt TestB2:rclone-test-qafunez0howe/023138we/resync/path2/ file6.txt
2025/05/30 02:32:12 NOTICE: (28)  : test - file7.txt - exists     - exists (same) - none            - same
2025/05/30 02:32:12 NOTICE: (29)  : test run bisync with resync
2025/05/30 02:32:12 NOTICE: (30)  : bisync resync
2025/05/30 02:32:14 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:14 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:32:14 INFO  : Synching Path1 "/tmp/023138we/resync/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:32:14 INFO  : Copying Path2 files to Path1
2025/05/30 02:32:14 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:32:14 INFO  : file2.txt: Copied (new)
2025/05/30 02:32:15 INFO  : file4.txt: Copied (new)
2025/05/30 02:32:15 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:32:15 INFO  : file6.txt: Updated modification time in destination
2025/05/30 02:32:15 INFO  : file3.txt: Copied (replaced existing)
2025/05/30 02:32:15 INFO  : file1.txt: Copied (new)
2025/05/30 02:32:16 INFO  : file5.txt: Copied (replaced existing)
2025/05/30 02:32:16 INFO  : Resync updating listings
2025/05/30 02:32:16 INFO  : Validating listings for Path1 "/tmp/023138we/resync/path1/" vs Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:32:16 INFO  : Bisync successful
2025/05/30 02:32:16 NOTICE: (31)  : copy-listings mixed-diffs
2025/05/30 02:32:16 NOTICE: (32)  : test run normal bisync
2025/05/30 02:32:16 NOTICE: (33)  : bisync
2025/05/30 02:32:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:32:17 INFO  : Synching Path1 "/tmp/023138we/resync/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:32:17 INFO  : Building Path1 and Path2 listings
2025/05/30 02:32:17 INFO  : Path1 checking for diffs
2025/05/30 02:32:17 INFO  : Path2 checking for diffs
2025/05/30 02:32:17 INFO  : No changes found
2025/05/30 02:32:17 INFO  : Updating listings
2025/05/30 02:32:17 INFO  : Validating listings for Path1 "/tmp/023138we/resync/path1/" vs Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:32:17 INFO  : Bisync successful
2025/05/30 02:32:17 NOTICE: (34)  : test 4. confirm critical error on normal sync of empty path.
2025/05/30 02:32:17 NOTICE: (35)  : purge-children TestB2:rclone-test-qafunez0howe/023138we/resync/path2/
2025/05/30 02:32:18 NOTICE: (36)  : bisync
2025/05/30 02:32:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:19 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/05/30 02:32:19 INFO  : Synching Path1 "/tmp/023138we/resync/path1/" with Path2 "TestB2:rclone-test-qafunez0howe/023138we/resync/path2/"
2025/05/30 02:32:19 INFO  : Building Path1 and Path2 listings
2025/05/30 02:32:20 INFO  : Path1 checking for diffs
2025/05/30 02:32:20 INFO  : Path2 checking for diffs
2025/05/30 02:32:20 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/023138we/workdir/tmp_023138we_resync_path1..TestB2_rclone-test-qafunez0howe_023138we_resync_path2.path2.lst-new
2025/05/30 02:32:20 ERROR : Bisync critical error: empty current Path2 listing: /tmp/023138we/workdir/tmp_023138we_resync_path1..TestB2_rclone-test-qafunez0howe_023138we_resync_path2.path2.lst-new
2025/05/30 02:32:20 ERROR : Bisync aborted. Must run --resync to recover.
2025/05/30 02:32:20 NOTICE: Bisync error: bisync aborted
    bisync_test.go:560: TEST resync PASSED
--- FAIL: TestBisyncLocalRemote (47.71s)
    --- FAIL: TestBisyncLocalRemote/filters (10.70s)
    --- PASS: TestBisyncLocalRemote/resync (34.18s)
=== RUN   TestBisyncRemoteRemote
2025/05/30 02:32:26 NOTICE: remote: TestB2:rclone-test-deniriw1waci
=== RUN   TestBisyncRemoteRemote/check_filename
2025/05/30 02:32:32 NOTICE: checking initFs Local file system at /tmp/023226ma/initdir/test_check_filename-mabinic3
2025/05/30 02:32:34 NOTICE: checking Path1 B2 bucket rclone-test-deniriw1waci path 023226ma/check_filename/path1
2025/05/30 02:32:36 NOTICE: checking path2 B2 bucket rclone-test-deniriw1waci path 023226ma/check_filename/path2
2025/05/30 02:32:36 NOTICE: (01)  : test check-filename
2025/05/30 02:32:36 NOTICE: (02)  : test initial bisync
2025/05/30 02:32:36 NOTICE: (03)  : bisync resync
2025/05/30 02:32:39 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:39 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:32:39 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:39 INFO  : Copying Path2 files to Path1
2025/05/30 02:32:39 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:32:39 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:32:40 INFO  : Resync updating listings
2025/05/30 02:32:40 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:40 INFO  : Bisync successful
2025/05/30 02:32:40 NOTICE: (04)  : test 1. see that check-access passes with the initial setup
2025/05/30 02:32:40 NOTICE: (05)  : bisync check-access check-filename=.chk_file
2025/05/30 02:32:43 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32: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/05/30 02:32:43 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:43 INFO  : Building Path1 and Path2 listings
2025/05/30 02:32:43 INFO  : Path1 checking for diffs
2025/05/30 02:32:43 INFO  : Path2 checking for diffs
2025/05/30 02:32:43 INFO  : Checking access health
2025/05/30 02:32:43 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/30 02:32:43 INFO  : No changes found
2025/05/30 02:32:43 INFO  : Updating listings
2025/05/30 02:32:43 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:43 INFO  : Bisync successful
2025/05/30 02:32:43 NOTICE: (06)  : copy-listings initial-pass
2025/05/30 02:32:43 NOTICE: (07)  : test 2. delete the remote subdir .chk_file, run sync. should fail critical.
2025/05/30 02:32:43 NOTICE: (08)  : delete-file TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/subdir/.chk_file
2025/05/30 02:32:45 NOTICE: (09)  : bisync check-access check-filename=.chk_file
2025/05/30 02:32:50 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:50 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:32:50 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:50 INFO  : Building Path1 and Path2 listings
2025/05/30 02:32:50 INFO  : Path1 checking for diffs
2025/05/30 02:32:50 INFO  : Path2 checking for diffs
2025/05/30 02:32:50 INFO  : - Path2    File was deleted          - subdir/.chk_file
2025/05/30 02:32:50 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/05/30 02:32:50 INFO  : Checking access health
2025/05/30 02:32:50 ERROR : Access test failed: Path1 count 2, Path2 count 1 - .chk_file
2025/05/30 02:32:50 ERROR : -          Access test failed: Path1 file not found in Path2 - subdir/.chk_file
2025/05/30 02:32:50 ERROR : Bisync critical error: check file check failed
2025/05/30 02:32:50 ERROR : Bisync aborted. Must run --resync to recover.
2025/05/30 02:32:50 NOTICE: Bisync error: bisync aborted
2025/05/30 02:32:50 NOTICE: (10)  : move-listings path2-missing
2025/05/30 02:32:50 NOTICE: (11)  : test 3. put the remote subdir .chk_file back, run resync.
2025/05/30 02:32:50 NOTICE: (12)  : copy-file TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/subdir/.chk_file TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/subdir/
2025/05/30 02:32:54 NOTICE: (13)  : bisync check-access resync check-filename=.chk_file
2025/05/30 02:32:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:32:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:32:59 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:32:59 INFO  : Copying Path2 files to Path1
2025/05/30 02:32:59 INFO  : Checking access health
2025/05/30 02:32:59 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/30 02:32:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:32:59 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:33:00 INFO  : Resync updating listings
2025/05/30 02:33:00 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:33:00 INFO  : Bisync successful
2025/05/30 02:33:00 NOTICE: (14)  : test 4. run sync with check-access. should pass.
2025/05/30 02:33:00 NOTICE: (15)  : bisync check-access check-filename=.chk_file
2025/05/30 02:33:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33: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/05/30 02:33:03 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:33:03 INFO  : Building Path1 and Path2 listings
2025/05/30 02:33:03 INFO  : Path1 checking for diffs
2025/05/30 02:33:03 INFO  : Path2 checking for diffs
2025/05/30 02:33:03 INFO  : Checking access health
2025/05/30 02:33:03 INFO  : Found 2 matching ".chk_file" files on both paths
2025/05/30 02:33:03 INFO  : No changes found
2025/05/30 02:33:03 INFO  : Updating listings
2025/05/30 02:33:03 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_filename/path2/"
2025/05/30 02:33:03 INFO  : Bisync successful
    bisync_test.go:560: TEST check_filename PASSED
=== RUN   TestBisyncRemoteRemote/check_sync
2025/05/30 02:33:10 NOTICE: checking initFs Local file system at /tmp/023226ma/initdir/test_check_sync-pewolab5
2025/05/30 02:33:13 NOTICE: checking Path1 B2 bucket rclone-test-deniriw1waci path 023226ma/check_sync/path1
2025/05/30 02:33:17 NOTICE: checking path2 B2 bucket rclone-test-deniriw1waci path 023226ma/check_sync/path2
2025/05/30 02:33:17 NOTICE: (01)  : test check-sync
2025/05/30 02:33:17 NOTICE: (02)  : test initial bisync
2025/05/30 02:33:17 NOTICE: (03)  : bisync resync
2025/05/30 02:33:22 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33: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/05/30 02:33:22 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:22 INFO  : Copying Path2 files to Path1
2025/05/30 02:33:22 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:33:22 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:33:22 INFO  : Resync updating listings
2025/05/30 02:33:22 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:22 INFO  : Bisync successful
2025/05/30 02:33:22 NOTICE: (04)  : test 1. run check-sync-only on a clean sync
2025/05/30 02:33:22 NOTICE: (05)  : bisync check-sync-only
2025/05/30 02:33:25 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:25 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:25 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:25 INFO  : Bisync successful
2025/05/30 02:33:25 NOTICE: (06)  : test 2. inject modified listings into the workdir
2025/05/30 02:33:25 NOTICE: (07)  : copy-as /tmp/023226ma/datadir/test_check_sync-pehinud1/_testdir_path1.._testdir_path2.path1.lst /tmp/023226ma/workdir/ TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path1..TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path2.path1.lst
2025/05/30 02:33:25 NOTICE: (08)  : copy-as /tmp/023226ma/datadir/test_check_sync-pehinud1/_testdir_path1.._testdir_path2.path2.lst /tmp/023226ma/workdir/ TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path1..TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path2.path2.lst
2025/05/30 02:33:25 NOTICE: (09)  : test 3. run check-sync-only on modified listings
2025/05/30 02:33:25 NOTICE: (10)  : bisync check-sync-only
2025/05/30 02:33:27 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:27 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:27 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:27 ERROR : -          Path1 file not found in Path2      - file2.txt
2025/05/30 02:33:27 ERROR : -          Path2 file not found in Path1      - file1.txt
2025/05/30 02:33:27 ERROR : Bisync critical error: path1 and path2 are out of sync, run --resync to recover
2025/05/30 02:33:27 ERROR : Bisync aborted. Must run --resync to recover.
2025/05/30 02:33:27 NOTICE: Bisync error: bisync aborted
2025/05/30 02:33:27 NOTICE: (11)  : copy-listings check-sync-only
2025/05/30 02:33:27 NOTICE: (12)  : test 4. run normal sync to check that it aborts
2025/05/30 02:33:27 NOTICE: (13)  : bisync
2025/05/30 02:33:31 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:31 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:31 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:31 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/023226ma/workdir/TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path1..TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path2.path1.lst
Path2: /tmp/023226ma/workdir/TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path1..TestB2_rclone-test-deniriw1waci_023226ma_check_sync_path2.path2.lst
Try running this command to inspect the work dir: 
rclone lsl "/tmp/023226ma/workdir"
2025/05/30 02:33:31 ERROR : Bisync aborted. Must run --resync to recover.
2025/05/30 02:33:31 NOTICE: Bisync error: bisync aborted
2025/05/30 02:33:31 NOTICE: (14)  : test 5. prune failure listings after critical abort
2025/05/30 02:33:31 NOTICE: (15)  : delete-glob /tmp/023226ma/workdir/ *.lst
2025/05/30 02:33:31 NOTICE: (16)  : delete-glob /tmp/023226ma/workdir/ *.lst-err
2025/05/30 02:33:31 NOTICE: (17)  : delete-glob /tmp/023226ma/workdir/ *.lst-new
2025/05/30 02:33:31 NOTICE: (18)  : test 6. run resync
2025/05/30 02:33:31 NOTICE: (19)  : bisync resync
2025/05/30 02:33:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:35 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:35 INFO  : Copying Path2 files to Path1
2025/05/30 02:33:35 INFO  : - Path2    Resync is copying files to         - Path1
2025/05/30 02:33:35 INFO  : - Path1    Resync is copying files to         - Path2
2025/05/30 02:33:35 INFO  : Resync updating listings
2025/05/30 02:33:35 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:35 INFO  : Bisync successful
2025/05/30 02:33:35 NOTICE: (20)  : test 7. run normal sync with check-sync enabled (default)
2025/05/30 02:33:35 NOTICE: (21)  : bisync
2025/05/30 02:33:38 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:38 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:38 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:38 INFO  : Building Path1 and Path2 listings
2025/05/30 02:33:38 INFO  : Path1 checking for diffs
2025/05/30 02:33:38 INFO  : Path2 checking for diffs
2025/05/30 02:33:38 INFO  : No changes found
2025/05/30 02:33:38 INFO  : Updating listings
2025/05/30 02:33:38 INFO  : Validating listings for Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" vs Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:38 INFO  : Bisync successful
2025/05/30 02:33:38 NOTICE: (22)  : test 8. run normal sync with no-check-sync
2025/05/30 02:33:38 NOTICE: (23)  : bisync no-check-sync
2025/05/30 02:33:40 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/05/30 02:33:40 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/05/30 02:33:40 INFO  : Synching Path1 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path1/" with Path2 "TestB2:rclone-test-deniriw1waci/023226ma/check_sync/path2/"
2025/05/30 02:33:40 INFO  : Building Path1 and Path2 listings
2025/05/30 02:33:41 INFO  : Path1 checking for diffs
2025/05/30 02:33:41 INFO  : Path2 checking for diffs
2025/05/30 02:33:41 INFO  : No changes found
2025/05/30 02:33:41 INFO  : Updating listings
2025/05/30 02:33:41 INFO  : Bisync successful
    bisync_test.go:560: TEST check_sync PASSED
--- PASS: TestBisyncRemoteRemote (80.52s)
    --- PASS: TestBisyncRemoteRemote/check_filename (40.46s)
    --- PASS: TestBisyncRemoteRemote/check_sync (38.35s)
FAIL
2025/05/30 02:33:47 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(filters|resync)$|^TestBisyncRemoteRemote$/^(check_filename|check_sync)$'" - Finished ERROR in 2m9.65385753s (try 2/5): exit status 1: Failed [TestBisyncLocalRemote/filters]