"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^check_access$'" - Starting (try 3/5)
2025/03/18 06:31:45 DEBUG : Creating backend with remote "TestB2:rclone-test-gejezal4gepo"
2025/03/18 06:31:45 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/18 06:31:46 DEBUG : Creating backend with remote "/tmp/rclone4275918651"
=== RUN   TestBisyncRemoteRemote
2025/03/18 06:31:46 DEBUG : Creating backend with remote "TestB2:rclone-test-hafasen2nexo"
2025/03/18 06:31:47 NOTICE: remote: TestB2:rclone-test-hafasen2nexo
=== RUN   TestBisyncRemoteRemote/check_access
2025/03/18 06:31:47 DEBUG : Creating backend with remote "TestB2:rclone-test-hafasen2nexo/063147co"
2025/03/18 06:31:47 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/03/18 06:31:48 INFO  : path1: Making directory
2025/03/18 06:31:49 DEBUG : Creating backend with remote "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1"
2025/03/18 06:31:50 INFO  : path2: Making directory
2025/03/18 06:31:50 DEBUG : Creating backend with remote "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2"
2025/03/18 06:31:51 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/initial"
2025/03/18 06:31:51 DEBUG : Creating backend with remote "/tmp/063147co/initdir/test_check_access-xidafoq8"
2025/03/18 06:31:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : subdir: Making directory with metadata
2025/03/18 06:31:51 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/03/18 06:31:51 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/03/18 06:31:51 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : Local file system at /tmp/063147co/initdir/test_check_access-xidafoq8: Waiting for checks to finish
2025/03/18 06:31:51 DEBUG : Local file system at /tmp/063147co/initdir/test_check_access-xidafoq8: Waiting for transfers to finish
2025/03/18 06:31:51 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/18 06:31:51 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/18 06:31:51 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt
2025/03/18 06:31:51 INFO  : file2.txt: Copied (new)
2025/03/18 06:31:51 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt
2025/03/18 06:31:51 INFO  : file1.txt: Copied (new)
2025/03/18 06:31:51 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/18 06:31:51 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt
2025/03/18 06:31:51 INFO  : file3.txt: Copied (new)
2025/03/18 06:31:51 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/18 06:31:51 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/18 06:31:51 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST
2025/03/18 06:31:51 INFO  : RCLONE_TEST: Copied (new)
2025/03/18 06:31:51 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt
2025/03/18 06:31:51 INFO  : file4.txt: Copied (new)
2025/03/18 06:31:51 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/03/18 06:31:51 DEBUG : subdir/file20.txt.5028a97.partial: renamed to: subdir/file20.txt
2025/03/18 06:31:51 INFO  : subdir/file20.txt: Copied (new)
2025/03/18 06:31:51 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/03/18 06:31:51 DEBUG : subdir/RCLONE_TEST.cf9a93ac.partial: renamed to: subdir/RCLONE_TEST
2025/03/18 06:31:51 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/03/18 06:31:51 INFO  : subdir: Set directory modification time (using SetModTime)
2025/03/18 06:31:51 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/modfiles"
2025/03/18 06:31:51 DEBUG : Creating backend with remote "/tmp/063147co/datadir/test_check_access-yuyupax4"
2025/03/18 06:31:51 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:51 DEBUG : Local file system at /tmp/063147co/datadir/test_check_access-yuyupax4: Waiting for checks to finish
2025/03/18 06:31:51 DEBUG : Local file system at /tmp/063147co/datadir/test_check_access-yuyupax4: Waiting for transfers to finish
2025/03/18 06:31:51 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/03/18 06:31:51 DEBUG : hold.txt.627e309f.partial: renamed to: hold.txt
2025/03/18 06:31:51 INFO  : hold.txt: Copied (new)
2025/03/18 06:31:53 NOTICE: checking initFs Local file system at /tmp/063147co/initdir/test_check_access-xidafoq8
2025/03/18 06:31:53 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:53 INFO  : subdir: Making directory
2025/03/18 06:31:53 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:53 DEBUG : B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path1: Waiting for checks to finish
2025/03/18 06:31:53 DEBUG : B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path1: Waiting for transfers to finish
2025/03/18 06:31:54 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:54 INFO  : file1.txt: Copied (new)
2025/03/18 06:31:54 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:54 INFO  : file3.txt: Copied (new)
2025/03/18 06:31:54 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:54 INFO  : file4.txt: Copied (new)
2025/03/18 06:31:54 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/03/18 06:31:54 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/03/18 06:31:54 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:54 INFO  : file2.txt: Copied (new)
2025/03/18 06:31:54 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/03/18 06:31:54 INFO  : RCLONE_TEST: Copied (new)
2025/03/18 06:31:55 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:55 INFO  : subdir/file20.txt: Copied (new)
2025/03/18 06:31:55 NOTICE: checking Path1 B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path1
2025/03/18 06:31:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:55 INFO  : subdir: Making directory
2025/03/18 06:31:55 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/03/18 06:31:55 DEBUG : B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path2: Waiting for checks to finish
2025/03/18 06:31:55 DEBUG : B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path2: Waiting for transfers to finish
2025/03/18 06:31:56 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:56 INFO  : file2.txt: Copied (new)
2025/03/18 06:31:56 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:56 INFO  : file4.txt: Copied (new)
2025/03/18 06:31:56 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:56 INFO  : file1.txt: Copied (new)
2025/03/18 06:31:56 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/03/18 06:31:56 INFO  : subdir/RCLONE_TEST: Copied (new)
2025/03/18 06:31:56 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:56 INFO  : subdir/file20.txt: Copied (new)
2025/03/18 06:31:57 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/03/18 06:31:57 INFO  : file3.txt: Copied (new)
2025/03/18 06:31:57 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/03/18 06:31:57 INFO  : RCLONE_TEST: Copied (new)
2025/03/18 06:31:57 NOTICE: checking path2 B2 bucket rclone-test-hafasen2nexo path 063147co/check_access/path2
2025/03/18 06:31:57 NOTICE: (01)  : test check-access
2025/03/18 06:31:57 NOTICE: (02)  : test initial bisync
2025/03/18 06:31:57 NOTICE: (03)  : bisync resync
2025/03/18 06:32:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/03/18 06:32: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/03/18 06:32:00 INFO  : Synching Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" with Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:00 INFO  : Copying Path2 files to Path1
2025/03/18 06:32:00 INFO  : - Path2    Resync is copying files to         - Path1
2025/03/18 06:32:00 INFO  : - Path1    Resync is copying files to         - Path2
2025/03/18 06:32:00 INFO  : Resync updating listings
2025/03/18 06:32:00 INFO  : Validating listings for Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" vs Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:00 INFO  : Bisync successful
2025/03/18 06:32:00 NOTICE: (04)  : test 1. see that check-access passes with the initial setup
2025/03/18 06:32:00 NOTICE: (05)  : bisync check-access
2025/03/18 06:32:03 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/03/18 06:32: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/03/18 06:32:03 INFO  : Synching Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" with Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:03 INFO  : Building Path1 and Path2 listings
2025/03/18 06:32:04 INFO  : Path1 checking for diffs
2025/03/18 06:32:04 INFO  : Path2 checking for diffs
2025/03/18 06:32:04 INFO  : Checking access health
2025/03/18 06:32:04 INFO  : Found 2 matching "RCLONE_TEST" files on both paths
2025/03/18 06:32:04 INFO  : No changes found
2025/03/18 06:32:04 INFO  : Updating listings
2025/03/18 06:32:04 INFO  : Validating listings for Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" vs Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:04 INFO  : Bisync successful
2025/03/18 06:32:04 NOTICE: (06)  : test 2. delete the path2 subdir RCLONE_TEST and run sync. should fail critical.
2025/03/18 06:32:04 NOTICE: (07)  : delete-file TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/subdir/RCLONE_TEST
2025/03/18 06:32:06 INFO  : RCLONE_TEST: Deleted
2025/03/18 06:32:06 NOTICE: (08)  : bisync check-access
2025/03/18 06:32:09 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/03/18 06:32:09 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/03/18 06:32:09 INFO  : Synching Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" with Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:09 INFO  : Building Path1 and Path2 listings
2025/03/18 06:32:10 INFO  : Path1 checking for diffs
2025/03/18 06:32:10 INFO  : Path2 checking for diffs
2025/03/18 06:32:10 INFO  : - Path2    File was deleted          - subdir/RCLONE_TEST
2025/03/18 06:32:10 INFO  : Path2:    1 changes:    0 new,    0 modified,    1 deleted
2025/03/18 06:32:10 INFO  : Checking access health
2025/03/18 06:32:10 ERROR : Access test failed: Path1 count 2, Path2 count 1 - RCLONE_TEST
2025/03/18 06:32:10 ERROR : -          Access test failed: Path1 file not found in Path2 - subdir/RCLONE_TEST
2025/03/18 06:32:10 ERROR : Bisync critical error: check file check failed
2025/03/18 06:32:10 ERROR : Bisync aborted. Must run --resync to recover.
2025/03/18 06:32:10 NOTICE: Bisync error: bisync aborted
2025/03/18 06:32:10 NOTICE: (09)  : copy-listings path2-missing
2025/03/18 06:32:10 NOTICE: (10)  : test 3. put the path2 subdir RCLONE_TEST back, resync.
2025/03/18 06:32:10 NOTICE: (11)  : copy-file TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/subdir/RCLONE_TEST TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/
2025/03/18 06:32:12 NOTICE: (12)  : bisync resync
2025/03/18 06:32:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/03/18 06:32:17 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/03/18 06:32:17 INFO  : Synching Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" with Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:17 INFO  : Copying Path2 files to Path1
2025/03/18 06:32:17 INFO  : - Path2    Resync is copying files to         - Path1
2025/03/18 06:32:17 INFO  : - Path1    Resync is copying files to         - Path2
2025/03/18 06:32:18 INFO  : subdir/RCLONE_TEST: Copied (server-side copy)
2025/03/18 06:32:18 INFO  : Resync updating listings
2025/03/18 06:32:18 INFO  : Validating listings for Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" vs Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:18 INFO  : Bisync successful
2025/03/18 06:32:18 NOTICE: (13)  : test 4. run sync with check-access. should pass.
2025/03/18 06:32:18 NOTICE: (14)  : bisync check-access
2025/03/18 06:32:20 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/03/18 06:32:20 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/03/18 06:32:20 INFO  : Synching Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" with Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:20 INFO  : Building Path1 and Path2 listings
2025/03/18 06:32:21 INFO  : Path1 checking for diffs
2025/03/18 06:32:21 INFO  : Path2 checking for diffs
2025/03/18 06:32:21 INFO  : Checking access health
2025/03/18 06:32:21 INFO  : Found 2 matching "RCLONE_TEST" files on both paths
2025/03/18 06:32:21 INFO  : No changes found
2025/03/18 06:32:21 INFO  : Updating listings
2025/03/18 06:32:21 INFO  : Validating listings for Path1 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/" vs Path2 "TestB2:rclone-test-hafasen2nexo/063147co/check_access/path2/"
2025/03/18 06:32:21 INFO  : Bisync successful
2025/03/18 06:32:21 NOTICE: (15)  : test 5. delete path1 top level RCLONE_TEST, run sync. should fail critical.
2025/03/18 06:32:21 NOTICE: (16)  : delete-file TestB2:rclone-test-hafasen2nexo/063147co/check_access/path1/RCLONE_TEST
2025/03/18 06:32:22 INFO  : RCLONE_TEST: Deleted
2025/03/18 06:32:22 NOTICE: (17)  : bisync check-access
    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:987
        	            				/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:
        	            	no tomes available (503 service_unavailable)
        	Test:       	TestBisyncRemoteRemote/check_access
