"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5)
2025/10/01 04:08:57 DEBUG : Creating backend with remote "TestB2:rclone-test-pizetob2mune"
2025/10/01 04:08:57 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/01 04:08:58 DEBUG : Creating backend with remote "/tmp/rclone2244459307"
=== RUN   TestBisyncRemoteRemote
2025/10/01 04:08:58 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru"
2025/10/01 04:08:59 NOTICE: remote: TestB2:rclone-test-toqikoj8hiru
=== RUN   TestBisyncRemoteRemote/resync
2025/10/01 04:08:59 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le"
2025/10/01 04:09:00 INFO  : path1: Making directory
2025/10/01 04:09:01 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync/path1"
2025/10/01 04:09:02 INFO  : path2: Making directory
2025/10/01 04:09:02 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync/path2"
2025/10/01 04:09:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial"
2025/10/01 04:09:03 DEBUG : Creating backend with remote "/tmp/040859le/initdir/test_resync-nametir9"
2025/10/01 04:09:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : Local file system at /tmp/040859le/initdir/test_resync-nametir9: Waiting for checks to finish
2025/10/01 04:09:03 DEBUG : Local file system at /tmp/040859le/initdir/test_resync-nametir9: Waiting for transfers to finish
2025/10/01 04:09:03 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/01 04:09:03 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/01 04:09:03 INFO  : file4.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/01 04:09:03 INFO  : file5.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/01 04:09:03 INFO  : file6.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/01 04:09:03 INFO  : file7.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/01 04:09:03 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:03 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/01 04:09:03 INFO  : file3.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/01 04:09:03 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/01 04:09:03 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:03 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles"
2025/10/01 04:09:03 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync-gapohah3"
2025/10/01 04:09:03 DEBUG : fileA.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : fileB.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:03 DEBUG : Local file system at /tmp/040859le/datadir/test_resync-gapohah3: Waiting for checks to finish
2025/10/01 04:09:03 DEBUG : Local file system at /tmp/040859le/datadir/test_resync-gapohah3: Waiting for transfers to finish
2025/10/01 04:09:03 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:09:03 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:09:03 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt
2025/10/01 04:09:03 INFO  : fileB.txt: Copied (new)
2025/10/01 04:09:03 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt
2025/10/01 04:09:03 INFO  : fileA.txt: Copied (new)
2025/10/01 04:09:03 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path1: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:09:04 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path2: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:09:05 NOTICE: checking initFs Local file system at /tmp/040859le/initdir/test_resync-nametir9
2025/10/01 04:09:05 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:05 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path1: Waiting for checks to finish
2025/10/01 04:09:05 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path1: Waiting for transfers to finish
2025/10/01 04:09:06 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:06 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:06 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:06 INFO  : file4.txt: Copied (new)
2025/10/01 04:09:07 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:07 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:07 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:07 INFO  : file5.txt: Copied (new)
2025/10/01 04:09:07 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:07 INFO  : file3.txt: Copied (new)
2025/10/01 04:09:07 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:07 INFO  : file7.txt: Copied (new)
2025/10/01 04:09:07 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:09:07 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:07 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:07 INFO  : file6.txt: Copied (new)
2025/10/01 04:09:07 NOTICE: checking Path1 B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path1
2025/10/01 04:09:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:07 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path2: Waiting for checks to finish
2025/10/01 04:09:07 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path2: Waiting for transfers to finish
2025/10/01 04:09:08 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:08 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:08 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:08 INFO  : file3.txt: Copied (new)
2025/10/01 04:09:09 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:09 INFO  : file5.txt: Copied (new)
2025/10/01 04:09:09 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:09 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:09 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:09 INFO  : file4.txt: Copied (new)
2025/10/01 04:09:09 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:09:09 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:09 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:09 INFO  : file6.txt: Copied (new)
2025/10/01 04:09:10 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:10 INFO  : file7.txt: Copied (new)
2025/10/01 04:09:10 NOTICE: checking path2 B2 bucket rclone-test-toqikoj8hiru path 040859le/resync/path2
2025/10/01 04:09:10 NOTICE: (01)  : test resync
2025/10/01 04:09:10 NOTICE: (02)  : test 1. resync with empty path1, resulting in copying all content from path2.
2025/10/01 04:09:10 NOTICE: (03)  : purge-children TestB2:rclone-test-toqikoj8hiru/040859le/resync/path1/
2025/10/01 04:09:10 DEBUG : RCLONE_TEST: Remove file
2025/10/01 04:09:10 DEBUG : file1.txt: Remove file
2025/10/01 04:09:10 DEBUG : file2.txt: Remove file
2025/10/01 04:09:11 DEBUG : file3.txt: Remove file
2025/10/01 04:09:11 DEBUG : file4.txt: Remove file
2025/10/01 04:09:11 DEBUG : file5.txt: Remove file
2025/10/01 04:09:11 DEBUG : file6.txt: Remove file
2025/10/01 04:09:11 DEBUG : file7.txt: Remove file
2025/10/01 04:09:12 NOTICE: (04)  : bisync resync
2025/10/01 04:09:12 DEBUG : modtime_write_test: Clearing upload URL because of error: Post "https://pod-000-1109-12.backblaze.com/b2api/v1/b2_upload_file/cfa29d23f05d9bdb9f980e1b/c001_v0001109_t0048": EOF
2025/10/01 04:09:12 DEBUG : pacer: low level retry 1/1 (error Post "https://pod-000-1109-12.backblaze.com/b2api/v1/b2_upload_file/cfa29d23f05d9bdb9f980e1b/c001_v0001109_t0048": EOF)
2025/10/01 04:09:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
    bisync_test.go:1025: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:1025
        	            				/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:
        	            	Post "https://pod-000-1109-12.backblaze.com/b2api/v1/b2_upload_file/cfa29d23f05d9bdb9f980e1b/c001_v0001109_t0048": EOF
        	Test:       	TestBisyncRemoteRemote/resync
