"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^max_delete_path1$|^TestBisyncRemoteRemote$/^rclone_args$'" - Starting (try 3/5)
2025/10/11 03:57:49 DEBUG : Creating backend with remote "TestB2:rclone-test-berevey7qiha"
2025/10/11 03:57:49 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/10/11 03:57:49 DEBUG : Creating backend with remote "/tmp/rclone3970392291"
=== RUN   TestBisyncLocalRemote
2025/10/11 03:57:49 DEBUG : Creating backend with remote "TestB2:rclone-test-jibizez7vaqe"
2025/10/11 03:57:50 NOTICE: remote: TestB2:rclone-test-jibizez7vaqe
=== RUN   TestBisyncLocalRemote/max_delete_path1
2025/10/11 03:57:50 DEBUG : Creating backend with remote "/tmp/035750ji"
2025/10/11 03:57:50 DEBUG : Creating backend with remote "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:50 DEBUG : Creating backend with remote "TestB2:rclone-test-jibizez7vaqe/035750ji"
2025/10/11 03:57:51 INFO  : path2: Making directory
2025/10/11 03:57:52 DEBUG : Creating backend with remote "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2"
2025/10/11 03:57:53 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/initial"
2025/10/11 03:57:53 DEBUG : Creating backend with remote "/tmp/035750ji/initdir/test_max_delete_path1-mezudor1"
2025/10/11 03:57:53 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : Local file system at /tmp/035750ji/initdir/test_max_delete_path1-mezudor1: Waiting for checks to finish
2025/10/11 03:57:53 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : Local file system at /tmp/035750ji/initdir/test_max_delete_path1-mezudor1: Waiting for transfers to finish
2025/10/11 03:57:53 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/11 03:57:53 INFO  : file1.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/11 03:57:53 INFO  : file3.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/11 03:57:53 INFO  : file5.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/11 03:57:53 INFO  : file6.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:53 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/10/11 03:57:53 INFO  : file8.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/11 03:57:53 INFO  : file2.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/11 03:57:53 INFO  : file7.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/11 03:57:53 INFO  : file4.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/11 03:57:53 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2025/10/11 03:57:53 INFO  : file9.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_max_delete_path1/modfiles"
2025/10/11 03:57:53 DEBUG : Creating backend with remote "/tmp/035750ji/datadir/test_max_delete_path1-kubotok5"
2025/10/11 03:57:53 DEBUG : hold.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:53 DEBUG : Local file system at /tmp/035750ji/datadir/test_max_delete_path1-kubotok5: Waiting for checks to finish
2025/10/11 03:57:53 DEBUG : Local file system at /tmp/035750ji/datadir/test_max_delete_path1-kubotok5: Waiting for transfers to finish
2025/10/11 03:57:53 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK
2025/10/11 03:57:53 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt
2025/10/11 03:57:53 INFO  : hold.txt: Copied (new)
2025/10/11 03:57:53 DEBUG : Waiting for deletions to finish
2025/10/11 03:57:53 ERROR : error listing: directory not found
2025/10/11 03:57:53 ERROR : Local file system at /tmp/035750ji/max_delete_path1/path1: Failed to list "": directory not found
2025/10/11 03:57:53 DEBUG : removing 1 level 0 directories
2025/10/11 03:57:53 INFO  : Local file system at /tmp/035750ji/max_delete_path1/path1: Removing directory
2025/10/11 03:57:53 ERROR : Failed to rmdir: stat /tmp/035750ji/max_delete_path1/path1: no such file or directory
2025/10/11 03:57:53 INFO  : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: cleaning bucket "rclone-test-jibizez7vaqe" of all files
2025/10/11 03:57:54 ERROR : error listing: directory not found
2025/10/11 03:57:54 NOTICE: checking initFs Local file system at /tmp/035750ji/initdir/test_max_delete_path1-mezudor1
2025/10/11 03:57:54 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : Local file system at /tmp/035750ji/max_delete_path1/path1: Waiting for checks to finish
2025/10/11 03:57:54 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : Local file system at /tmp/035750ji/max_delete_path1/path1: Waiting for transfers to finish
2025/10/11 03:57:54 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/11 03:57:54 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 INFO  : file2.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt
2025/10/11 03:57:54 INFO  : file3.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt
2025/10/11 03:57:54 INFO  : file1.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt
2025/10/11 03:57:54 INFO  : file5.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt
2025/10/11 03:57:54 INFO  : file4.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt
2025/10/11 03:57:54 INFO  : file6.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt
2025/10/11 03:57:54 INFO  : file7.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:57:54 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt
2025/10/11 03:57:54 INFO  : file8.txt: Copied (new)
2025/10/11 03:57:54 DEBUG : file9.txt: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/11 03:57:54 DEBUG : file9.txt.84a50ab9.partial: renamed to: file9.txt
2025/10/11 03:57:54 INFO  : file9.txt: Copied (new)
2025/10/11 03:57:54 NOTICE: checking Path1 Local file system at /tmp/035750ji/max_delete_path1/path1
2025/10/11 03:57:54 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file3.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file4.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file5.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file6.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file7.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file8.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : file9.txt: Need to transfer - File not found at Destination
2025/10/11 03:57:54 DEBUG : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: Waiting for checks to finish
2025/10/11 03:57:54 DEBUG : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: Waiting for transfers to finish
2025/10/11 03:57:55 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file2.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file3.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file5.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file1.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file6.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file7.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file8.txt: Copied (new)
2025/10/11 03:57:55 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:57:55 INFO  : file4.txt: Copied (new)
2025/10/11 03:57:56 DEBUG : file9.txt: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/11 03:57:56 INFO  : file9.txt: Copied (new)
2025/10/11 03:57:56 NOTICE: checking path2 B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2
2025/10/11 03:57:56 NOTICE: (01)  : test max-delete-path1
2025/10/11 03:57:56 NOTICE: (02)  : test initial bisync
2025/10/11 03:57:56 NOTICE: (03)  : bisync resync
2025/10/11 03:57:59 DEBUG : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:57:59 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 03:57:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/11 03:57:59 INFO  : Synching Path1 "/tmp/035750ji/max_delete_path1/path1/" with Path2 "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/"
2025/10/11 03:57:59 INFO  : Copying Path2 files to Path1
2025/10/11 03:57:59 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/11 03:57:59 INFO  : There was nothing to transfer
2025/10/11 03:57:59 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/11 03:57:59 INFO  : There was nothing to transfer
2025/10/11 03:57:59 INFO  : Resync updating listings
2025/10/11 03:57:59 INFO  : Validating listings for Path1 "/tmp/035750ji/max_delete_path1/path1/" vs Path2 "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/"
2025/10/11 03:57:59 INFO  : Bisync successful
2025/10/11 03:57:59 NOTICE: (04)  : test delete >50% of local files
2025/10/11 03:57:59 NOTICE: (05)  : delete-file /tmp/035750ji/max_delete_path1/path1/file1.txt
2025/10/11 03:57:59 DEBUG : Creating backend with remote "/tmp/035750ji/max_delete_path1/path1/"
2025/10/11 03:57:59 DEBUG : fs cache: renaming cache item "/tmp/035750ji/max_delete_path1/path1/" to be canonical "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:59 INFO  : file1.txt: Deleted
2025/10/11 03:57:59 NOTICE: (06)  : delete-file /tmp/035750ji/max_delete_path1/path1/file2.txt
2025/10/11 03:57:59 DEBUG : fs cache: switching user supplied name "/tmp/035750ji/max_delete_path1/path1/" for canonical name "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:59 INFO  : file2.txt: Deleted
2025/10/11 03:57:59 NOTICE: (07)  : delete-file /tmp/035750ji/max_delete_path1/path1/file3.txt
2025/10/11 03:57:59 DEBUG : fs cache: switching user supplied name "/tmp/035750ji/max_delete_path1/path1/" for canonical name "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:59 INFO  : file3.txt: Deleted
2025/10/11 03:57:59 NOTICE: (08)  : delete-file /tmp/035750ji/max_delete_path1/path1/file4.txt
2025/10/11 03:57:59 DEBUG : fs cache: switching user supplied name "/tmp/035750ji/max_delete_path1/path1/" for canonical name "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:59 INFO  : file4.txt: Deleted
2025/10/11 03:57:59 NOTICE: (09)  : delete-file /tmp/035750ji/max_delete_path1/path1/file5.txt
2025/10/11 03:57:59 DEBUG : fs cache: switching user supplied name "/tmp/035750ji/max_delete_path1/path1/" for canonical name "/tmp/035750ji/max_delete_path1/path1"
2025/10/11 03:57:59 INFO  : file5.txt: Deleted
2025/10/11 03:57:59 NOTICE: (10)  : test sync should fail due to too many local deletes
2025/10/11 03:57:59 NOTICE: (11)  : bisync
2025/10/11 03:58:02 DEBUG : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:02 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 03:58:02 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/11 03:58:02 INFO  : Synching Path1 "/tmp/035750ji/max_delete_path1/path1/" with Path2 "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/"
2025/10/11 03:58:02 INFO  : Building Path1 and Path2 listings
2025/10/11 03:58:02 INFO  : Path1 checking for diffs
2025/10/11 03:58:02 INFO  : - Path1    File was deleted          - file1.txt
2025/10/11 03:58:02 INFO  : - Path1    File was deleted          - file2.txt
2025/10/11 03:58:02 INFO  : - Path1    File was deleted          - file3.txt
2025/10/11 03:58:02 INFO  : - Path1    File was deleted          - file4.txt
2025/10/11 03:58:02 INFO  : - Path1    File was deleted          - file5.txt
2025/10/11 03:58:02 INFO  : Path1:    5 changes:    0 new,    0 modified,    5 deleted
2025/10/11 03:58:02 INFO  : Path2 checking for diffs
2025/10/11 03:58:02 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path1 "/tmp/035750ji/max_delete_path1/path1/". Run with --force if desired.
2025/10/11 03:58:02 NOTICE: Bisync aborted. Please try again.
2025/10/11 03:58:02 NOTICE: Bisync error: too many deletes
2025/10/11 03:58:02 NOTICE: (12)  : copy-listings initial-fail
2025/10/11 03:58:02 NOTICE: (13)  : test change max-delete limit to 60%. sync should run.
2025/10/11 03:58:02 NOTICE: (14)  : bisync max-delete=60
2025/10/11 03:58:06 DEBUG : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:06 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 03:58:06 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": true,
	"DownloadHash": false
}
2025/10/11 03:58:06 INFO  : Synching Path1 "/tmp/035750ji/max_delete_path1/path1/" with Path2 "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/"
2025/10/11 03:58:06 INFO  : Building Path1 and Path2 listings
2025/10/11 03:58:06 INFO  : Path1 checking for diffs
2025/10/11 03:58:06 INFO  : - Path1    File was deleted          - file1.txt
2025/10/11 03:58:06 INFO  : - Path1    File was deleted          - file2.txt
2025/10/11 03:58:06 INFO  : - Path1    File was deleted          - file3.txt
2025/10/11 03:58:06 INFO  : - Path1    File was deleted          - file4.txt
2025/10/11 03:58:06 INFO  : - Path1    File was deleted          - file5.txt
2025/10/11 03:58:06 INFO  : Path1:    5 changes:    0 new,    0 modified,    5 deleted
2025/10/11 03:58:06 INFO  : Path2 checking for diffs
2025/10/11 03:58:06 INFO  : Applying changes
2025/10/11 03:58:06 INFO  : - Path2    Queue delete              - TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/file1.txt
2025/10/11 03:58:06 INFO  : - Path2    Queue delete              - TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/file2.txt
2025/10/11 03:58:06 INFO  : - Path2    Queue delete              - TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/file3.txt
2025/10/11 03:58:06 INFO  : - Path2    Queue delete              - TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/file4.txt
2025/10/11 03:58:06 INFO  : - Path2    Queue delete              - TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/file5.txt
2025/10/11 03:58:06 INFO  : - Path1    Do queued copies to                - Path2
2025/10/11 03:58:06 INFO  : file1.txt: Deleted
2025/10/11 03:58:06 INFO  : file2.txt: Deleted
2025/10/11 03:58:06 INFO  : file3.txt: Deleted
2025/10/11 03:58:06 INFO  : file4.txt: Deleted
2025/10/11 03:58:06 INFO  : file5.txt: Deleted
2025/10/11 03:58:06 INFO  : There was nothing to transfer
2025/10/11 03:58:06 INFO  : Updating listings
2025/10/11 03:58:06 INFO  : Validating listings for Path1 "/tmp/035750ji/max_delete_path1/path1/" vs Path2 "TestB2:rclone-test-jibizez7vaqe/035750ji/max_delete_path1/path2/"
2025/10/11 03:58:06 INFO  : Bisync successful
2025/10/11 03:58:06 DEBUG : Waiting for deletions to finish
2025/10/11 03:58:06 INFO  : file6.txt: Deleted
2025/10/11 03:58:06 INFO  : file9.txt: Deleted
2025/10/11 03:58:06 INFO  : file8.txt: Deleted
2025/10/11 03:58:06 INFO  : file7.txt: Deleted
2025/10/11 03:58:06 DEBUG : removing 1 level 0 directories
2025/10/11 03:58:06 INFO  : Local file system at /tmp/035750ji/max_delete_path1/path1: Removing directory
2025/10/11 03:58:06 INFO  : B2 bucket rclone-test-jibizez7vaqe path 035750ji/max_delete_path1/path2: cleaning bucket "rclone-test-jibizez7vaqe" of all files
2025/10/11 03:58:07 DEBUG : file1.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b71277b_d20251011_m035806_c001_v7007000_t0000_u01760155086478")
2025/10/11 03:58:07 DEBUG : file1.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1007a93f1412ce18_d20251011_m035755_c001_v0001150_t0052_u01760155075305")
2025/10/11 03:58:07 DEBUG : file2.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b71277c_d20251011_m035806_c001_v7007000_t0000_u01760155086662")
2025/10/11 03:58:07 DEBUG : file2.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f113256d0f01ab06d_d20251011_m035755_c001_v0001184_t0026_u01760155075111")
2025/10/11 03:58:07 DEBUG : file3.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b71277d_d20251011_m035806_c001_v7007000_t0000_u01760155086846")
2025/10/11 03:58:07 DEBUG : file3.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1055e2a82769a5a8_d20251011_m035755_c001_v0001184_t0029_u01760155075495")
2025/10/11 03:58:07 DEBUG : file4.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f415fb2332666a091_d20251011_m035806_c001_v7007000_t0000_u01760155086853")
2025/10/11 03:58:07 DEBUG : file4.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f10329c83682dacb7_d20251011_m035755_c001_v0001130_t0022_u01760155075681")
2025/10/11 03:58:07 DEBUG : file5.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f4192e54f0f814de4_d20251011_m035806_c001_v7007000_t0000_u01760155086868")
2025/10/11 03:58:07 DEBUG : file5.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f113256d0f01ab06f_d20251011_m035755_c001_v0001184_t0018_u01760155075328")
2025/10/11 03:58:07 DEBUG : file6.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1055e2a82769a5ac_d20251011_m035755_c001_v0001184_t0015_u01760155075691")
2025/10/11 03:58:07 DEBUG : file7.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f113256d0f01ab073_d20251011_m035755_c001_v0001184_t0007_u01760155075729")
2025/10/11 03:58:07 DEBUG : file8.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1007a93f1412ce1a_d20251011_m035755_c001_v0001150_t0021_u01760155075742")
2025/10/11 03:58:07 DEBUG : file9.txt: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1055e2a82769a5b2_d20251011_m035755_c001_v0001184_t0005_u01760155075888")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b712779_d20251011_m035805_c001_v7007000_t0000_u01760155085912")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f115580d1c8dd7713_d20251011_m035804_c001_v0001130_t0026_u01760155084446")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f10329c83682dacc6_d20251011_m035802_c001_v0001130_t0059_u01760155082904")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b712772_d20251011_m035802_c001_v7007000_t0000_u01760155082153")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f114097d89f03286b_d20251011_m035800_c001_v0001180_t0021_u01760155080949")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f1007a93f1412ce24_d20251011_m035759_c001_v0001150_t0029_u01760155079492")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f400166412b712768_d20251011_m035758_c001_v7007000_t0000_u01760155078916")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f103c1c95473ade90_d20251011_m035757_c001_v0001177_t0002_u01760155077695")
2025/10/11 03:58:07 DEBUG : modtime_write_test: Deleting (id "4_zaf22fd5360addb1b9f980e1b_f113256d0f01ab077_d20251011_m035756_c001_v0001184_t0054_u01760155076293")
    bisync_test.go:608: TEST max_delete_path1 PASSED
