"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteLocal$/^(all_changed|basic)$'" - Starting (try 2/5)
2025/11/01 04:36:01 DEBUG : Creating backend with remote "TestB2:rclone-test-heyatiz0gula"
2025/11/01 04:36:01 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/11/01 04:36:01 DEBUG : Creating backend with remote "/tmp/rclone387797516"
=== RUN   TestBisyncRemoteLocal
2025/11/01 04:36:01 DEBUG : Creating backend with remote "TestB2:rclone-test-foxezad6nimo"
2025/11/01 04:36:02 NOTICE: remote: TestB2:rclone-test-foxezad6nimo
=== RUN   TestBisyncRemoteLocal/all_changed
2025/11/01 04:36:02 DEBUG : Creating backend with remote "TestB2:rclone-test-foxezad6nimo/043602do"
2025/11/01 04:36:03 INFO  : path1: Making directory
2025/11/01 04:36:04 DEBUG : Creating backend with remote "TestB2:rclone-test-foxezad6nimo/043602do/all_changed/path1"
2025/11/01 04:36:05 DEBUG : Creating backend with remote "/tmp/043602do"
2025/11/01 04:36:05 DEBUG : Creating backend with remote "/tmp/043602do/all_changed/path2"
2025/11/01 04:36:05 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_all_changed/initial"
2025/11/01 04:36:05 DEBUG : Creating backend with remote "/tmp/043602do/initdir/test_all_changed-qojocuq3"
2025/11/01 04:36:05 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : subdir: Making directory with metadata
2025/11/01 04:36:05 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/01 04:36:05 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/01 04:36:05 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:05 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/01 04:36:05 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/01 04:36:05 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : Local file system at /tmp/043602do/initdir/test_all_changed-qojocuq3: Waiting for checks to finish
2025/11/01 04:36:05 DEBUG : Local file system at /tmp/043602do/initdir/test_all_changed-qojocuq3: Waiting for transfers to finish
2025/11/01 04:36:05 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/01 04:36:05 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/01 04:36:05 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/01 04:36:05 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/01 04:36:05 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/01 04:36:05 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:05 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:05 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/01 04:36:05 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:05 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/01 04:36:05 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:05 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/01 04:36:05 INFO  : B2 bucket rclone-test-foxezad6nimo path 043602do/all_changed/path1: cleaning bucket "rclone-test-foxezad6nimo" of all files
2025/11/01 04:36:06 DEBUG : Waiting for deletions to finish
2025/11/01 04:36:06 ERROR : error listing: directory not found
2025/11/01 04:36:06 ERROR : Local file system at /tmp/043602do/all_changed/path2: Failed to list "": directory not found
2025/11/01 04:36:06 DEBUG : removing 1 level 0 directories
2025/11/01 04:36:06 INFO  : Local file system at /tmp/043602do/all_changed/path2: Removing directory
2025/11/01 04:36:06 ERROR : Failed to rmdir: stat /tmp/043602do/all_changed/path2: no such file or directory
2025/11/01 04:36:06 ERROR : error listing: directory not found
2025/11/01 04:36:06 NOTICE: checking initFs Local file system at /tmp/043602do/initdir/test_all_changed-qojocuq3
2025/11/01 04:36:06 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 INFO  : subdir: Making directory
2025/11/01 04:36:06 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:06 DEBUG : B2 bucket rclone-test-foxezad6nimo path 043602do/all_changed/path1: Waiting for checks to finish
2025/11/01 04:36:06 DEBUG : B2 bucket rclone-test-foxezad6nimo path 043602do/all_changed/path1: Waiting for transfers to finish
2025/11/01 04:36:08 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:08 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:08 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:08 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/01 04:36:08 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:08 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:08 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:08 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:08 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:09 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:09 NOTICE: checking Path1 B2 bucket rclone-test-foxezad6nimo path 043602do/all_changed/path1
2025/11/01 04:36:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : subdir: Making directory with metadata
2025/11/01 04:36:09 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/01 04:36:09 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/01 04:36:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:09 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : Local file system at /tmp/043602do/all_changed/path2: Waiting for checks to finish
2025/11/01 04:36:09 DEBUG : Local file system at /tmp/043602do/all_changed/path2: Waiting for transfers to finish
2025/11/01 04:36:09 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/01 04:36:09 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/01 04:36:09 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/01 04:36:09 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/01 04:36:09 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/01 04:36:09 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/01 04:36:09 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:09 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/01 04:36:09 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:09 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:09 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/01 04:36:09 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/01 04:36:09 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:09 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:09 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/01 04:36:09 NOTICE: checking path2 Local file system at /tmp/043602do/all_changed/path2
2025/11/01 04:36:09 NOTICE: (01)  : test all-changed
2025/11/01 04:36:09 NOTICE: (02)  : test initial bisync
2025/11/01 04:36:09 NOTICE: (03)  : bisync resync
    bisync_test.go:1036: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1036
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1045
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/usr/local/go/src/strings/iter.go:61
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestBisyncRemoteLocal/all_changed
=== RUN   TestBisyncRemoteLocal/basic
2025/11/01 04:36:10 INFO  : path1: Making directory
2025/11/01 04:36:10 DEBUG : Creating backend with remote "TestB2:rclone-test-foxezad6nimo/043602do/basic/path1"
2025/11/01 04:36:11 DEBUG : Creating backend with remote "/tmp/043602do/basic/path2"
2025/11/01 04:36:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/initial"
2025/11/01 04:36:11 DEBUG : Creating backend with remote "/tmp/043602do/initdir/test_basic-xafafir1"
2025/11/01 04:36:11 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : subdir: Making directory with metadata
2025/11/01 04:36:11 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/01 04:36:11 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/01 04:36:11 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : Local file system at /tmp/043602do/initdir/test_basic-xafafir1: Waiting for checks to finish
2025/11/01 04:36:11 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : Local file system at /tmp/043602do/initdir/test_basic-xafafir1: Waiting for transfers to finish
2025/11/01 04:36:11 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/01 04:36:11 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/01 04:36:11 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/01 04:36:11 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/01 04:36:11 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/01 04:36:11 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:11 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/01 04:36:11 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/01 04:36:11 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/01 04:36:11 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:11 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:11 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/01 04:36:11 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:11 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/01 04:36:11 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_basic/modfiles"
2025/11/01 04:36:11 DEBUG : Creating backend with remote "/tmp/043602do/datadir/test_basic-yuqakud8"
2025/11/01 04:36:11 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:11 DEBUG : Local file system at /tmp/043602do/datadir/test_basic-yuqakud8: Waiting for checks to finish
2025/11/01 04:36:11 DEBUG : Local file system at /tmp/043602do/datadir/test_basic-yuqakud8: Waiting for transfers to finish
2025/11/01 04:36:11 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/11/01 04:36:11 DEBUG : file1.txt.75b77f44.partial: renamed to: file1.txt
2025/11/01 04:36:11 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:11 INFO  : B2 bucket rclone-test-foxezad6nimo path 043602do/basic/path1: cleaning bucket "rclone-test-foxezad6nimo" of all files
2025/11/01 04:36:12 DEBUG : Waiting for deletions to finish
2025/11/01 04:36:12 ERROR : error listing: directory not found
2025/11/01 04:36:12 ERROR : Local file system at /tmp/043602do/basic/path2: Failed to list "": directory not found
2025/11/01 04:36:12 DEBUG : removing 1 level 0 directories
2025/11/01 04:36:12 INFO  : Local file system at /tmp/043602do/basic/path2: Removing directory
2025/11/01 04:36:12 ERROR : Failed to rmdir: stat /tmp/043602do/basic/path2: no such file or directory
2025/11/01 04:36:12 ERROR : error listing: directory not found
2025/11/01 04:36:12 NOTICE: checking initFs Local file system at /tmp/043602do/initdir/test_basic-xafafir1
2025/11/01 04:36:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 INFO  : subdir: Making directory
2025/11/01 04:36:13 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:13 DEBUG : B2 bucket rclone-test-foxezad6nimo path 043602do/basic/path1: Waiting for checks to finish
2025/11/01 04:36:13 DEBUG : B2 bucket rclone-test-foxezad6nimo path 043602do/basic/path1: Waiting for transfers to finish
2025/11/01 04:36:13 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:13 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:14 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:14 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:14 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:14 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:14 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:14 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:14 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/11/01 04:36:14 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:14 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:14 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:14 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:14 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/11/01 04:36:16 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:16 NOTICE: checking Path1 B2 bucket rclone-test-foxezad6nimo path 043602do/basic/path1
2025/11/01 04:36:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : subdir: Making directory with metadata
2025/11/01 04:36:16 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/11/01 04:36:16 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/11/01 04:36:16 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/11/01 04:36:16 INFO  : file1.copy1.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/11/01 04:36:16 DEBUG : Local file system at /tmp/043602do/basic/path2: Waiting for checks to finish
2025/11/01 04:36:16 DEBUG : Local file system at /tmp/043602do/basic/path2: Waiting for transfers to finish
2025/11/01 04:36:16 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/11/01 04:36:16 INFO  : file1.copy3.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/11/01 04:36:16 INFO  : file1.copy2.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/11/01 04:36:16 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/11/01 04:36:16 INFO  : file1.copy4.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/11/01 04:36:16 INFO  : RCLONE_TEST: Copied (new)
2025/11/01 04:36:16 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/11/01 04:36:16 INFO  : file1.copy5.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/11/01 04:36:16 INFO  : file1.txt: Copied (new)
2025/11/01 04:36:16 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/11/01 04:36:16 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/11/01 04:36:16 INFO  : subdir/file20.txt: Copied (new)
2025/11/01 04:36:16 INFO  : subdir: Set directory modification time (using SetModTime)
2025/11/01 04:36:16 NOTICE: checking path2 Local file system at /tmp/043602do/basic/path2
2025/11/01 04:36:16 NOTICE: (01)  : test basic
2025/11/01 04:36:16 NOTICE: (02)  : test initial bisync
2025/11/01 04:36:16 NOTICE: (03)  : bisync resync
    bisync_test.go:1036: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1036
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1045
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1111
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:788
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:542
        	            				/usr/local/go/src/strings/iter.go:61
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:525
        	            				/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:405
        	Error:      	Received unexpected error:
        	            	expected a FileSseMode but found: '' (400 bad_request)
        	Test:       	TestBisyncRemoteLocal/basic