=== RUN   TestBisyncRemoteRemote/resync_modes
2025/10/01 04:09:12 INFO  : path1: Making directory
2025/10/01 04:09:12 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1"
2025/10/01 04:09:13 INFO  : path2: Making directory
2025/10/01 04:09:13 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2"
2025/10/01 04:09:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial"
2025/10/01 04:09:14 DEBUG : Creating backend with remote "/tmp/040859le/initdir/test_resync_modes-digureh5"
2025/10/01 04:09:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : Local file system at /tmp/040859le/initdir/test_resync_modes-digureh5: Waiting for checks to finish
2025/10/01 04:09:14 DEBUG : Local file system at /tmp/040859le/initdir/test_resync_modes-digureh5: Waiting for transfers to finish
2025/10/01 04:09:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:14 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/01 04:09:14 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/01 04:09:14 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/01 04:09:14 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:14 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:09:14 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/01 04:09:14 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles"
2025/10/01 04:09:14 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:14 DEBUG : file1L.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : file1R.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:14 DEBUG : Local file system at /tmp/040859le/datadir/test_resync_modes-kimatik3: Waiting for checks to finish
2025/10/01 04:09:14 DEBUG : Local file system at /tmp/040859le/datadir/test_resync_modes-kimatik3: Waiting for transfers to finish
2025/10/01 04:09:14 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK
2025/10/01 04:09:14 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK
2025/10/01 04:09:14 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt
2025/10/01 04:09:14 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:14 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt
2025/10/01 04:09:14 INFO  : file1R.txt: Copied (new)
2025/10/01 04:09:14 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK
2025/10/01 04:09:14 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt
2025/10/01 04:09:14 INFO  : file1L.txt: Copied (new)
2025/10/01 04:09:14 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:09:15 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:09:16 NOTICE: checking initFs Local file system at /tmp/040859le/initdir/test_resync_modes-digureh5
2025/10/01 04:09:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:16 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:16 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Waiting for checks to finish
2025/10/01 04:09:16 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Waiting for transfers to finish
2025/10/01 04:09:17 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:17 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:17 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:17 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:09:17 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:17 NOTICE: checking Path1 B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1
2025/10/01 04:09:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:09:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:09:18 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Waiting for checks to finish
2025/10/01 04:09:18 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Waiting for transfers to finish
2025/10/01 04:09:19 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:19 INFO  : file2.txt: Copied (new)
2025/10/01 04:09:19 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:09:19 INFO  : file1.txt: Copied (new)
2025/10/01 04:09:19 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:09:19 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:09:19 NOTICE: checking path2 B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2
2025/10/01 04:09:20 NOTICE: (01)  : test resync-mode
2025/10/01 04:09:20 NOTICE: (02)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:09:20 NOTICE: (03)  : touch-glob 2001-01-02 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:09:20 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/"
2025/10/01 04:09:20 DEBUG : fs cache: renaming cache item "/tmp/040859le/datadir/test_resync_modes-kimatik3/" to be canonical "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:20 DEBUG : file1R.txt: Set modification time 2001-01-02
2025/10/01 04:09:20 NOTICE: (04)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:09:20 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:09:20 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:09:20 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:21 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:09:21 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/01 04:09:22 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:09:22 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:09:22 NOTICE: (05)  : touch-glob 2001-03-04 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:09:22 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:22 DEBUG : file1L.txt: Set modification time 2001-03-04
2025/10/01 04:09:22 NOTICE: (06)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:09:22 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:09:22 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:09:22 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:09:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:09:24 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/01 04:09:25 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:09:25 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:09:25 NOTICE: (07)  : test bisync run with --resync-mode=newer
2025/10/01 04:09:25 NOTICE: (08)  : bisync resync resync-mode=newer
2025/10/01 04:09:34 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:09:34 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:09:34 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:09:34 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:09:34 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:34 INFO  : Copying Path2 files to Path1
2025/10/01 04:09:34 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:09:34 INFO  : There was nothing to transfer
2025/10/01 04:09:34 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:09:35 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:09:35 INFO  : Resync updating listings
2025/10/01 04:09:35 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:35 INFO  : Bisync successful
2025/10/01 04:09:35 NOTICE: (09)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:09:35 NOTICE: (10)  : touch-glob 2001-07-23 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:09:35 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:35 DEBUG : file1R.txt: Set modification time 2001-07-23
2025/10/01 04:09:35 NOTICE: (11)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:09:35 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:09:35 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:09:35 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:36 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:09:36 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/01 04:09:38 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:09:38 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:09:38 NOTICE: (12)  : touch-glob 2001-08-26 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:09:38 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:38 DEBUG : file1L.txt: Set modification time 2001-08-26
2025/10/01 04:09:38 NOTICE: (13)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:09:38 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:09:38 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:09:38 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:09:39 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:09:39 DEBUG : file1L.txt: Modification times differ by -4200h0m0s: 2001-08-26 00:00:00 +0000 UTC, 2001-03-04 00:00:00 +0000 UTC
2025/10/01 04:09:39 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:09:41 INFO  : file1L.txt: Updated modification time in destination
2025/10/01 04:09:41 DEBUG : file1L.txt: Unchanged skipping
2025/10/01 04:09:41 NOTICE: (14)  : test bisync run with --resync-mode=path2
2025/10/01 04:09:41 NOTICE: (15)  : bisync resync resync-mode=path2
2025/10/01 04:09:49 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:09:49 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:09:49 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:09:49 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:09:49 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:49 INFO  : Copying Path2 files to Path1
2025/10/01 04:09:49 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:09:50 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:09:50 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:09:50 INFO  : Resync updating listings
2025/10/01 04:09:50 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:50 INFO  : Bisync successful
2025/10/01 04:09:50 NOTICE: (16)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:09:50 NOTICE: (17)  : touch-glob 2002-07-23 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:09:50 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:50 DEBUG : file1R.txt: Set modification time 2002-07-23
2025/10/01 04:09:50 NOTICE: (18)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:09:50 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:09:50 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:09:50 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:09:51 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:09:51 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2002-07-23 00:00:00 +0000 UTC, 2001-07-23 00:00:00 +0000 UTC
2025/10/01 04:09:51 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:09:53 INFO  : file1R.txt: Updated modification time in destination
2025/10/01 04:09:53 DEBUG : file1R.txt: Unchanged skipping
2025/10/01 04:09:53 NOTICE: (19)  : touch-glob 2002-08-26 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:09:53 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:09:53 DEBUG : file1L.txt: Set modification time 2002-08-26
2025/10/01 04:09:53 NOTICE: (20)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:09:53 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:09:53 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:09:53 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:09:54 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:09:54 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/01 04:09:55 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:09:55 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:09:55 NOTICE: (21)  : test bisync run with --resync-mode=larger
2025/10/01 04:09:55 NOTICE: (22)  : bisync resync resync-mode=larger
2025/10/01 04:10:04 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:04 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:04 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:10:04 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:10:04 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:04 INFO  : Copying Path2 files to Path1
2025/10/01 04:10:04 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:10:04 INFO  : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9
2025/10/01 04:10:05 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:10:05 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:10:05 INFO  : Resync updating listings
2025/10/01 04:10:05 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:05 INFO  : Bisync successful
2025/10/01 04:10:05 NOTICE: (23)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:10:05 NOTICE: (24)  : touch-glob 2003-07-23 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:10:05 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:05 DEBUG : file1R.txt: Set modification time 2003-07-23
2025/10/01 04:10:05 NOTICE: (25)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:10:05 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:10:05 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:05 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:06 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:10:06 DEBUG : file1R.txt: Modification times differ by -8760h0m0s: 2003-07-23 00:00:00 +0000 UTC, 2002-07-23 00:00:00 +0000 UTC
2025/10/01 04:10:06 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:10:08 INFO  : file1R.txt: Updated modification time in destination
2025/10/01 04:10:08 DEBUG : file1R.txt: Unchanged skipping
2025/10/01 04:10:08 NOTICE: (26)  : touch-glob 2003-09-04 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:10:08 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:08 DEBUG : file1L.txt: Set modification time 2003-09-04
2025/10/01 04:10:08 NOTICE: (27)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:10:08 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:10:08 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:10:08 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:10:09 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:10:09 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/01 04:10:11 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:10:11 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:10:11 NOTICE: (28)  : test bisync run with --resync-mode=older
2025/10/01 04:10:11 NOTICE: (29)  : bisync resync resync-mode=older
2025/10/01 04:10:19 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:19 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:19 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:10:19 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:10:19 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:19 INFO  : Copying Path2 files to Path1
2025/10/01 04:10:19 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:10:20 INFO  : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s
2025/10/01 04:10:20 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:10:20 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:10:20 INFO  : Resync updating listings
2025/10/01 04:10:20 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:20 INFO  : Bisync successful
2025/10/01 04:10:20 NOTICE: (30)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:10:20 NOTICE: (31)  : touch-glob 2004-07-23 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:10:20 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:20 DEBUG : file1R.txt: Set modification time 2004-07-23
2025/10/01 04:10:20 NOTICE: (32)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:10:20 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:10:20 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:20 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:21 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:10:22 DEBUG : file1R.txt: Modification times differ by -8784h0m0s: 2004-07-23 00:00:00 +0000 UTC, 2003-07-23 00:00:00 +0000 UTC
2025/10/01 04:10:22 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:10:23 INFO  : file1R.txt: Updated modification time in destination
2025/10/01 04:10:23 DEBUG : file1R.txt: Unchanged skipping
2025/10/01 04:10:23 NOTICE: (33)  : touch-glob 2004-07-23 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:10:23 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:23 DEBUG : file1L.txt: Set modification time 2004-07-23
2025/10/01 04:10:23 NOTICE: (34)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:10:23 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:10:23 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:10:23 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:10:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:10:24 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/01 04:10:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:10:26 INFO  : file1L.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:10:26 NOTICE: (35)  : test bisync run with --resync-mode=smaller
2025/10/01 04:10:26 NOTICE: (36)  : bisync resync resync-mode=smaller
2025/10/01 04:10:34 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:34 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:34 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:10:34 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:10:34 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:34 INFO  : Copying Path2 files to Path1
2025/10/01 04:10:34 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:10:34 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/01 04:10:34 INFO  : There was nothing to transfer
2025/10/01 04:10:34 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:10:34 INFO  : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9
2025/10/01 04:10:35 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:10:35 INFO  : Resync updating listings
2025/10/01 04:10:35 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:35 INFO  : Bisync successful
2025/10/01 04:10:35 NOTICE: (37)  : test changed on both paths and NOT identical - file1 (file1R, file1L)
2025/10/01 04:10:35 NOTICE: (38)  : touch-glob 2005-01-02 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:10:35 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:35 DEBUG : file1R.txt: Set modification time 2005-01-02
2025/10/01 04:10:35 NOTICE: (39)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:10:35 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:10:35 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:35 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:36 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:10:36 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/01 04:10:38 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:10:38 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:10:38 NOTICE: (40)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file2.txt
2025/10/01 04:10:38 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file2.txt"
2025/10/01 04:10:38 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:38 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:10:39 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file2.txt"
2025/10/01 04:10:39 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0)
2025/10/01 04:10:41 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:10:41 INFO  : file1R.txt: Copied (replaced existing) to: file2.txt
2025/10/01 04:10:41 NOTICE: (41)  : touch-glob 2006-03-04 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:10:41 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:41 DEBUG : file1L.txt: Set modification time 2006-03-04
2025/10/01 04:10:41 NOTICE: (42)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:10:41 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:10:41 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:10:41 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:10:42 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:10:42 DEBUG : file1L.txt: Modification times differ by -14136h0m0s: 2006-03-04 00:00:00 +0000 UTC, 2004-07-23 00:00:00 +0000 UTC
2025/10/01 04:10:42 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:10:43 INFO  : file1L.txt: Updated modification time in destination
2025/10/01 04:10:43 DEBUG : file1L.txt: Unchanged skipping
2025/10/01 04:10:43 NOTICE: (43)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file2.txt
2025/10/01 04:10:43 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file2.txt"
2025/10/01 04:10:43 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:10:43 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:44 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file2.txt"
2025/10/01 04:10:45 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0)
2025/10/01 04:10:46 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:10:46 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/01 04:10:46 NOTICE: (44)  : test bisync run with --resync-mode=path1
2025/10/01 04:10:46 NOTICE: (45)  : bisync resync resync-mode=path1
2025/10/01 04:10:55 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:55 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:10:55 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:10:55 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/01 04:10:55 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:55 INFO  : Copying Path2 files to Path1
2025/10/01 04:10:55 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:10:55 INFO  : There was nothing to transfer
2025/10/01 04:10:55 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:10:56 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:10:56 INFO  : file2.txt: Copied (server-side copy)
2025/10/01 04:10:56 INFO  : Resync updating listings
2025/10/01 04:10:56 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:56 INFO  : Bisync successful
2025/10/01 04:10:56 NOTICE: (46)  : test no winner
2025/10/01 04:10:56 NOTICE: (47)  : touch-glob 2022-02-22 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1R.txt
2025/10/01 04:10:56 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:10:56 DEBUG : file1R.txt: Set modification time 2022-02-22
2025/10/01 04:10:56 NOTICE: (48)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file1.txt
2025/10/01 04:10:56 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file1.txt"
2025/10/01 04:10:56 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:56 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:10:57 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file1.txt"
2025/10/01 04:10:57 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33)
2025/10/01 04:10:59 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:10:59 INFO  : file1R.txt: Copied (replaced existing) to: file1.txt
2025/10/01 04:10:59 NOTICE: (49)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file2.txt
2025/10/01 04:10:59 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file2.txt"
2025/10/01 04:10:59 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1R.txt"
2025/10/01 04:10:59 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:11:01 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file2.txt"
2025/10/01 04:11:01 DEBUG : file1R.txt: Modification times differ by -150240h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2005-01-02 00:00:00 +0000 UTC
2025/10/01 04:11:01 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK
2025/10/01 04:11:03 INFO  : file1R.txt: Updated modification time in destination
2025/10/01 04:11:03 DEBUG : file1R.txt: Unchanged skipping
2025/10/01 04:11:03 NOTICE: (50)  : touch-glob 2022-02-22 /tmp/040859le/datadir/test_resync_modes-kimatik3/ file1L.txt
2025/10/01 04:11:03 DEBUG : fs cache: switching user supplied name "/tmp/040859le/datadir/test_resync_modes-kimatik3/" for canonical name "/tmp/040859le/datadir/test_resync_modes-kimatik3"
2025/10/01 04:11:03 DEBUG : file1L.txt: Set modification time 2022-02-22
2025/10/01 04:11:03 NOTICE: (51)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/ file1.txt
2025/10/01 04:11:03 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" as "file1.txt"
2025/10/01 04:11:03 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:11:03 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/"
2025/10/01 04:11:04 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1" as "file1.txt"
2025/10/01 04:11:04 DEBUG : file1L.txt: Modification times differ by -140016h0m0s: 2022-02-22 00:00:00 +0000 UTC, 2006-03-04 00:00:00 +0000 UTC
2025/10/01 04:11:04 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:11:05 INFO  : file1L.txt: Updated modification time in destination
2025/10/01 04:11:05 DEBUG : file1L.txt: Unchanged skipping
2025/10/01 04:11:05 NOTICE: (52)  : copy-as /tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/ file2.txt
2025/10/01 04:11:05 DEBUG : copyFile "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt" to "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/" as "file2.txt"
2025/10/01 04:11:05 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_resync_modes-kimatik3/file1L.txt"
2025/10/01 04:11:05 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:11:06 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2" as "file2.txt"
2025/10/01 04:11:06 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42)
2025/10/01 04:11:08 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK
2025/10/01 04:11:08 INFO  : file1L.txt: Copied (replaced existing) to: file2.txt
2025/10/01 04:11:08 NOTICE: (53)  : test bisync run with --resync-mode=newer
2025/10/01 04:11:08 NOTICE: (54)  : bisync resync resync-mode=newer
2025/10/01 04:11:17 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:11:17 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/01 04:11:17 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/01 04:11: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/10/01 04:11:17 INFO  : Synching Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" with Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:11:17 INFO  : Copying Path2 files to Path1
2025/10/01 04:11:17 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/01 04:11:18 INFO  : file2.txt: Copied (server-side copy)
2025/10/01 04:11:18 INFO  : file1.txt: Copied (server-side copy)
2025/10/01 04:11:18 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/01 04:11:18 INFO  : Resync updating listings
2025/10/01 04:11:18 INFO  : Validating listings for Path1 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path1/" vs Path2 "TestB2:rclone-test-toqikoj8hiru/040859le/resync_modes/path2/"
2025/10/01 04:11:18 INFO  : Bisync successful
2025/10/01 04:11:18 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path1: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:18 DEBUG : RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10368ba454dd22f5_d20251001_m040917_c001_v0001182_t0018_u01759291757789")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1021db59543ffb33_d20251001_m041118_c001_v0001183_t0005_u01759291878143")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1140a0294f1f0bec_d20251001_m041105_c001_v0001181_t0011_u01759291865473")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1011b9ba4e704848_d20251001_m041043_c001_v0001182_t0055_u01759291843571")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f113d203ecbe96ad2_d20251001_m041026_c001_v0001171_t0055_u01759291826147")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111e19df0cac9847_d20251001_m041020_c001_v0001136_t0025_u01759291820252")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1132a193e2559230_d20251001_m041010_c001_v0001105_t0050_u01759291810754")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1098c2b4a1749aa3_d20251001_m041004_c001_v0001032_t0034_u01759291804543")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10437833ab865b1a_d20251001_m040955_c001_v0001183_t0015_u01759291795853")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1067d1a4f6c0440e_d20251001_m040949_c001_v0001157_t0037_u01759291789904")
2025/10/01 04:11:18 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11132c1fdf4fd5c7_d20251001_m040940_c001_v0001104_t0045_u01759291780653")
2025/10/01 04:11:19 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f117f9ddbbb5de75b_d20251001_m040925_c001_v0001092_t0004_u01759291765654")
2025/10/01 04:11:19 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f107dcefa3d573651_d20251001_m040917_c001_v0001172_t0013_u01759291757413")
2025/10/01 04:11:19 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1130b48266855b5e_d20251001_m041117_c001_v0001136_t0007_u01759291877776")
2025/10/01 04:11:19 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11905e7ded0a04a5_d20251001_m041102_c001_v0001146_t0039_u01759291862631")
2025/10/01 04:11:19 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11976030820ee2a4_d20251001_m041040_c001_v0001101_t0057_u01759291840929")
2025/10/01 04:11:19 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1026ff9bb15df48d_d20251001_m040917_c001_v0001184_t0011_u01759291757619")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f4132479332f8f875_d20251001_m041112_c001_v7007000_t0000_u01759291872841")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10719047654bf4f4_d20251001_m041111_c001_v0001041_t0009_u01759291871440")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f107dcefa3d5738ff_d20251001_m041109_c001_v0001172_t0055_u01759291869100")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f423de3327038d775_d20251001_m041050_c001_v7007000_t0000_u01759291850958")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1072c83b6ec28f3a_d20251001_m041049_c001_v0001040_t0018_u01759291849470")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10368ba454dd265e_d20251001_m041047_c001_v0001182_t0027_u01759291847298")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f4128667835f7706c_d20251001_m041030_c001_v7007000_t0000_u01759291830274")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112df6bdd2a0feb5_d20251001_m041029_c001_v0001177_t0052_u01759291829001")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1026ff9bb15df777_d20251001_m041026_c001_v0001184_t0057_u01759291826778")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f423de3327038d6bc_d20251001_m041015_c001_v7007000_t0000_u01759291815253")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f106f5fff452136bf_d20251001_m041014_c001_v0001181_t0005_u01759291814015")
2025/10/01 04:11:19 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f107dcefa3d5737a7_d20251001_m041011_c001_v0001172_t0046_u01759291811546")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f4188142601633a8b_d20251001_m040959_c001_v7007000_t0000_u01759291799913")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f117fd7cdc5bb5b3d_d20251001_m040958_c001_v0001102_t0017_u01759291798603")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10368ba454dd246b_d20251001_m040956_c001_v0001182_t0015_u01759291796423")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f42136b7bf6c06787_d20251001_m040945_c001_v7007000_t0000_u01759291785145")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112d0f51a0f9bde3_d20251001_m040943_c001_v0001181_t0015_u01759291783932")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1026ff9bb15df57c_d20251001_m040941_c001_v0001184_t0055_u01759291781792")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85956_d20251001_m040929_c001_v7007000_t0000_u01759291769920")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1026ff9bb15df4f4_d20251001_m040928_c001_v0001184_t0056_u01759291768705")
2025/10/01 04:11:20 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f107dcefa3d573691_d20251001_m040926_c001_v0001172_t0037_u01759291766365")
2025/10/01 04:11:20 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/resync_modes/path2: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:21 DEBUG : RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f118accb17fdaaf8a_d20251001_m040919_c001_v0001106_t0031_u01759291759106")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f115cc6779c72ae1b_d20251001_m041059_c001_v0001036_t0004_u01759291859335")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1069e441dd223264_d20251001_m041056_c001_v0001184_t0005_u01759291856000")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f107701d9395c2bf3_d20251001_m041037_c001_v0001136_t0012_u01759291837804")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1004d4e6c31f4129_d20251001_m041034_c001_v0001176_t0020_u01759291834928")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f117d5a4997dcae25_d20251001_m041023_c001_v0001182_t0042_u01759291823292")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11345a5b8b8119ac_d20251001_m041007_c001_v0001029_t0002_u01759291807701")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f106550eea7462d76_d20251001_m040952_c001_v0001102_t0041_u01759291792727")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11336cb6446bc15c_d20251001_m040937_c001_v0001104_t0001_u01759291777959")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1175899a3c595d73_d20251001_m040934_c001_v0001105_t0000_u01759291774902")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f104b1bf0b289b2ad_d20251001_m040922_c001_v0001184_t0027_u01759291762776")
2025/10/01 04:11:21 DEBUG : file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1024fd7198c631ee_d20251001_m040919_c001_v0001093_t0037_u01759291759270")
2025/10/01 04:11:21 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f101ef4a4fb790e84_d20251001_m041108_c001_v0001101_t0027_u01759291868277")
2025/10/01 04:11:21 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f101b6f879f4cbbab_d20251001_m041056_c001_v0001098_t0056_u01759291856239")
2025/10/01 04:11:21 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f114b4c83905b8337_d20251001_m041046_c001_v0001106_t0043_u01759291846437")
2025/10/01 04:11:21 DEBUG : file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111fc8549205b27e_d20251001_m040918_c001_v0001115_t0001_u01759291758889")
2025/10/01 04:11:21 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f406aa5ac2214ad36_d20251001_m041117_c001_v7007000_t0000_u01759291877342")
2025/10/01 04:11:21 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f119d2999eb7be993_d20251001_m041115_c001_v0001137_t0007_u01759291875625")
2025/10/01 04:11:21 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111fc8549205b44d_d20251001_m041113_c001_v0001115_t0046_u01759291873383")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f400c1ac742709638_d20251001_m041055_c001_v7007000_t0000_u01759291855393")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f117d558e83686094_d20251001_m041054_c001_v0001131_t0019_u01759291854007")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f118accb17fdab149_d20251001_m041051_c001_v0001106_t0035_u01759291851506")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f4203db09106ccb77_d20251001_m041034_c001_v7007000_t0000_u01759291834329")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11040e2627d71081_d20251001_m041033_c001_v0001181_t0029_u01759291833124")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1024fd7198c633d9_d20251001_m041030_c001_v0001093_t0041_u01759291830811")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f403c2324b25f152f_d20251001_m041019_c001_v7007000_t0000_u01759291819856")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1081b6e4dc1cadff_d20251001_m041018_c001_v0001113_t0044_u01759291818431")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111fc8549205b355_d20251001_m041015_c001_v0001115_t0050_u01759291815801")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f4173808e5f444537_d20251001_m041004_c001_v7007000_t0000_u01759291804165")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1004be712f7142a7_d20251001_m041002_c001_v0001183_t0046_u01759291802940")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f118accb17fdab036_d20251001_m041000_c001_v0001106_t0028_u01759291800470")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f41522a5b6ea34caa_d20251001_m040949_c001_v7007000_t0000_u01759291789445")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1008f5aedbaad019_d20251001_m040948_c001_v0001182_t0017_u01759291788276")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1024fd7198c6329f_d20251001_m040945_c001_v0001093_t0012_u01759291785703")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f400c1ac74270957b_d20251001_m040934_c001_v7007000_t0000_u01759291774248")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f110e17335bd82799_d20251001_m040932_c001_v0001131_t0000_u01759291772906")
2025/10/01 04:11:22 DEBUG : modtime_write_test: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111fc8549205b2a9_d20251001_m040930_c001_v0001115_t0011_u01759291770474")
    bisync_test.go:608: TEST resync_modes PASSED