2025/10/11 03:58:08 DEBUG : B2 bucket rclone-test-jibizez7vaqe: Purge remote
2025/10/11 03:58:08 INFO  : B2 bucket rclone-test-jibizez7vaqe: cleaning bucket "rclone-test-jibizez7vaqe" of all files
--- PASS: TestBisyncLocalRemote (19.82s)
    --- PASS: TestBisyncLocalRemote/max_delete_path1 (18.03s)
=== RUN   TestBisyncRemoteRemote
2025/10/11 03:58:09 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu"
2025/10/11 03:58:10 NOTICE: remote: TestB2:rclone-test-nazobop3milu
=== RUN   TestBisyncRemoteRemote/rclone_args
2025/10/11 03:58:10 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze"
2025/10/11 03:58:11 INFO  : path1: Making directory
2025/10/11 03:58:12 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1"
2025/10/11 03:58:13 INFO  : path2: Making directory
2025/10/11 03:58:13 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2"
2025/10/11 03:58:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/initial"
2025/10/11 03:58:14 DEBUG : Creating backend with remote "/tmp/035810ze/initdir/test_rclone_args-wahifac4"
2025/10/11 03:58:14 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : subdir: Making directory with metadata
2025/10/11 03:58:14 INFO  : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z)
2025/10/11 03:58:14 DEBUG : Added delayed dir = "subdir", newDst=subdir
2025/10/11 03:58:14 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:58:14 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt
2025/10/11 03:58:14 INFO  : file2.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:58:14 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt
2025/10/11 03:58:14 INFO  : subdir/file20.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : subdir/file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:58:14 DEBUG : subdir/file21.txt.8fb30fba.partial: renamed to: subdir/file21.txt
2025/10/11 03:58:14 INFO  : subdir/file21.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : Local file system at /tmp/035810ze/initdir/test_rclone_args-wahifac4: Waiting for checks to finish
2025/10/11 03:58:14 DEBUG : Local file system at /tmp/035810ze/initdir/test_rclone_args-wahifac4: Waiting for transfers to finish
2025/10/11 03:58:14 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK
2025/10/11 03:58:14 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST
2025/10/11 03:58:14 INFO  : RCLONE_TEST: Copied (new)
2025/10/11 03:58:14 DEBUG : file1.txt: md5 = 06bf632289064f6deae02a9f6f07856c OK
2025/10/11 03:58:14 DEBUG : file1.txt.3d039419.partial: renamed to: file1.txt
2025/10/11 03:58:14 INFO  : file1.txt: Copied (new)
2025/10/11 03:58:14 INFO  : subdir: Set directory modification time (using SetModTime)
2025/10/11 03:58:14 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rclone_args/modfiles"
2025/10/11 03:58:14 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6"
2025/10/11 03:58:14 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file20.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file21.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:14 DEBUG : file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:58:14 DEBUG : file20.txt.fd848222.partial: renamed to: file20.txt
2025/10/11 03:58:14 INFO  : file20.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : file21.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK
2025/10/11 03:58:14 DEBUG : file21.txt.68f456b7.partial: renamed to: file21.txt
2025/10/11 03:58:14 DEBUG : Local file system at /tmp/035810ze/datadir/test_rclone_args-nemebum6: Waiting for checks to finish
2025/10/11 03:58:14 INFO  : file21.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : Local file system at /tmp/035810ze/datadir/test_rclone_args-nemebum6: Waiting for transfers to finish
2025/10/11 03:58:14 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/11 03:58:14 DEBUG : file2.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK
2025/10/11 03:58:14 DEBUG : file1.txt.8c06417f.partial: renamed to: file1.txt
2025/10/11 03:58:14 INFO  : file1.txt: Copied (new)
2025/10/11 03:58:14 DEBUG : file2.txt.d6677e1f.partial: renamed to: file2.txt
2025/10/11 03:58:14 INFO  : file2.txt: Copied (new)
2025/10/11 03:58:14 INFO  : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: cleaning bucket "rclone-test-nazobop3milu" of all files
2025/10/11 03:58:15 INFO  : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: cleaning bucket "rclone-test-nazobop3milu" of all files
2025/10/11 03:58:16 NOTICE: checking initFs Local file system at /tmp/035810ze/initdir/test_rclone_args-wahifac4
2025/10/11 03:58:16 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/11 03:58:16 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:16 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:16 INFO  : subdir: Making directory
2025/10/11 03:58:16 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:16 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:16 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Waiting for checks to finish
2025/10/11 03:58:16 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Waiting for transfers to finish
2025/10/11 03:58:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/11 03:58:17 INFO  : RCLONE_TEST: Copied (new)
2025/10/11 03:58:17 DEBUG : file1.txt: sha1 = b7ee1cdd4f6d4b833b6990ea6977ce970aaa3366 OK
2025/10/11 03:58:17 INFO  : file1.txt: Copied (new)
2025/10/11 03:58:17 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:17 INFO  : file2.txt: Copied (new)
2025/10/11 03:58:17 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:17 INFO  : subdir/file20.txt: Copied (new)
2025/10/11 03:58:17 DEBUG : subdir/file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:17 INFO  : subdir/file21.txt: Copied (new)
2025/10/11 03:58:17 NOTICE: checking Path1 B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1
2025/10/11 03:58:18 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination
2025/10/11 03:58:18 DEBUG : file1.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:18 DEBUG : file2.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:18 INFO  : subdir: Making directory
2025/10/11 03:58:18 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:18 DEBUG : subdir/file21.txt: Need to transfer - File not found at Destination
2025/10/11 03:58:18 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Waiting for checks to finish
2025/10/11 03:58:18 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Waiting for transfers to finish
2025/10/11 03:58:19 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:19 INFO  : file2.txt: Copied (new)
2025/10/11 03:58:19 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK
2025/10/11 03:58:19 INFO  : RCLONE_TEST: Copied (new)
2025/10/11 03:58:19 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:19 INFO  : subdir/file20.txt: Copied (new)
2025/10/11 03:58:19 DEBUG : subdir/file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:19 INFO  : subdir/file21.txt: Copied (new)
2025/10/11 03:58:19 DEBUG : file1.txt: sha1 = b7ee1cdd4f6d4b833b6990ea6977ce970aaa3366 OK
2025/10/11 03:58:19 INFO  : file1.txt: Copied (new)
2025/10/11 03:58:19 NOTICE: checking path2 B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2
2025/10/11 03:58:20 NOTICE: (01)  : test rclone-args
2025/10/11 03:58:20 NOTICE: (02)  : touch-glob 2001-01-02 /tmp/035810ze/datadir/test_rclone_args-nemebum6/ *
2025/10/11 03:58:20 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/"
2025/10/11 03:58:20 DEBUG : fs cache: renaming cache item "/tmp/035810ze/datadir/test_rclone_args-nemebum6/" to be canonical "/tmp/035810ze/datadir/test_rclone_args-nemebum6"
2025/10/11 03:58:20 DEBUG : file21.txt: Set modification time 2001-01-02
2025/10/11 03:58:20 DEBUG : file1.txt: Set modification time 2001-01-02
2025/10/11 03:58:20 DEBUG : file2.txt: Set modification time 2001-01-02
2025/10/11 03:58:20 DEBUG : file20.txt: Set modification time 2001-01-02
2025/10/11 03:58:20 NOTICE: (03)  : test initial bisync
2025/10/11 03:58:20 NOTICE: (04)  : bisync resync checksum
2025/10/11 03:58:28 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:28 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:28 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 2,
	"HashType2": 2,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/10/11 03:58:28 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:58:28 INFO  : Copying Path2 files to Path1