2025/11/01 04:36:18 DEBUG : B2 bucket rclone-test-foxezad6nimo: Purge remote
2025/11/01 04:36:18 INFO  : B2 bucket rclone-test-foxezad6nimo: cleaning bucket "rclone-test-foxezad6nimo" of all files
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/RCLONE_TEST: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f100ad04e313ea673_d20251101_m043607_c001_v0001182_t0004_u01761971767961")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.copy1.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1024b8aa3e59644d_d20251101_m043607_c001_v0001105_t0010_u01761971767588")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.copy2.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1167164c349f2f0f_d20251101_m043607_c001_v0001117_t0030_u01761971767408")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.copy3.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f119c0cbff6b74aa5_d20251101_m043607_c001_v0001136_t0044_u01761971767770")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.copy4.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f119c0cbff6b74aa9_d20251101_m043608_c001_v0001136_t0053_u01761971768089")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.copy5.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1167164c349f2f11_d20251101_m043608_c001_v0001117_t0030_u01761971768109")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/file1.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1024b8aa3e59644f_d20251101_m043608_c001_v0001105_t0032_u01761971768182")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/modtime_write_test: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f100ad04e313ea67f_d20251101_m043609_c001_v0001182_t0000_u01761971769336")
2025/11/01 04:36:19 DEBUG : 043602do/all_changed/path1/subdir/file20.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f100ad04e313ea675_d20251101_m043608_c001_v0001182_t0040_u01761971768196")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/RCLONE_TEST: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f11487229abbbf139_d20251101_m043614_c001_v0001184_t0003_u01761971774250")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.copy1.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f119258400171b747_d20251101_m043613_c001_v0001153_t0002_u01761971773686")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.copy2.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f10442097b29eb20f_d20251101_m043613_c001_v0001161_t0032_u01761971773880")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.copy3.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1074fa4aac0bc4d5_d20251101_m043614_c001_v0001036_t0032_u01761971774067")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.copy4.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f119258400171b74b_d20251101_m043614_c001_v0001153_t0021_u01761971774017")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.copy5.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f10442097b29eb211_d20251101_m043614_c001_v0001161_t0016_u01761971774284")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/file1.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f119258400171b74d_d20251101_m043614_c001_v0001153_t0008_u01761971774429")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/modtime_write_test: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f11487229abbbf143_d20251101_m043616_c001_v0001184_t0017_u01761971776467")
2025/11/01 04:36:19 DEBUG : 043602do/basic/path1/subdir/file20.txt: Deleting (id "4_zeff27db3b03d3bdb9fa80e1b_f1074fa4aac0bc4d7_d20251101_m043614_c001_v0001036_t0058_u01761971774454")
--- FAIL: TestBisyncRemoteLocal (18.84s)
    --- FAIL: TestBisyncRemoteLocal/all_changed (8.30s)
    --- FAIL: TestBisyncRemoteLocal/basic (7.49s)
FAIL
2025/11/01 04:36:20 DEBUG : B2 bucket rclone-test-heyatiz0gula: Purge remote
2025/11/01 04:36:20 INFO  : B2 bucket rclone-test-heyatiz0gula: cleaning bucket "rclone-test-heyatiz0gula" of all files
2025/11/01 04:36:21 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteLocal$/^(all_changed|basic)$'" - Finished ERROR in 20.230701232s (try 2/5): exit status 1: Failed [TestBisyncRemoteLocal/all_changed TestBisyncRemoteLocal/basic]