=== RUN   TestBisyncRemoteRemote/rmdirs
2025/10/01 04:11:23 INFO  : path1: Making directory
2025/10/01 04:11:23 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/rmdirs/path1"
2025/10/01 04:11:24 INFO  : path2: Making directory
2025/10/01 04:11:24 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/rmdirs/path2"
2025/10/01 04:11:25 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial"
2025/10/01 04:11:25 DEBUG : Creating backend with remote "/tmp/040859le/initdir/test_rmdirs-kubinet4"
2025/10/01 04:11:25 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : subdir: Making directory with metadata
2025/10/01 04:11:25 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/01 04:11:25 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/01 04:11:25 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : Local file system at /tmp/040859le/initdir/test_rmdirs-kubinet4: Waiting for checks to finish
2025/10/01 04:11:25 DEBUG : Local file system at /tmp/040859le/initdir/test_rmdirs-kubinet4: Waiting for transfers to finish
2025/10/01 04:11:25 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt
2025/10/01 04:11:25 INFO  : file1.copy1.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt
2025/10/01 04:11:25 INFO  : file1.copy2.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt
2025/10/01 04:11:25 INFO  : file1.copy3.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt
2025/10/01 04:11:25 INFO  : file1.copy4.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt
2025/10/01 04:11:25 INFO  : file1.copy5.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/01 04:11:25 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:25 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/01 04:11:25 INFO  : subdir/file20.txt: Copied (new)
2025/10/01 04:11:25 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/01 04:11:25 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/01 04:11:25 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:25 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/01 04:11:25 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles"
2025/10/01 04:11:25 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_rmdirs-batomof1"
2025/10/01 04:11:25 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:25 DEBUG : Local file system at /tmp/040859le/datadir/test_rmdirs-batomof1: Waiting for checks to finish
2025/10/01 04:11:25 DEBUG : Local file system at /tmp/040859le/datadir/test_rmdirs-batomof1: Waiting for transfers to finish
2025/10/01 04:11:25 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:25 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt
2025/10/01 04:11:25 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:25 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path1: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:26 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path2: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:27 NOTICE: checking initFs Local file system at /tmp/040859le/initdir/test_rmdirs-kubinet4
2025/10/01 04:11:27 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 INFO  : subdir: Making directory
2025/10/01 04:11:27 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:27 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path1: Waiting for checks to finish
2025/10/01 04:11:27 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path1: Waiting for transfers to finish
2025/10/01 04:11:28 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:28 INFO  : file1.copy2.txt: Copied (new)
2025/10/01 04:11:28 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:28 INFO  : file1.copy4.txt: Copied (new)
2025/10/01 04:11:29 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:29 INFO  : file1.copy1.txt: Copied (new)
2025/10/01 04:11:29 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:29 INFO  : file1.copy3.txt: Copied (new)
2025/10/01 04:11:29 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:11:29 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:29 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:29 INFO  : subdir/file20.txt: Copied (new)
2025/10/01 04:11:29 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:29 INFO  : file1.copy5.txt: Copied (new)
2025/10/01 04:11:29 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:29 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:29 NOTICE: checking Path1 B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path1
2025/10/01 04:11:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 INFO  : subdir: Making directory
2025/10/01 04:11:29 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:29 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path2: Waiting for checks to finish
2025/10/01 04:11:29 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path2: Waiting for transfers to finish
2025/10/01 04:11:30 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:30 INFO  : file1.copy1.txt: Copied (new)
2025/10/01 04:11:30 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:30 INFO  : file1.copy4.txt: Copied (new)
2025/10/01 04:11:30 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:30 INFO  : file1.copy5.txt: Copied (new)
2025/10/01 04:11:31 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:31 INFO  : file1.copy3.txt: Copied (new)
2025/10/01 04:11:31 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:31 INFO  : file1.copy2.txt: Copied (new)
2025/10/01 04:11:31 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:31 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:31 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:11:31 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:31 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:31 INFO  : subdir/file20.txt: Copied (new)
2025/10/01 04:11:31 NOTICE: checking path2 B2 bucket rclone-test-toqikoj8hiru path 040859le/rmdirs/path2
2025/10/01 04:11:31 NOTICE: (01)  : test rmdirs
2025/10/01 04:11:31 NOTICE: (02)  : test initial bisync
2025/10/01 04:11:31 NOTICE: (03)  : bisync resync
    bisync_test.go:1007: skipping test as remote does not support empty dirs