2025/10/11 03:58:28 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/11 03:58:28 INFO  : There was nothing to transfer
2025/10/11 03:58:28 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/11 03:58:28 INFO  : There was nothing to transfer
2025/10/11 03:58:28 INFO  : Resync updating listings
2025/10/11 03:58:28 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:58:28 INFO  : Bisync successful
2025/10/11 03:58:28 NOTICE: (05)  : test place newer files on both paths
2025/10/11 03:58:28 NOTICE: (06)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/
2025/10/11 03:58:28 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" as ""
2025/10/11 03:58:28 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt"
2025/10/11 03:58:28 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/"
2025/10/11 03:58:30 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1" as "file1.txt"
2025/10/11 03:58:30 DEBUG : file1.txt: Modification times differ by -8808h0m0s: 2001-01-02 00:00:00 +0000 UTC, 2000-01-01 00:00:00 +0000 UTC
2025/10/11 03:58:30 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c (Local file system at /tmp/035810ze/datadir/test_rclone_args-nemebum6)
2025/10/11 03:58:30 DEBUG : file1.txt: sha1 = b7ee1cdd4f6d4b833b6990ea6977ce970aaa3366 (B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1)
2025/10/11 03:58:30 DEBUG : file1.txt: sha1 differ
2025/10/11 03:58:31 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:58:31 INFO  : file1.txt: Copied (replaced existing)
2025/10/11 03:58:31 NOTICE: (07)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/
2025/10/11 03:58:31 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" as ""
2025/10/11 03:58:31 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt"
2025/10/11 03:58:31 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:58:32 DEBUG : operations.CopyFile "file2.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2" as "file2.txt"
2025/10/11 03:58:33 DEBUG : file2.txt: Sizes differ (src 19 vs dst 0)
2025/10/11 03:58:34 DEBUG : file2.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:58:34 INFO  : file2.txt: Copied (replaced existing)
2025/10/11 03:58:34 NOTICE: (08)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir
2025/10/11 03:58:34 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir" as ""
2025/10/11 03:58:34 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt"
2025/10/11 03:58:34 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:58:35 DEBUG : operations.CopyFile "file20.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1/subdir" as "file20.txt"
2025/10/11 03:58:36 DEBUG : file20.txt: Modification times differ by -8808h0m0s: 2001-01-02 00:00:00 +0000 UTC, 2000-01-01 00:00:00 +0000 UTC
2025/10/11 03:58:36 DEBUG : file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:37 INFO  : file20.txt: Updated modification time in destination
2025/10/11 03:58:37 DEBUG : file20.txt: Unchanged skipping
2025/10/11 03:58:37 NOTICE: (09)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir
2025/10/11 03:58:37 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir" as ""
2025/10/11 03:58:37 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt"
2025/10/11 03:58:37 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 03:58:38 DEBUG : operations.CopyFile "file21.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2/subdir" as "file21.txt"
2025/10/11 03:58:39 DEBUG : file21.txt: Modification times differ by -8808h0m0s: 2001-01-02 00:00:00 +0000 UTC, 2000-01-01 00:00:00 +0000 UTC
2025/10/11 03:58:39 DEBUG : file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:58:40 INFO  : file21.txt: Updated modification time in destination
2025/10/11 03:58:40 DEBUG : file21.txt: Unchanged skipping
2025/10/11 03:58:40 NOTICE: (10)  : touch-glob 2001-01-02 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/ file1.txt
2025/10/11 03:58:40 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/"
2025/10/11 03:58:41 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" to be canonical "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1"
2025/10/11 03:58:42 DEBUG : file1.txt: Set modification time 2001-01-02
2025/10/11 03:58:42 NOTICE: (11)  : touch-glob 2001-01-02 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file2.txt
2025/10/11 03:58:42 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:58:44 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" to be canonical "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2"
2025/10/11 03:58:44 DEBUG : file2.txt: Set modification time 2001-01-02
2025/10/11 03:58:45 NOTICE: (12)  : touch-glob 2001-01-02 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/ file20.txt
2025/10/11 03:58:45 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/"
2025/10/11 03:58:46 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/" to be canonical "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:58:47 DEBUG : file20.txt: Set modification time 2001-01-02
2025/10/11 03:58:48 NOTICE: (13)  : touch-glob 2001-01-02 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/ file21.txt
2025/10/11 03:58:48 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/"
2025/10/11 03:58:49 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/" to be canonical "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 03:58:50 DEBUG : file21.txt: Set modification time 2001-01-02
2025/10/11 03:58:50 NOTICE: (14)  : test run bisync with custom options
2025/10/11 03:58:50 NOTICE: (15)  : bisync checksum
2025/10/11 03:58:59 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:59 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:58:59 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": true,
	"HashType1": 2,
	"HashType2": 2,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/10/11 03:58:59 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:58:59 INFO  : Building Path1 and Path2 listings