2025/03/18 06:32:23 DEBUG : B2 bucket rclone-test-hafasen2nexo: Purge remote
2025/03/18 06:32:23 INFO  : B2 bucket rclone-test-hafasen2nexo: cleaning bucket "rclone-test-hafasen2nexo" of all files
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e619_d20250318_m063222_c001_v7007000_t0000_u01742279542714")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10847e1573dc663e_d20250318_m063154_c001_v0001105_t0031_u01742279514531")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/file1.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10560db61099737f_d20250318_m063153_c001_v0001120_t0035_u01742279513966")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/file2.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f108b1274fe75ee44_d20250318_m063154_c001_v0001144_t0057_u01742279514352")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/file3.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10517f3fbb026e5e_d20250318_m063154_c001_v0001146_t0040_u01742279514159")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/file4.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10560db610997381_d20250318_m063154_c001_v0001120_t0042_u01742279514361")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e610_d20250318_m063219_c001_v7007000_t0000_u01742279539352")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f109abe8bd200456e_d20250318_m063219_c001_v0001184_t0041_u01742279539150")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10517f3fbb026ebc_d20250318_m063218_c001_v0001146_t0020_u01742279538558")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e60f_d20250318_m063214_c001_v7007000_t0000_u01742279534875")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f1120c997987ca764_d20250318_m063214_c001_v0001146_t0030_u01742279534465")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10560db6109973ad_d20250318_m063213_c001_v0001120_t0017_u01742279533366")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e60a_d20250318_m063207_c001_v7007000_t0000_u01742279527728")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10568d08a6d1ca3b_d20250318_m063207_c001_v0001144_t0006_u01742279527034")
2025/03/18 06:32:24 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10847e1573dc6678_d20250318_m063206_c001_v0001105_t0051_u01742279526595")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e607_d20250318_m063202_c001_v7007000_t0000_u01742279522499")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f106581b46d243143_d20250318_m063202_c001_v0001184_t0005_u01742279522286")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f108b1274fe75ee66_d20250318_m063201_c001_v0001144_t0001_u01742279521439")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e602_d20250318_m063158_c001_v7007000_t0000_u01742279518747")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f119469353c23b167_d20250318_m063158_c001_v0001182_t0007_u01742279518533")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10517f3fbb026e6a_d20250318_m063157_c001_v0001146_t0040_u01742279517543")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/subdir/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10517f3fbb026e64_d20250318_m063154_c001_v0001146_t0011_u01742279514626")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path1/subdir/file20.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10560db610997385_d20250318_m063154_c001_v0001120_t0011_u01742279514755")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f11142ae13114b469_d20250318_m063156_c001_v0001040_t0058_u01742279516895")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/file1.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10468ceddb1732f7_d20250318_m063156_c001_v0001177_t0037_u01742279516514")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/file2.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f105bc9d85e827758_d20250318_m063156_c001_v0001181_t0000_u01742279516320")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/file3.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10608e734ae48b7b_d20250318_m063156_c001_v0001102_t0055_u01742279516701")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/file4.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f105bc9d85e82775c_d20250318_m063156_c001_v0001181_t0058_u01742279516536")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f41822b4612809e56_d20250318_m063220_c001_v7007000_t0000_u01742279540877")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f1134b3c75aa5365e_d20250318_m063220_c001_v0001116_t0006_u01742279540316")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f105bc9d85e8277e8_d20250318_m063219_c001_v0001181_t0012_u01742279539899")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f41822b4612809e29_d20250318_m063217_c001_v7007000_t0000_u01742279537043")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f115ca5a976c02abb_d20250318_m063216_c001_v0001184_t0038_u01742279536836")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f11142ae13114b484_d20250318_m063215_c001_v0001040_t0030_u01742279535413")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f4143dd8edc9b52c3_d20250318_m063209_c001_v7007000_t0000_u01742279529838")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f112ec9706c5c08b2_d20250318_m063209_c001_v0001184_t0003_u01742279529629")
2025/03/18 06:32:25 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10608e734ae48bb3_d20250318_m063208_c001_v0001102_t0033_u01742279528274")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f4143dd8edc9b52bd_d20250318_m063203_c001_v7007000_t0000_u01742279523683")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f114db92bbe7def43_d20250318_m063203_c001_v0001181_t0032_u01742279523472")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10468ceddb17331d_d20250318_m063203_c001_v0001177_t0005_u01742279523060")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f4143dd8edc9b52ba_d20250318_m063200_c001_v7007000_t0000_u01742279520152")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f115499a12a6f2ee2_d20250318_m063159_c001_v0001029_t0000_u01742279519801")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/modtime_write_test: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f105bc9d85e82776e_d20250318_m063158_c001_v0001181_t0043_u01742279518926")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/subdir/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f114f01df6fac212d_d20250318_m063217_c001_v0001183_t0010_u01742279537972")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/subdir/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f403c9dea2b72e609_d20250318_m063206_c001_v7007000_t0000_u01742279526056")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/subdir/RCLONE_TEST: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f105bc9d85e82775e_d20250318_m063156_c001_v0001181_t0001_u01742279516760")
2025/03/18 06:32:26 DEBUG : 063147co/check_access/path2/subdir/file20.txt: Deleting (id "4_z3f928d33f03dab7b9f580e1b_f10468ceddb1732fb_d20250318_m063156_c001_v0001177_t0059_u01742279516773")
--- FAIL: TestBisyncRemoteRemote (40.77s)
    --- FAIL: TestBisyncRemoteRemote/check_access (36.20s)
FAIL
2025/03/18 06:32:27 DEBUG : B2 bucket rclone-test-gejezal4gepo: Purge remote
2025/03/18 06:32:27 INFO  : B2 bucket rclone-test-gejezal4gepo: cleaning bucket "rclone-test-gejezal4gepo" of all files
2025/03/18 06:32:28 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^check_access$'" - Finished ERROR in 42.135803472s (try 3/5): exit status 1: Failed [TestBisyncRemoteRemote/check_access]