=== RUN   TestBisyncRemoteRemote/volatile
2025/10/01 04:11:31 INFO  : path1: Making directory
2025/10/01 04:11:31 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/volatile/path1"
2025/10/01 04:11:32 INFO  : path2: Making directory
2025/10/01 04:11:32 DEBUG : Creating backend with remote "TestB2:rclone-test-toqikoj8hiru/040859le/volatile/path2"
2025/10/01 04:11:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial"
2025/10/01 04:11:33 DEBUG : Creating backend with remote "/tmp/040859le/initdir/test_volatile-dukixox0"
2025/10/01 04:11:33 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : Local file system at /tmp/040859le/initdir/test_volatile-dukixox0: Waiting for checks to finish
2025/10/01 04:11:33 DEBUG : Local file system at /tmp/040859le/initdir/test_volatile-dukixox0: Waiting for transfers to finish
2025/10/01 04:11:33 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/01 04:11:33 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/01 04:11:33 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:33 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/01 04:11:33 INFO  : file2.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/01 04:11:33 INFO  : file3.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/01 04:11:33 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/01 04:11:33 INFO  : file5.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/01 04:11:33 INFO  : file7.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/01 04:11:33 INFO  : file4.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/01 04:11:33 INFO  : file6.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/10/01 04:11:33 INFO  : file8.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles"
2025/10/01 04:11:33 DEBUG : Creating backend with remote "/tmp/040859le/datadir/test_volatile-tubiguw4"
2025/10/01 04:11:33 DEBUG : dummy.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file10.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file11.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file5L.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file5R.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:33 DEBUG : Local file system at /tmp/040859le/datadir/test_volatile-tubiguw4: Waiting for checks to finish
2025/10/01 04:11:33 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/01 04:11:33 DEBUG : Local file system at /tmp/040859le/datadir/test_volatile-tubiguw4: Waiting for transfers to finish
2025/10/01 04:11:33 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt
2025/10/01 04:11:33 INFO  : dummy.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:33 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt
2025/10/01 04:11:33 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:33 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK
2025/10/01 04:11:33 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:33 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt
2025/10/01 04:11:33 INFO  : file10.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt
2025/10/01 04:11:33 INFO  : file11.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt
2025/10/01 04:11:33 INFO  : file2.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK
2025/10/01 04:11:33 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt
2025/10/01 04:11:33 INFO  : file5L.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK
2025/10/01 04:11:33 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt
2025/10/01 04:11:33 INFO  : file5R.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:33 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/01 04:11:33 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt
2025/10/01 04:11:33 INFO  : file6.txt: Copied (new)
2025/10/01 04:11:33 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt
2025/10/01 04:11:33 INFO  : file7.txt: Copied (new)
2025/10/01 04:11:33 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path1: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:34 INFO  : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path2: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:35 NOTICE: checking initFs Local file system at /tmp/040859le/initdir/test_volatile-dukixox0
2025/10/01 04:11:35 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:35 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path1: Waiting for checks to finish
2025/10/01 04:11:35 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path1: Waiting for transfers to finish
2025/10/01 04:11:36 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:36 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:36 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:36 INFO  : file2.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file5.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file4.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:11:37 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:37 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file6.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file8.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file7.txt: Copied (new)
2025/10/01 04:11:37 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:37 INFO  : file3.txt: Copied (new)
2025/10/01 04:11:37 NOTICE: checking Path1 B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path1
2025/10/01 04:11:38 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/01 04:11:38 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path2: Waiting for checks to finish
2025/10/01 04:11:38 DEBUG : B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path2: Waiting for transfers to finish
2025/10/01 04:11:39 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file1.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file2.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file3.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file6.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file4.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file5.txt: Copied (new)
2025/10/01 04:11:39 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/01 04:11:39 INFO  : RCLONE_TEST: Copied (new)
2025/10/01 04:11:39 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:39 INFO  : file7.txt: Copied (new)
2025/10/01 04:11:40 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/01 04:11:40 INFO  : file8.txt: Copied (new)
2025/10/01 04:11:40 NOTICE: checking path2 B2 bucket rclone-test-toqikoj8hiru path 040859le/volatile/path2
2025/10/01 04:11:40 NOTICE: (01)  : test volatile
2025/10/01 04:11:40 NOTICE: (02)  : test initial bisync
2025/10/01 04:11:40 NOTICE: (03)  : bisync resync
    bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files