2025/10/11 03:58:59 INFO  : Path1 checking for diffs
2025/10/11 03:58:59 INFO  : - Path1    File changed: hash - file1.txt
2025/10/11 03:58:59 INFO  : Path1:    1 changes:    0 new,    1 modified,    0 deleted
2025/10/11 03:58:59 INFO  : (Modified:    1 hash differs)
2025/10/11 03:58:59 INFO  : Path2 checking for diffs
2025/10/11 03:58:59 INFO  : - Path2    File changed: size (larger), hash - file2.txt
2025/10/11 03:58:59 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/10/11 03:58:59 INFO  : (Modified:    1 larger,    0 smaller,    1 hash differs)
2025/10/11 03:58:59 INFO  : Applying changes
2025/10/11 03:58:59 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/file1.txt
2025/10/11 03:58:59 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/file2.txt
2025/10/11 03:58:59 INFO  : - Path2    Do queued copies to                - Path1
2025/10/11 03:59:00 INFO  : file2.txt: Copied (server-side copy)
2025/10/11 03:59:00 INFO  : - Path1    Do queued copies to                - Path2
2025/10/11 03:59:01 INFO  : file1.txt: Copied (server-side copy)
2025/10/11 03:59:01 INFO  : Updating listings
2025/10/11 03:59:01 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:01 INFO  : Bisync successful
2025/10/11 03:59:01 NOTICE: (16)  : touch-glob 2007-07-23 /tmp/035810ze/datadir/test_rclone_args-nemebum6/ *
2025/10/11 03:59:01 DEBUG : fs cache: switching user supplied name "/tmp/035810ze/datadir/test_rclone_args-nemebum6/" for canonical name "/tmp/035810ze/datadir/test_rclone_args-nemebum6"
2025/10/11 03:59:01 DEBUG : file21.txt: Set modification time 2007-07-23
2025/10/11 03:59:01 DEBUG : file1.txt: Set modification time 2007-07-23
2025/10/11 03:59:01 DEBUG : file2.txt: Set modification time 2007-07-23
2025/10/11 03:59:01 DEBUG : file20.txt: Set modification time 2007-07-23
2025/10/11 03:59:01 NOTICE: (17)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/
2025/10/11 03:59:01 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" as ""
2025/10/11 03:59:01 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt"
2025/10/11 03:59:01 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/"
2025/10/11 03:59:02 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1" as "file1.txt"
2025/10/11 03:59:02 DEBUG : file1.txt: Modification times differ by -57432h0m0s: 2007-07-23 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC
2025/10/11 03:59:02 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:59:03 INFO  : file1.txt: Updated modification time in destination
2025/10/11 03:59:03 DEBUG : file1.txt: Unchanged skipping
2025/10/11 03:59:03 NOTICE: (18)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/
2025/10/11 03:59:03 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" as ""
2025/10/11 03:59:03 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt"
2025/10/11 03:59:03 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:04 DEBUG : operations.CopyFile "file2.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2" as "file2.txt"
2025/10/11 03:59:04 DEBUG : file2.txt: Modification times differ by -57432h0m0s: 2007-07-23 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC
2025/10/11 03:59:04 DEBUG : file2.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:59:06 INFO  : file2.txt: Updated modification time in destination
2025/10/11 03:59:06 DEBUG : file2.txt: Unchanged skipping
2025/10/11 03:59:06 NOTICE: (19)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir
2025/10/11 03:59:06 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir" as ""
2025/10/11 03:59:06 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt"
2025/10/11 03:59:06 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:59:07 DEBUG : operations.CopyFile "file20.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1/subdir" as "file20.txt"
2025/10/11 03:59:07 DEBUG : file20.txt: Modification times differ by -57432h0m0s: 2007-07-23 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC
2025/10/11 03:59:07 DEBUG : file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:59:09 INFO  : file20.txt: Updated modification time in destination
2025/10/11 03:59:09 DEBUG : file20.txt: Unchanged skipping
2025/10/11 03:59:09 NOTICE: (20)  : copy-as /tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file1.txt
2025/10/11 03:59:09 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" as "file1.txt"
2025/10/11 03:59:09 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt"
2025/10/11 03:59:09 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:10 DEBUG : operations.CopyFile "file21.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2" as "file1.txt"
2025/10/11 03:59:10 DEBUG : file21.txt: Sizes differ (src 0 vs dst 19)
2025/10/11 03:59:12 DEBUG : file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:59:12 INFO  : file21.txt: Copied (replaced existing) to: file1.txt
2025/10/11 03:59:12 NOTICE: (21)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/ file1.txt
2025/10/11 03:59:12 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1"
2025/10/11 03:59:12 DEBUG : file1.txt: Set modification time 2007-07-23
2025/10/11 03:59:13 NOTICE: (22)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file2.txt
2025/10/11 03:59:13 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2"
2025/10/11 03:59:14 DEBUG : file2.txt: Set modification time 2007-07-23
2025/10/11 03:59:14 NOTICE: (23)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/ file20.txt
2025/10/11 03:59:14 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:59:15 DEBUG : file20.txt: Set modification time 2007-07-23
2025/10/11 03:59:16 NOTICE: (24)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/ file21.txt
2025/10/11 03:59:16 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 03:59:16 DEBUG : file21.txt: Set modification time 2007-07-23
2025/10/11 03:59:17 NOTICE: (25)  : bisync size-only
2025/10/11 03:59:26 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:59:26 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:59:26 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 03:59:26 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": false,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/11 03:59:26 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:26 INFO  : Building Path1 and Path2 listings
2025/10/11 03:59:26 INFO  : Path1 checking for diffs
2025/10/11 03:59:26 INFO  : Path2 checking for diffs
2025/10/11 03:59:26 INFO  : - Path2    File changed: size (smaller) - file1.txt
2025/10/11 03:59:26 INFO  : Path2:    1 changes:    0 new,    1 modified,    0 deleted
2025/10/11 03:59:26 INFO  : (Modified:    0 larger,    1 smaller)
2025/10/11 03:59:26 INFO  : Applying changes
2025/10/11 03:59:26 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/file1.txt
2025/10/11 03:59:26 INFO  : - Path2    Do queued copies to                - Path1
2025/10/11 03:59:26 INFO  : file1.txt: Copied (server-side copy)
2025/10/11 03:59:26 INFO  : Updating listings
2025/10/11 03:59:26 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:26 INFO  : Bisync successful
2025/10/11 03:59:26 NOTICE: (26)  : bisync resync
2025/10/11 03:59:35 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:59:35 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 03:59:35 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 03:59:35 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/11 03:59:35 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:35 INFO  : Copying Path2 files to Path1
2025/10/11 03:59:35 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/11 03:59:35 INFO  : There was nothing to transfer
2025/10/11 03:59:35 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/11 03:59:36 INFO  : file2.txt: Updated modification time in destination
2025/10/11 03:59:36 INFO  : subdir/file20.txt: Updated modification time in destination
2025/10/11 03:59:37 INFO  : subdir/file21.txt: Updated modification time in destination
2025/10/11 03:59:37 INFO  : There was nothing to transfer
2025/10/11 03:59:37 INFO  : Resync updating listings
2025/10/11 03:59:37 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:37 INFO  : Bisync successful
2025/10/11 03:59:37 NOTICE: (27)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/
2025/10/11 03:59:37 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" as ""
2025/10/11 03:59:37 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt"
2025/10/11 03:59:37 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/"
2025/10/11 03:59:38 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1" as "file1.txt"
2025/10/11 03:59:38 DEBUG : file1.txt: Sizes differ (src 19 vs dst 0)
2025/10/11 03:59:40 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:59:40 INFO  : file1.txt: Copied (replaced existing)
2025/10/11 03:59:40 NOTICE: (28)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/
2025/10/11 03:59:40 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" as ""
2025/10/11 03:59:40 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file2.txt"
2025/10/11 03:59:40 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 03:59:41 DEBUG : operations.CopyFile "file2.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2" as "file2.txt"
2025/10/11 03:59:41 DEBUG : file2.txt: Modification times differ by -57432h0m0s: 2007-07-23 00:00:00 +0000 UTC, 2001-01-02 00:00:00 +0000 UTC
2025/10/11 03:59:41 DEBUG : file2.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 03:59:43 INFO  : file2.txt: Updated modification time in destination
2025/10/11 03:59:43 DEBUG : file2.txt: Unchanged skipping
2025/10/11 03:59:43 NOTICE: (29)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir
2025/10/11 03:59:43 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir" as ""
2025/10/11 03:59:43 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt"
2025/10/11 03:59:43 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:59:44 DEBUG : operations.CopyFile "file20.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1/subdir" as "file20.txt"
2025/10/11 03:59:44 DEBUG : file20.txt: Size and modification time the same (differ by 0s, within tolerance 1ms)
2025/10/11 03:59:44 DEBUG : file20.txt: Unchanged skipping
2025/10/11 03:59:44 NOTICE: (30)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir
2025/10/11 03:59:44 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir" as ""
2025/10/11 03:59:44 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt"
2025/10/11 03:59:44 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 03:59:45 DEBUG : operations.CopyFile "file21.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2/subdir" as "file21.txt"
2025/10/11 03:59:45 DEBUG : file21.txt: Modification times differ by -66240h0m0s: 2007-07-23 00:00:00 +0000 UTC, 2000-01-01 00:00:00 +0000 UTC
2025/10/11 03:59:45 DEBUG : file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 03:59:47 INFO  : file21.txt: Updated modification time in destination
2025/10/11 03:59:47 DEBUG : file21.txt: Unchanged skipping
2025/10/11 03:59:47 NOTICE: (31)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/ file1.txt
2025/10/11 03:59:47 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1"
2025/10/11 03:59:47 DEBUG : file1.txt: Set modification time 2007-07-23
2025/10/11 03:59:48 NOTICE: (32)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file2.txt
2025/10/11 03:59:48 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2"
2025/10/11 03:59:48 DEBUG : file2.txt: Set modification time 2007-07-23
2025/10/11 03:59:49 NOTICE: (33)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/ file20.txt
2025/10/11 03:59:49 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 03:59:49 DEBUG : file20.txt: Set modification time 2007-07-23
2025/10/11 03:59:50 NOTICE: (34)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/ file21.txt
2025/10/11 03:59:50 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 03:59:50 DEBUG : file21.txt: Set modification time 2007-07-23
2025/10/11 03:59:51 NOTICE: (35)  : bisync ignore-size
2025/10/11 04:00:00 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:00 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:00 INFO  : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set.
2025/10/11 04:00:00 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": false,
	"Checksum": false,
	"HashType1": 0,
	"HashType2": 0,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": false
}
2025/10/11 04:00:00 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:00 INFO  : Building Path1 and Path2 listings
2025/10/11 04:00:00 INFO  : Path1 checking for diffs
2025/10/11 04:00:00 INFO  : Path2 checking for diffs
2025/10/11 04:00:00 INFO  : - Path2    File changed: time (newer) - file2.txt
2025/10/11 04:00:00 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/10/11 04:00:00 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/10/11 04:00:00 INFO  : (Modified:    2 newer,    0 older)
2025/10/11 04:00:00 INFO  : Applying changes
2025/10/11 04:00:00 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/file2.txt
2025/10/11 04:00:00 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/file21.txt
2025/10/11 04:00:00 INFO  : - Path2    Do queued copies to                - Path1
2025/10/11 04:00:01 INFO  : file2.txt: Updated modification time in destination
2025/10/11 04:00:02 INFO  : subdir/file21.txt: Updated modification time in destination
2025/10/11 04:00:02 INFO  : There was nothing to transfer
2025/10/11 04:00:02 INFO  : Updating listings
2025/10/11 04:00:02 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:02 INFO  : Bisync successful
2025/10/11 04:00:02 NOTICE: (36)  : bisync resync compare-all
2025/10/11 04:00:10 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:10 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:10 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 2,
	"HashType2": 2,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/10/11 04:00:10 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:10 INFO  : Copying Path2 files to Path1