2025/10/01 04:11:40 DEBUG : B2 bucket rclone-test-toqikoj8hiru: Purge remote
2025/10/01 04:11:40 INFO  : B2 bucket rclone-test-toqikoj8hiru: cleaning bucket "rclone-test-toqikoj8hiru" of all files
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df8592c_d20251001_m040910_c001_v7007000_t0000_u01759291750616")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112c09436256f425_d20251001_m040907_c001_v0001172_t0042_u01759291747088")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df8592f_d20251001_m040910_c001_v7007000_t0000_u01759291750800")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112ef1e566babf6a_d20251001_m040906_c001_v0001183_t0008_u01759291746523")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85930_d20251001_m040910_c001_v7007000_t0000_u01759291750983")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1117b1549ba345bf_d20251001_m040906_c001_v0001120_t0046_u01759291746698")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85932_d20251001_m040911_c001_v7007000_t0000_u01759291751242")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11288d2be34b6055_d20251001_m040906_c001_v0001109_t0035_u01759291746897")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85933_d20251001_m040911_c001_v7007000_t0000_u01759291751436")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112ef1e566babf6e_d20251001_m040906_c001_v0001183_t0023_u01759291746731")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85935_d20251001_m040911_c001_v7007000_t0000_u01759291751675")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112ef1e566babf74_d20251001_m040906_c001_v0001183_t0019_u01759291746952")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file6.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85938_d20251001_m040911_c001_v7007000_t0000_u01759291751859")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file6.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1117b1549ba345c3_d20251001_m040907_c001_v0001120_t0024_u01759291747154")
2025/10/01 04:11:41 DEBUG : 040859le/resync/path1/file7.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f411bd6689df85939_d20251001_m040912_c001_v7007000_t0000_u01759291752042")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path1/file7.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f112ef1e566babf76_d20251001_m040907_c001_v0001183_t0043_u01759291747171")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1126c0dbfb4c4377_d20251001_m040909_c001_v0001041_t0055_u01759291749232")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10384d948cd889b9_d20251001_m040908_c001_v0001038_t0056_u01759291748632")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10636f205feb87ae_d20251001_m040909_c001_v0001093_t0037_u01759291749017")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f116c67cc917cd40b_d20251001_m040908_c001_v0001165_t0023_u01759291748846")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10384d948cd889bd_d20251001_m040909_c001_v0001038_t0040_u01759291748999")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f116c67cc917cd410_d20251001_m040909_c001_v0001165_t0013_u01759291749091")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file6.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f116c67cc917cd414_d20251001_m040909_c001_v0001165_t0033_u01759291749366")
2025/10/01 04:11:42 DEBUG : 040859le/resync/path2/file7.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10636f205feb87b0_d20251001_m040909_c001_v0001093_t0058_u01759291749413")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f113e1986700f5f67_d20251001_m041128_c001_v0001040_t0022_u01759291888727")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.copy1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1127f26508b29d5d_d20251001_m041128_c001_v0001113_t0003_u01759291888526")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.copy2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1087bd2458ee97c0_d20251001_m041128_c001_v0001171_t0058_u01759291888353")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.copy3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f106ec0f9c673b234_d20251001_m041128_c001_v0001183_t0045_u01759291888910")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.copy4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1087bd2458ee97c4_d20251001_m041128_c001_v0001171_t0038_u01759291888754")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.copy5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1087bd2458ee97ca_d20251001_m041129_c001_v0001171_t0017_u01759291889052")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1127f26508b29d65_d20251001_m041129_c001_v0001113_t0001_u01759291889101")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path1/subdir/file20.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f106ec0f9c673b238_d20251001_m041129_c001_v0001183_t0022_u01759291889113")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path2/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f11413c008b07badd_d20251001_m041130_c001_v0001171_t0045_u01759291890723")
2025/10/01 04:11:42 DEBUG : 040859le/rmdirs/path2/file1.copy1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10321e053af4a053_d20251001_m041130_c001_v0001181_t0002_u01759291890332")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/file1.copy2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1077bf86cf499130_d20251001_m041130_c001_v0001120_t0005_u01759291890532")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/file1.copy3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f110558294cddf6d1_d20251001_m041130_c001_v0001179_t0057_u01759291890910")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/file1.copy4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10321e053af4a057_d20251001_m041130_c001_v0001181_t0002_u01759291890553")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/file1.copy5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10321e053af4a05b_d20251001_m041130_c001_v0001181_t0036_u01759291890763")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10321e053af4a05d_d20251001_m041130_c001_v0001181_t0000_u01759291890975")
2025/10/01 04:11:43 DEBUG : 040859le/rmdirs/path2/subdir/file20.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f110558294cddf6d7_d20251001_m041131_c001_v0001179_t0048_u01759291891129")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111c4e7a08b114ba_d20251001_m041137_c001_v0001105_t0004_u01759291897016")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1111742f6fe01fdf_d20251001_m041136_c001_v0001040_t0023_u01759291896453")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f104d0b339340a7e9_d20251001_m041136_c001_v0001105_t0055_u01759291896640")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f10222fdd6c17255b_d20251001_m041136_c001_v0001104_t0025_u01759291896845")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1111742f6fe01fe3_d20251001_m041136_c001_v0001040_t0058_u01759291896857")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f104d0b339340a7eb_d20251001_m041136_c001_v0001105_t0055_u01759291896891")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file6.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f104d0b339340a7f1_d20251001_m041137_c001_v0001105_t0026_u01759291897164")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file7.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1111742f6fe01fe7_d20251001_m041137_c001_v0001040_t0051_u01759291897200")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path1/file8.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f111c4e7a08b114bc_d20251001_m041137_c001_v0001105_t0035_u01759291897267")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/RCLONE_TEST: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f100b39f1d0097b1a_d20251001_m041139_c001_v0001032_t0019_u01759291899402")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/file1.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1036bd988652e585_d20251001_m041138_c001_v0001093_t0012_u01759291898828")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/file2.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f103c018430048a54_d20251001_m041139_c001_v0001105_t0014_u01759291899006")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/file3.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1069e441dd2235e4_d20251001_m041139_c001_v0001184_t0040_u01759291899209")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/file4.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1036bd988652e587_d20251001_m041139_c001_v0001093_t0017_u01759291899238")
2025/10/01 04:11:43 DEBUG : 040859le/volatile/path2/file5.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f103c018430048a59_d20251001_m041139_c001_v0001105_t0049_u01759291899370")
2025/10/01 04:11:44 DEBUG : 040859le/volatile/path2/file6.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1069e441dd2235ec_d20251001_m041139_c001_v0001184_t0014_u01759291899419")
2025/10/01 04:11:44 DEBUG : 040859le/volatile/path2/file7.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1069e441dd2235f0_d20251001_m041139_c001_v0001184_t0030_u01759291899681")
2025/10/01 04:11:44 DEBUG : 040859le/volatile/path2/file8.txt: Deleting (id "4_zcfa29d23f05d9bdb9f980e1b_f1036bd988652e589_d20251001_m041139_c001_v0001093_t0015_u01759291899734")
--- FAIL: TestBisyncRemoteRemote (166.34s)
    --- FAIL: TestBisyncRemoteRemote/resync (13.02s)
    --- PASS: TestBisyncRemoteRemote/resync_modes (131.02s)
    --- SKIP: TestBisyncRemoteRemote/rmdirs (8.18s)
    --- SKIP: TestBisyncRemoteRemote/volatile (8.87s)
FAIL
2025/10/01 04:11:44 DEBUG : B2 bucket rclone-test-pizetob2mune: Purge remote
2025/10/01 04:11:44 INFO  : B2 bucket rclone-test-pizetob2mune: cleaning bucket "rclone-test-pizetob2mune" of all files
2025/10/01 04:11:45 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished ERROR in 2m48.193668309s (try 2/5): exit status 1: Failed [TestBisyncRemoteRemote/resync]