2025/10/11 04:00:10 INFO  : - Path2    Resync is copying files to         - Path1
2025/10/11 04:00:10 INFO  : There was nothing to transfer
2025/10/11 04:00:10 INFO  : - Path1    Resync is copying files to         - Path2
2025/10/11 04:00:11 INFO  : file1.txt: Copied (server-side copy)
2025/10/11 04:00:11 INFO  : Resync updating listings
2025/10/11 04:00:11 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:11 INFO  : Bisync successful
2025/10/11 04:00:11 NOTICE: (37)  : copy-as /tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file2.txt
2025/10/11 04:00:11 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" as "file2.txt"
2025/10/11 04:00:11 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt"
2025/10/11 04:00:11 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:12 DEBUG : operations.CopyFile "file21.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2" as "file2.txt"
2025/10/11 04:00:12 DEBUG : file21.txt: Sizes differ (src 0 vs dst 19)
2025/10/11 04:00:14 DEBUG : file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 04:00:14 INFO  : file21.txt: Copied (replaced existing) to: file2.txt
2025/10/11 04:00:14 NOTICE: (38)  : touch-glob 2023-08-26 /tmp/035810ze/datadir/test_rclone_args-nemebum6/ *
2025/10/11 04:00:14 DEBUG : fs cache: switching user supplied name "/tmp/035810ze/datadir/test_rclone_args-nemebum6/" for canonical name "/tmp/035810ze/datadir/test_rclone_args-nemebum6"
2025/10/11 04:00:14 DEBUG : file21.txt: Set modification time 2023-08-26
2025/10/11 04:00:14 DEBUG : file1.txt: Set modification time 2023-08-26
2025/10/11 04:00:14 DEBUG : file2.txt: Set modification time 2023-08-26
2025/10/11 04:00:14 DEBUG : file20.txt: Set modification time 2023-08-26
2025/10/11 04:00:14 NOTICE: (39)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/
2025/10/11 04:00:14 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" as ""
2025/10/11 04:00:14 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file1.txt"
2025/10/11 04:00:14 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/"
2025/10/11 04:00:15 DEBUG : operations.CopyFile "file1.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1" as "file1.txt"
2025/10/11 04:00:15 DEBUG : file1.txt: Modification times differ by -141072h0m0s: 2023-08-26 00:00:00 +0000 UTC, 2007-07-23 00:00:00 +0000 UTC
2025/10/11 04:00:15 DEBUG : file1.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK
2025/10/11 04:00:17 INFO  : file1.txt: Updated modification time in destination
2025/10/11 04:00:17 DEBUG : file1.txt: Unchanged skipping
2025/10/11 04:00:17 NOTICE: (40)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir
2025/10/11 04:00:17 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir" as ""
2025/10/11 04:00:17 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file20.txt"
2025/10/11 04:00:17 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 04:00:18 DEBUG : operations.CopyFile "file20.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1/subdir" as "file20.txt"
2025/10/11 04:00:18 DEBUG : file20.txt: Modification times differ by -141072h0m0s: 2023-08-26 00:00:00 +0000 UTC, 2007-07-23 00:00:00 +0000 UTC
2025/10/11 04:00:18 DEBUG : file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 04:00:20 INFO  : file20.txt: Updated modification time in destination
2025/10/11 04:00:20 DEBUG : file20.txt: Unchanged skipping
2025/10/11 04:00:20 NOTICE: (41)  : copy-file /tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir
2025/10/11 04:00:20 DEBUG : copyFile "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt" to "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir" as ""
2025/10/11 04:00:20 DEBUG : Creating backend with remote "/tmp/035810ze/datadir/test_rclone_args-nemebum6/file21.txt"
2025/10/11 04:00:20 DEBUG : Creating backend with remote "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 04:00:21 DEBUG : operations.CopyFile "file21.txt" to "B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2/subdir" as "file21.txt"
2025/10/11 04:00:21 DEBUG : file21.txt: Modification times differ by -141072h0m0s: 2023-08-26 00:00:00 +0000 UTC, 2007-07-23 00:00:00 +0000 UTC
2025/10/11 04:00:21 DEBUG : file21.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK
2025/10/11 04:00:22 INFO  : file21.txt: Updated modification time in destination
2025/10/11 04:00:22 DEBUG : file21.txt: Unchanged skipping
2025/10/11 04:00:22 NOTICE: (42)  : touch-glob 2007-07-23 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/ file2.txt
2025/10/11 04:00:22 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2"
2025/10/11 04:00:22 DEBUG : file2.txt: Set modification time 2007-07-23
2025/10/11 04:00:23 NOTICE: (43)  : touch-glob 2023-08-26 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/ file1.txt
2025/10/11 04:00:23 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1"
2025/10/11 04:00:23 DEBUG : file1.txt: Set modification time 2023-08-26
2025/10/11 04:00:24 NOTICE: (44)  : touch-glob 2023-08-26 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/ file20.txt
2025/10/11 04:00:24 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir"
2025/10/11 04:00:25 DEBUG : file20.txt: Set modification time 2023-08-26
2025/10/11 04:00:26 NOTICE: (45)  : touch-glob 2023-08-26 TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/ file21.txt
2025/10/11 04:00:26 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/" for canonical name "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir"
2025/10/11 04:00:26 DEBUG : file21.txt: Set modification time 2023-08-26
2025/10/11 04:00:27 NOTICE: (46)  : bisync compare-all
2025/10/11 04:00:36 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:36 DEBUG : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata
2025/10/11 04:00:36 INFO  : Bisyncing with Comparison Settings: 
{
	"Modtime": true,
	"Size": true,
	"Checksum": true,
	"HashType1": 2,
	"HashType2": 2,
	"NoSlowHash": false,
	"SlowHashSyncOnly": false,
	"SlowHashDetected": false,
	"DownloadHash": true
}
2025/10/11 04:00:36 INFO  : Synching Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" with Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:36 INFO  : Building Path1 and Path2 listings
2025/10/11 04:00:36 INFO  : Path1 checking for diffs
2025/10/11 04:00:36 INFO  : - Path1    File changed: time (newer) - file1.txt
2025/10/11 04:00:36 INFO  : - Path1    File changed: time (newer) - subdir/file20.txt
2025/10/11 04:00:36 INFO  : Path1:    2 changes:    0 new,    2 modified,    0 deleted
2025/10/11 04:00:36 INFO  : (Modified:    2 newer,    0 older)
2025/10/11 04:00:36 INFO  : Path2 checking for diffs
2025/10/11 04:00:36 INFO  : - Path2    File changed: size (smaller), hash - file2.txt
2025/10/11 04:00:36 INFO  : - Path2    File changed: time (newer) - subdir/file21.txt
2025/10/11 04:00:36 INFO  : Path2:    2 changes:    0 new,    2 modified,    0 deleted
2025/10/11 04:00:36 INFO  : (Modified:    1 newer,    0 older,    0 larger,    1 smaller,    1 hash differs)
2025/10/11 04:00:36 INFO  : Applying changes
2025/10/11 04:00:36 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/file1.txt
2025/10/11 04:00:36 INFO  : - Path1    Queue copy to Path2       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/subdir/file20.txt
2025/10/11 04:00:36 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/file2.txt
2025/10/11 04:00:36 INFO  : - Path2    Queue copy to Path1       - TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/subdir/file21.txt
2025/10/11 04:00:36 INFO  : - Path2    Do queued copies to                - Path1
2025/10/11 04:00:37 INFO  : file2.txt: Copied (server-side copy)
2025/10/11 04:00:37 INFO  : subdir/file21.txt: Updated modification time in destination
2025/10/11 04:00:37 INFO  : - Path1    Do queued copies to                - Path2
2025/10/11 04:00:38 INFO  : file1.txt: Updated modification time in destination
2025/10/11 04:00:38 INFO  : subdir/file20.txt: Updated modification time in destination
2025/10/11 04:00:38 INFO  : Updating listings
2025/10/11 04:00:38 INFO  : Validating listings for Path1 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path1/" vs Path2 "TestB2:rclone-test-nazobop3milu/035810ze/rclone_args/path2/"
2025/10/11 04:00:38 INFO  : Bisync successful
2025/10/11 04:00:38 INFO  : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path1: cleaning bucket "rclone-test-nazobop3milu" of all files
2025/10/11 04:00:39 DEBUG : RCLONE_TEST: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10420238838183f0_d20251011_m035817_c001_v0001037_t0053_u01760155097247")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11686c44f5f9a874_d20251011_m040024_c001_v0001036_t0001_u01760155224434")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f102cdd9a7441cfac_d20251011_m040016_c001_v0001136_t0032_u01760155216841")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1045d55218382368_d20251011_m035948_c001_v0001179_t0023_u01760155188277")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1131a9a286910162_d20251011_m035940_c001_v0001134_t0000_u01760155180057")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1191923b977f476f_d20251011_m035926_c001_v0001150_t0050_u01760155166682")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f104b735d8a6ee4ad_d20251011_m035913_c001_v0001165_t0058_u01760155153353")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f110db01f3bdbe1c0_d20251011_m035903_c001_v0001183_t0041_u01760155143392")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1041af8f8f9f663e_d20251011_m035842_c001_v0001183_t0028_u01760155122765")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1037fc7d01c32a2f_d20251011_m035831_c001_v0001099_t0057_u01760155111606")
2025/10/11 04:00:39 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1042c4b55128b375_d20251011_m035817_c001_v0001109_t0057_u01760155097440")
2025/10/11 04:00:39 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f106bbedab1285fdc_d20251011_m040037_c001_v0001032_t0049_u01760155237071")
2025/10/11 04:00:39 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10236a1dc1020bdb_d20251011_m040001_c001_v0001181_t0040_u01760155201831")
2025/10/11 04:00:39 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1153595d3ce0009f_d20251011_m035900_c001_v0001102_t0054_u01760155140120")
2025/10/11 04:00:39 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1051570ebcb50fdd_d20251011_m035817_c001_v0001038_t0008_u01760155097061")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f40776c4bb3a0d539_d20251011_m040031_c001_v7007000_t0000_u01760155231758")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f115a3396213bd66f_d20251011_m040030_c001_v0001150_t0030_u01760155230359")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11866727be4cf85b_d20251011_m040027_c001_v0001157_t0028_u01760155227989")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f406528ccdaa9455f_d20251011_m040005_c001_v7007000_t0000_u01760155205939")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10263bfb844c9bee_d20251011_m040004_c001_v0001099_t0043_u01760155204575")
2025/10/11 04:00:39 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1051570ebcb510a2_d20251011_m040002_c001_v0001038_t0029_u01760155202903")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f406528ccdaa94548_d20251011_m035956_c001_v7007000_t0000_u01760155196001")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1059b56ddd97423a_d20251011_m035954_c001_v0001157_t0029_u01760155194664")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1042c4b55128b430_d20251011_m035952_c001_v0001109_t0003_u01760155192219")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f4176f693068ca970_d20251011_m035930_c001_v7007000_t0000_u01760155170983")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11624dca69c2b3e8_d20251011_m035929_c001_v0001038_t0042_u01760155169532")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1042023883818473_d20251011_m035927_c001_v0001037_t0006_u01760155167406")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f4176f693068ca969_d20251011_m035921_c001_v7007000_t0000_u01760155161761")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1191062647ad1b71_d20251011_m035920_c001_v0001146_t0021_u01760155160441")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11866727be4cf684_d20251011_m035918_c001_v0001157_t0001_u01760155158122")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f4131931c2e3162c6_d20251011_m035855_c001_v7007000_t0000_u01760155135231")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f114bdfb662933922_d20251011_m035853_c001_v0001161_t0011_u01760155133779")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1051570ebcb51014_d20251011_m035851_c001_v0001038_t0035_u01760155131422")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f418b4b763a41a746_d20251011_m035823_c001_v7007000_t0000_u01760155103680")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f105ce600f8320c30_d20251011_m035822_c001_v0001098_t0049_u01760155102209")
2025/10/11 04:00:40 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1042c4b55128b379_d20251011_m035820_c001_v0001109_t0032_u01760155100134")
2025/10/11 04:00:40 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1077a5c317539fb0_d20251011_m040026_c001_v0001136_t0046_u01760155226060")
2025/10/11 04:00:40 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f102cdd9a7441cfb4_d20251011_m040019_c001_v0001136_t0055_u01760155219443")
2025/10/11 04:00:40 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f116e2507f8e61fb8_d20251011_m035950_c001_v0001180_t0004_u01760155190312")
2025/10/11 04:00:40 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f102cddb32b4d6b62_d20251011_m035916_c001_v0001171_t0047_u01760155156026")
2025/10/11 04:00:40 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1103e726b87975b9_d20251011_m035908_c001_v0001104_t0034_u01760155148421")
2025/10/11 04:00:41 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1032124418e80a58_d20251011_m035847_c001_v0001134_t0033_u01760155127773")
2025/10/11 04:00:41 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f107cca10d736d399_d20251011_m035837_c001_v0001102_t0046_u01760155117326")
2025/10/11 04:00:41 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11866727be4cf564_d20251011_m035817_c001_v0001157_t0019_u01760155097629")
2025/10/11 04:00:41 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f106b2b5a64d65bb8_d20251011_m040037_c001_v0001165_t0036_u01760155237650")
2025/10/11 04:00:41 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f106d468e42162c15_d20251011_m040002_c001_v0001037_t0058_u01760155202079")
2025/10/11 04:00:41 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10420238838183f2_d20251011_m035817_c001_v0001037_t0038_u01760155097633")
2025/10/11 04:00:41 INFO  : B2 bucket rclone-test-nazobop3milu path 035810ze/rclone_args/path2: cleaning bucket "rclone-test-nazobop3milu" of all files
2025/10/11 04:00:42 DEBUG : RCLONE_TEST: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1189af0d5f66bbd0_d20251011_m035819_c001_v0001106_t0018_u01760155099114")
2025/10/11 04:00:42 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f110fa7a10eb27652_d20251011_m040038_c001_v0001176_t0033_u01760155238276")
2025/10/11 04:00:42 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f111ecac592751790_d20251011_m040010_c001_v0001136_t0036_u01760155210918")
2025/10/11 04:00:42 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1070044cd767fbec_d20251011_m035911_c001_v0001098_t0015_u01760155151772")
2025/10/11 04:00:42 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f118ab9022dfc1de5_d20251011_m035900_c001_v0001171_t0058_u01760155140795")
2025/10/11 04:00:42 DEBUG : file1.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11463cc256311837_d20251011_m035819_c001_v0001120_t0041_u01760155099487")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f105f6b7a03b5de80_d20251011_m040023_c001_v0001172_t0003_u01760155223406")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f116e5e7ad5eba162_d20251011_m040013_c001_v0001116_t0007_u01760155213884")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f114bdfb662933a23_d20251011_m035948_c001_v0001161_t0020_u01760155188879")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f111d3d6ebb1a91df_d20251011_m035943_c001_v0001137_t0018_u01760155183011")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1040c7fcb47efc8a_d20251011_m035936_c001_v0001146_t0049_u01760155176315")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f109b51d0a2a43a1b_d20251011_m035914_c001_v0001180_t0022_u01760155154702")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1157364545d22b8b_d20251011_m035905_c001_v0001184_t0000_u01760155145942")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11597e17bcb0d571_d20251011_m035845_c001_v0001099_t0030_u01760155125231")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f104accc3646a9b4c_d20251011_m035834_c001_v0001032_t0031_u01760155114548")
2025/10/11 04:00:42 DEBUG : file2.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f101d258b58c14739_d20251011_m035818_c001_v0001137_t0057_u01760155098919")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f418b4b763a41a925_d20251011_m040036_c001_v7007000_t0000_u01760155236114")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1088fd30c9b5f6d6_d20251011_m040034_c001_v0001136_t0017_u01760155234759")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f101d258b58c14950_d20251011_m040032_c001_v0001137_t0013_u01760155232316")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f409a3444628e379d_d20251011_m040009_c001_v7007000_t0000_u01760155209979")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1144c297b4f061f5_d20251011_m040008_c001_v0001178_t0005_u01760155208765")
2025/10/11 04:00:42 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1085b0f680a8a593_d20251011_m040006_c001_v0001131_t0055_u01760155206488")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f404fddc32f821722_d20251011_m040000_c001_v7007000_t0000_u01760155200472")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10998b182a0530e5_d20251011_m035959_c001_v0001098_t0020_u01760155199017")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1189af0d5f66be3d_d20251011_m035956_c001_v0001106_t0048_u01760155196550")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f419401ec446d917c_d20251011_m035935_c001_v7007000_t0000_u01760155175347")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10409669755e482e_d20251011_m035933_c001_v0001029_t0024_u01760155173975")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11463cc256311907_d20251011_m035931_c001_v0001120_t0001_u01760155171535")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f4175adbfc6b19502_d20251011_m035925_c001_v7007000_t0000_u01760155165924")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f101c8f0c202a48b0_d20251011_m035924_c001_v0001184_t0043_u01760155164691")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f101d258b58c1480f_d20251011_m035922_c001_v0001137_t0014_u01760155162312")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f4165fb72934ccd1d_d20251011_m035859_c001_v7007000_t0000_u01760155139358")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1191062647ad1b2b_d20251011_m035858_c001_v0001146_t0028_u01760155138008")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1085b0f680a8a480_d20251011_m035855_c001_v0001131_t0030_u01760155135782")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f419401ec446d9124_d20251011_m035828_c001_v7007000_t0000_u01760155108048")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11284495606dd17b_d20251011_m035826_c001_v0001171_t0050_u01760155106768")
2025/10/11 04:00:43 DEBUG : modtime_write_test: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1189af0d5f66bbe8_d20251011_m035823_c001_v0001106_t0045_u01760155103865")
2025/10/11 04:00:43 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f118ab9022dfc20be_d20251011_m040038_c001_v0001171_t0006_u01760155238520")
2025/10/11 04:00:43 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1095bce25164039b_d20251011_m035936_c001_v0001102_t0036_u01760155176636")
2025/10/11 04:00:43 DEBUG : subdir/file20.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1085b0f680a8a403_d20251011_m035819_c001_v0001131_t0003_u01760155099299")
2025/10/11 04:00:43 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11273c95b8a575c4_d20251011_m040027_c001_v0001180_t0029_u01760155227430")
2025/10/11 04:00:43 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f11376014106f2dd4_d20251011_m040022_c001_v0001165_t0016_u01760155222399")
2025/10/11 04:00:43 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f10784b24f05bc495_d20251011_m035951_c001_v0001180_t0024_u01760155191657")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1101615c85f53433_d20251011_m035946_c001_v0001182_t0022_u01760155186948")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f102eafeb33083bc2_d20251011_m035936_c001_v0001144_t0045_u01760155176891")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1061bd18ce091a41_d20251011_m035917_c001_v0001180_t0021_u01760155157535")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1096b85f084a7118_d20251011_m035850_c001_v0001117_t0053_u01760155130488")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f1081d1ca15434f8a_d20251011_m035840_c001_v0001134_t0021_u01760155120223")
2025/10/11 04:00:44 DEBUG : subdir/file21.txt: Deleting (id "4_z4fe24d3360bddb1b9f980e1b_f101d258b58c1473d_d20251011_m035819_c001_v0001137_t0032_u01760155099555")
    bisync_test.go:608: TEST rclone_args PASSED
2025/10/11 04:00:44 DEBUG : B2 bucket rclone-test-nazobop3milu: Purge remote
2025/10/11 04:00:44 INFO  : B2 bucket rclone-test-nazobop3milu: cleaning bucket "rclone-test-nazobop3milu" of all files
--- PASS: TestBisyncRemoteRemote (156.31s)
    --- PASS: TestBisyncRemoteRemote/rclone_args (154.51s)
PASS
2025/10/11 04:00:45 DEBUG : B2 bucket rclone-test-berevey7qiha: Purge remote
2025/10/11 04:00:45 INFO  : B2 bucket rclone-test-berevey7qiha: cleaning bucket "rclone-test-berevey7qiha" of all files
2025/10/11 04:00:46 NOTICE: purge failed: directory not found
"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^max_delete_path1$|^TestBisyncRemoteRemote$/^rclone_args$'" - Finished OK in 2m57.513867192s (try 3/5)