"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^resync$|^TestBisyncRemoteLocal$/^check_access$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5) 2025/09/27 02:00:43 DEBUG : Creating backend with remote "TestB2:rclone-test-jotubah8naro" 2025/09/27 02:00:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/09/27 02:00:44 DEBUG : Creating backend with remote "/tmp/rclone3599936690" === RUN TestBisyncRemoteLocal 2025/09/27 02:00:44 DEBUG : Creating backend with remote "TestB2:rclone-test-xepafob3nike" 2025/09/27 02:00:44 NOTICE: remote: TestB2:rclone-test-xepafob3nike === RUN TestBisyncRemoteLocal/check_access 2025/09/27 02:00:44 DEBUG : Creating backend with remote "TestB2:rclone-test-xepafob3nike/020044we" 2025/09/27 02:00:46 INFO : path1: Making directory 2025/09/27 02:00:46 DEBUG : Creating backend with remote "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1" 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/tmp/020044we" 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/tmp/020044we/check_access/path2" 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/initial" 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/tmp/020044we/initdir/test_check_access-qurekas4" 2025/09/27 02:00:47 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : subdir: Making directory with metadata 2025/09/27 02:00:47 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/27 02:00:47 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/27 02:00:47 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:47 DEBUG : Local file system at /tmp/020044we/initdir/test_check_access-qurekas4: Waiting for checks to finish 2025/09/27 02:00:47 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:47 DEBUG : Local file system at /tmp/020044we/initdir/test_check_access-qurekas4: Waiting for transfers to finish 2025/09/27 02:00:47 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:00:47 INFO : file1.txt: Copied (new) 2025/09/27 02:00:47 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:47 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:00:47 INFO : file2.txt: Copied (new) 2025/09/27 02:00:47 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:00:47 INFO : file3.txt: Copied (new) 2025/09/27 02:00:47 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:47 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:00:47 INFO : file4.txt: Copied (new) 2025/09/27 02:00:47 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:47 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/27 02:00:47 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:00:47 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:00:47 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:00:47 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2025/09/27 02:00:47 INFO : subdir/RCLONE_TEST: Copied (new) 2025/09/27 02:00:47 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:00:47 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:00:47 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access/modfiles" 2025/09/27 02:00:47 DEBUG : Creating backend with remote "/tmp/020044we/datadir/test_check_access-yumubac8" 2025/09/27 02:00:47 DEBUG : hold.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:47 DEBUG : Local file system at /tmp/020044we/datadir/test_check_access-yumubac8: Waiting for checks to finish 2025/09/27 02:00:47 DEBUG : Local file system at /tmp/020044we/datadir/test_check_access-yumubac8: Waiting for transfers to finish 2025/09/27 02:00:47 DEBUG : hold.txt: md5 = 628c2241b6ba4e313ef08bdfcf1cd964 OK 2025/09/27 02:00:47 DEBUG : hold.txt.83686dab.partial: renamed to: hold.txt 2025/09/27 02:00:47 INFO : hold.txt: Copied (new) 2025/09/27 02:00:47 INFO : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: cleaning bucket "rclone-test-xepafob3nike" of all files 2025/09/27 02:00:48 DEBUG : Waiting for deletions to finish 2025/09/27 02:00:48 ERROR : error listing: directory not found 2025/09/27 02:00:48 ERROR : Local file system at /tmp/020044we/check_access/path2: Failed to list "": directory not found 2025/09/27 02:00:48 DEBUG : removing 1 level 0 directories 2025/09/27 02:00:48 INFO : Local file system at /tmp/020044we/check_access/path2: Removing directory 2025/09/27 02:00:48 ERROR : Failed to rmdir: stat /tmp/020044we/check_access/path2: no such file or directory 2025/09/27 02:00:48 ERROR : error listing: directory not found 2025/09/27 02:00:48 NOTICE: checking initFs Local file system at /tmp/020044we/initdir/test_check_access-qurekas4 2025/09/27 02:00:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:49 INFO : subdir: Making directory 2025/09/27 02:00:49 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:49 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Waiting for checks to finish 2025/09/27 02:00:49 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Waiting for transfers to finish 2025/09/27 02:00:49 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:00:49 INFO : file1.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:00:50 INFO : file4.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:00:50 INFO : file2.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : subdir/RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:00:50 INFO : subdir/RCLONE_TEST: Copied (new) 2025/09/27 02:00:50 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:00:50 INFO : file3.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:00:50 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:00:50 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:00:50 NOTICE: checking Path1 B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1 2025/09/27 02:00:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : subdir: Making directory with metadata 2025/09/27 02:00:50 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/27 02:00:50 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/27 02:00:50 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:50 DEBUG : subdir/RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:00:50 DEBUG : Local file system at /tmp/020044we/check_access/path2: Waiting for checks to finish 2025/09/27 02:00:50 DEBUG : Local file system at /tmp/020044we/check_access/path2: Waiting for transfers to finish 2025/09/27 02:00:50 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:00:50 INFO : file1.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:50 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:50 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:00:50 INFO : file2.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:00:50 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:50 INFO : file3.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:00:50 INFO : file4.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:00:50 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/27 02:00:50 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:00:50 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:00:50 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:00:50 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:00:50 DEBUG : subdir/RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:00:50 DEBUG : subdir/RCLONE_TEST.e0d6433d.partial: renamed to: subdir/RCLONE_TEST 2025/09/27 02:00:50 INFO : subdir/RCLONE_TEST: Copied (new) 2025/09/27 02:00:50 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:00:50 NOTICE: checking path2 Local file system at /tmp/020044we/check_access/path2 2025/09/27 02:00:50 NOTICE: (01) : test check-access 2025/09/27 02:00:50 NOTICE: (02) : test initial bisync 2025/09/27 02:00:50 NOTICE: (03) : bisync resync 2025/09/27 02:00:53 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:00:53 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:00:53 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:00:53 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:00:53 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:00:53 INFO : Copying Path2 files to Path1 2025/09/27 02:00:53 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:00:54 INFO : There was nothing to transfer 2025/09/27 02:00:54 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:00:54 INFO : There was nothing to transfer 2025/09/27 02:00:54 INFO : Resync updating listings 2025/09/27 02:00:54 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:00:54 INFO : Bisync successful 2025/09/27 02:00:54 NOTICE: (04) : test 1. see that check-access passes with the initial setup 2025/09/27 02:00:54 NOTICE: (05) : bisync check-access 2025/09/27 02:00:57 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:00:57 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:00:57 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:00:57 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:00:57 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:00:57 INFO : Building Path1 and Path2 listings 2025/09/27 02:00:57 INFO : Path1 checking for diffs 2025/09/27 02:00:57 INFO : Path2 checking for diffs 2025/09/27 02:00:57 INFO : Checking access health 2025/09/27 02:00:57 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/09/27 02:00:57 INFO : No changes found 2025/09/27 02:00:57 INFO : Updating listings 2025/09/27 02:00:57 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:00:57 INFO : Bisync successful 2025/09/27 02:00:57 NOTICE: (06) : test 2. delete the path2 subdir RCLONE_TEST and run sync. should fail critical. 2025/09/27 02:00:57 NOTICE: (07) : delete-file /tmp/020044we/check_access/path2/subdir/RCLONE_TEST 2025/09/27 02:00:57 DEBUG : Creating backend with remote "/tmp/020044we/check_access/path2/subdir/" 2025/09/27 02:00:57 DEBUG : fs cache: renaming cache item "/tmp/020044we/check_access/path2/subdir/" to be canonical "/tmp/020044we/check_access/path2/subdir" 2025/09/27 02:00:57 INFO : RCLONE_TEST: Deleted 2025/09/27 02:00:57 NOTICE: (08) : bisync check-access 2025/09/27 02:01:01 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:01 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:01 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:01 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:01 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:01 INFO : Building Path1 and Path2 listings 2025/09/27 02:01:01 INFO : Path1 checking for diffs 2025/09/27 02:01:01 INFO : Path2 checking for diffs 2025/09/27 02:01:01 INFO : - Path2 File was deleted - subdir/RCLONE_TEST 2025/09/27 02:01:01 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2025/09/27 02:01:01 INFO : Checking access health 2025/09/27 02:01:01 ERROR : Access test failed: Path1 count 2, Path2 count 1 - RCLONE_TEST 2025/09/27 02:01:01 ERROR : -  Access test failed: Path1 file not found in Path2 - subdir/RCLONE_TEST 2025/09/27 02:01:01 ERROR : Bisync critical error: check file check failed 2025/09/27 02:01:01 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/27 02:01:01 NOTICE: Bisync error: bisync aborted 2025/09/27 02:01:01 NOTICE: (09) : copy-listings path2-missing 2025/09/27 02:01:01 NOTICE: (10) : test 3. put the path2 subdir RCLONE_TEST back, resync. 2025/09/27 02:01:01 NOTICE: (11) : copy-file TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/subdir/RCLONE_TEST /tmp/020044we/check_access/path2/ 2025/09/27 02:01:01 DEBUG : copyFile "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/subdir/RCLONE_TEST" to "/tmp/020044we/check_access/path2/" as "" 2025/09/27 02:01:01 DEBUG : Creating backend with remote "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/subdir/RCLONE_TEST" 2025/09/27 02:01:02 DEBUG : Creating backend with remote "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:02 DEBUG : operations.CopyFile "RCLONE_TEST" to "Local file system at /tmp/020044we/check_access/path2" as "RCLONE_TEST" 2025/09/27 02:01:02 DEBUG : RCLONE_TEST: Size and modification time the same (differ by 0s, within tolerance 1ms) 2025/09/27 02:01:02 DEBUG : RCLONE_TEST: Unchanged skipping 2025/09/27 02:01:02 NOTICE: (12) : bisync resync 2025/09/27 02:01:06 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:06 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:06 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01: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/09/27 02:01:06 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:06 INFO : Copying Path2 files to Path1 2025/09/27 02:01:06 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:01:07 INFO : There was nothing to transfer 2025/09/27 02:01:07 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:01:07 INFO : subdir/RCLONE_TEST: Copied (new) 2025/09/27 02:01:07 INFO : Resync updating listings 2025/09/27 02:01:07 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:07 INFO : Bisync successful 2025/09/27 02:01:07 NOTICE: (13) : test 4. run sync with check-access. should pass. 2025/09/27 02:01:07 NOTICE: (14) : bisync check-access 2025/09/27 02:01:11 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:11 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:11 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:11 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:11 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:11 INFO : Building Path1 and Path2 listings 2025/09/27 02:01:11 INFO : Path1 checking for diffs 2025/09/27 02:01:11 INFO : Path2 checking for diffs 2025/09/27 02:01:11 INFO : Checking access health 2025/09/27 02:01:11 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/09/27 02:01:11 INFO : No changes found 2025/09/27 02:01:11 INFO : Updating listings 2025/09/27 02:01:11 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:11 INFO : Bisync successful 2025/09/27 02:01:11 NOTICE: (15) : test 5. delete path1 top level RCLONE_TEST, run sync. should fail critical. 2025/09/27 02:01:11 NOTICE: (16) : delete-file TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/RCLONE_TEST 2025/09/27 02:01:11 DEBUG : Creating backend with remote "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" 2025/09/27 02:01:12 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" to be canonical "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1" 2025/09/27 02:01:12 INFO : RCLONE_TEST: Deleted 2025/09/27 02:01:12 NOTICE: (17) : bisync check-access 2025/09/27 02:01:16 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:16 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:16 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:16 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:16 INFO : Building Path1 and Path2 listings 2025/09/27 02:01:16 INFO : Path1 checking for diffs 2025/09/27 02:01:16 INFO : - Path1 File was deleted - RCLONE_TEST 2025/09/27 02:01:16 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2025/09/27 02:01:16 INFO : Path2 checking for diffs 2025/09/27 02:01:16 INFO : Checking access health 2025/09/27 02:01:16 ERROR : Access test failed: Path1 count 1, Path2 count 2 - RCLONE_TEST 2025/09/27 02:01:16 ERROR : -  Access test failed: Path2 file not found in Path1 - RCLONE_TEST 2025/09/27 02:01:16 ERROR : Bisync critical error: check file check failed 2025/09/27 02:01:16 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/27 02:01:16 NOTICE: Bisync error: bisync aborted 2025/09/27 02:01:16 NOTICE: (18) : copy-listings path1-missing 2025/09/27 02:01:16 NOTICE: (19) : test 6. run again. should fail critical due to missing listings. 2025/09/27 02:01:16 NOTICE: (20) : bisync check-access 2025/09/27 02:01:20 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:20 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:20 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:20 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:20 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:20 ERROR : Bisync critical error: cannot find prior Path1 or Path2 listings, likely due to critical error on prior run Tip: here are the filenames we were looking for. Do they exist? Path1: /tmp/020044we/workdir/TestB2_rclone-test-xepafob3nike_020044we_check_access_path1..tmp_020044we_check_access_path2.path1.lst Path2: /tmp/020044we/workdir/TestB2_rclone-test-xepafob3nike_020044we_check_access_path1..tmp_020044we_check_access_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/020044we/workdir" 2025/09/27 02:01:20 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/27 02:01:20 NOTICE: Bisync error: bisync aborted 2025/09/27 02:01:20 NOTICE: (21) : move-listings missing-listings 2025/09/27 02:01:20 NOTICE: (22) : test 7. run resync, which will copy the path2 top level back to path1. 2025/09/27 02:01:20 NOTICE: (23) : bisync resync 2025/09/27 02:01:24 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:24 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:24 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:24 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:24 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:24 INFO : Copying Path2 files to Path1 2025/09/27 02:01:24 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:01:25 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:25 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:01:25 INFO : Resync updating listings 2025/09/27 02:01:25 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:25 INFO : Bisync successful 2025/09/27 02:01:25 NOTICE: (24) : test 8. run sync with --check-access. should pass. 2025/09/27 02:01:25 NOTICE: (25) : bisync check-access 2025/09/27 02:01:29 DEBUG : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:29 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:01:29 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:29 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:29 INFO : Synching Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" with Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:29 INFO : Building Path1 and Path2 listings 2025/09/27 02:01:29 INFO : Path1 checking for diffs 2025/09/27 02:01:29 INFO : Path2 checking for diffs 2025/09/27 02:01:29 INFO : Checking access health 2025/09/27 02:01:29 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/09/27 02:01:29 INFO : No changes found 2025/09/27 02:01:29 INFO : Updating listings 2025/09/27 02:01:29 INFO : Validating listings for Path1 "TestB2:rclone-test-xepafob3nike/020044we/check_access/path1/" vs Path2 "/tmp/020044we/check_access/path2/" 2025/09/27 02:01:29 INFO : Bisync successful 2025/09/27 02:01:29 INFO : B2 bucket rclone-test-xepafob3nike path 020044we/check_access/path1: cleaning bucket "rclone-test-xepafob3nike" of all files 2025/09/27 02:01:29 DEBUG : RCLONE_TEST: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eac22_d20250927_m020125_c001_v0001176_t0034_u01758938485200") 2025/09/27 02:01:29 DEBUG : RCLONE_TEST: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b2288510_d20250927_m020112_c001_v7007000_t0000_u01758938472793") 2025/09/27 02:01:29 DEBUG : RCLONE_TEST: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f106892adabe63079_d20250927_m020050_c001_v0001145_t0036_u01758938450262") 2025/09/27 02:01:29 DEBUG : file1.txt: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eabc9_d20250927_m020049_c001_v0001176_t0009_u01758938449722") 2025/09/27 02:01:29 DEBUG : file2.txt: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1092501eafb2d81e_d20250927_m020049_c001_v0001044_t0030_u01758938449878") 2025/09/27 02:01:29 DEBUG : file3.txt: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1006894b1b12a3fb_d20250927_m020050_c001_v0001120_t0048_u01758938450084") 2025/09/27 02:01:29 DEBUG : file4.txt: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eabcb_d20250927_m020049_c001_v0001176_t0045_u01758938449940") 2025/09/27 02:01:29 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b2288522_d20250927_m020129_c001_v7007000_t0000_u01758938489016") 2025/09/27 02:01:29 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1139d86bb22a54aa_d20250927_m020127_c001_v0001113_t0035_u01758938487720") 2025/09/27 02:01:29 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1006894b1b12a44b_d20250927_m020126_c001_v0001120_t0019_u01758938486139") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b228851c_d20250927_m020124_c001_v7007000_t0000_u01758938484433") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f10821b2810608560_d20250927_m020123_c001_v0001137_t0041_u01758938483066") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f106892adabe630b4_d20250927_m020121_c001_v0001145_t0012_u01758938481059") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b2288518_d20250927_m020120_c001_v7007000_t0000_u01758938480500") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f119b050ebd04bcc6_d20250927_m020119_c001_v0001038_t0037_u01758938479033") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1092501eafb2d862_d20250927_m020117_c001_v0001044_t0012_u01758938477327") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b2288514_d20250927_m020116_c001_v7007000_t0000_u01758938476404") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f112b87f952dfcf2e_d20250927_m020114_c001_v0001116_t0017_u01758938474864") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1006894b1b12a430_d20250927_m020113_c001_v0001120_t0053_u01758938473354") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b2288507_d20250927_m020110_c001_v7007000_t0000_u01758938470956") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f106553463d942b59_d20250927_m020109_c001_v0001172_t0014_u01758938469533") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eabf0_d20250927_m020108_c001_v0001176_t0059_u01758938468108") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b22884fa_d20250927_m020106_c001_v7007000_t0000_u01758938466640") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f11214fbe78e96dd9_d20250927_m020105_c001_v0001178_t0027_u01758938465424") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f106892adabe63095_d20250927_m020103_c001_v0001145_t0040_u01758938463349") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b22884da_d20250927_m020101_c001_v7007000_t0000_u01758938461140") 2025/09/27 02:01:30 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1137d7e3e0b1ea98_d20250927_m020059_c001_v0001145_t0039_u01758938459918") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1092501eafb2d83c_d20250927_m020058_c001_v0001044_t0023_u01758938458302") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b22884bf_d20250927_m020057_c001_v7007000_t0000_u01758938457365") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f11930c202acb7d2a_d20250927_m020056_c001_v0001176_t0001_u01758938456149") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1006894b1b12a407_d20250927_m020054_c001_v0001120_t0051_u01758938454564") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f403c2324b22884a4_d20250927_m020053_c001_v7007000_t0000_u01758938453647") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f112979d16263b09a_d20250927_m020052_c001_v0001105_t0009_u01758938452269") 2025/09/27 02:01:31 DEBUG : modtime_write_test: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eabd3_d20250927_m020050_c001_v0001176_t0048_u01758938450835") 2025/09/27 02:01:31 DEBUG : subdir/RCLONE_TEST: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1128544f8f4eabcd_d20250927_m020050_c001_v0001176_t0018_u01758938450142") 2025/09/27 02:01:31 DEBUG : subdir/file20.txt: Deleting (id "4_zefb2bda3e06d8b8b9f980e1b_f1092501eafb2d820_d20250927_m020050_c001_v0001044_t0045_u01758938450171") 2025/09/27 02:01:31 DEBUG : Waiting for deletions to finish 2025/09/27 02:01:31 INFO : file2.txt: Deleted 2025/09/27 02:01:31 INFO : file3.txt: Deleted 2025/09/27 02:01:31 INFO : RCLONE_TEST: Deleted 2025/09/27 02:01:31 INFO : file1.txt: Deleted 2025/09/27 02:01:31 INFO : file4.txt: Deleted 2025/09/27 02:01:31 INFO : subdir/RCLONE_TEST: Deleted 2025/09/27 02:01:31 INFO : subdir/file20.txt: Deleted 2025/09/27 02:01:31 DEBUG : removing 1 level 1 directories 2025/09/27 02:01:31 INFO : subdir: Removing directory 2025/09/27 02:01:31 DEBUG : removing 1 level 0 directories 2025/09/27 02:01:31 INFO : Local file system at /tmp/020044we/check_access/path2: Removing directory bisync_test.go:608: TEST check_access PASSED 2025/09/27 02:01:31 DEBUG : B2 bucket rclone-test-xepafob3nike: Purge remote 2025/09/27 02:01:31 INFO : B2 bucket rclone-test-xepafob3nike: cleaning bucket "rclone-test-xepafob3nike" of all files --- PASS: TestBisyncRemoteLocal (48.61s) --- PASS: TestBisyncRemoteLocal/check_access (46.89s) === RUN TestBisyncLocalRemote 2025/09/27 02:01:32 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu" 2025/09/27 02:01:33 NOTICE: remote: TestB2:rclone-test-kufoyun4sizu === RUN TestBisyncLocalRemote/resync 2025/09/27 02:01:33 DEBUG : Creating backend with remote "/tmp/020133vi" 2025/09/27 02:01:33 DEBUG : Creating backend with remote "/tmp/020133vi/resync/path1" 2025/09/27 02:01:33 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi" 2025/09/27 02:01:34 INFO : path2: Making directory 2025/09/27 02:01:35 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2" 2025/09/27 02:01:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial" 2025/09/27 02:01:36 DEBUG : Creating backend with remote "/tmp/020133vi/initdir/test_resync-vodeqep2" 2025/09/27 02:01:36 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:01:36 INFO : file2.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : Local file system at /tmp/020133vi/initdir/test_resync-vodeqep2: Waiting for checks to finish 2025/09/27 02:01:36 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:01:36 INFO : file3.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:01:36 DEBUG : Local file system at /tmp/020133vi/initdir/test_resync-vodeqep2: Waiting for transfers to finish 2025/09/27 02:01:36 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:01:36 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:36 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:01:36 INFO : file1.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:01:36 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/27 02:01:36 INFO : file6.txt: Copied (new) 2025/09/27 02:01:36 INFO : file4.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:36 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/27 02:01:36 INFO : file7.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/27 02:01:36 INFO : file5.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles" 2025/09/27 02:01:36 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9" 2025/09/27 02:01:36 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:36 DEBUG : Local file system at /tmp/020133vi/datadir/test_resync-sebunac9: Waiting for checks to finish 2025/09/27 02:01:36 DEBUG : Local file system at /tmp/020133vi/datadir/test_resync-sebunac9: Waiting for transfers to finish 2025/09/27 02:01:36 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:01:36 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:01:36 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt 2025/09/27 02:01:36 INFO : fileA.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt 2025/09/27 02:01:36 INFO : fileB.txt: Copied (new) 2025/09/27 02:01:36 DEBUG : Waiting for deletions to finish 2025/09/27 02:01:36 ERROR : error listing: directory not found 2025/09/27 02:01:36 ERROR : Local file system at /tmp/020133vi/resync/path1: Failed to list "": directory not found 2025/09/27 02:01:36 DEBUG : removing 1 level 0 directories 2025/09/27 02:01:36 INFO : Local file system at /tmp/020133vi/resync/path1: Removing directory 2025/09/27 02:01:36 ERROR : Failed to rmdir: stat /tmp/020133vi/resync/path1: no such file or directory 2025/09/27 02:01:36 INFO : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: cleaning bucket "rclone-test-kufoyun4sizu" of all files 2025/09/27 02:01:37 ERROR : error listing: directory not found 2025/09/27 02:01:37 NOTICE: checking initFs Local file system at /tmp/020133vi/initdir/test_resync-vodeqep2 2025/09/27 02:01:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : Local file system at /tmp/020133vi/resync/path1: Waiting for checks to finish 2025/09/27 02:01:37 DEBUG : Local file system at /tmp/020133vi/resync/path1: Waiting for transfers to finish 2025/09/27 02:01:37 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:01:37 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:01:37 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:37 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:01:37 INFO : file1.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:01:37 INFO : file2.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:01:37 INFO : file3.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/27 02:01:37 INFO : file5.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/27 02:01:37 INFO : file7.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:01:37 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/27 02:01:37 INFO : file6.txt: Copied (new) 2025/09/27 02:01:37 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:01:37 INFO : file4.txt: Copied (new) 2025/09/27 02:01:37 NOTICE: checking Path1 Local file system at /tmp/020133vi/resync/path1 2025/09/27 02:01:37 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:01:37 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Waiting for checks to finish 2025/09/27 02:01:37 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Waiting for transfers to finish 2025/09/27 02:01:38 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:38 INFO : file2.txt: Copied (new) 2025/09/27 02:01:38 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:38 INFO : file4.txt: Copied (new) 2025/09/27 02:01:38 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:38 INFO : file1.txt: Copied (new) 2025/09/27 02:01:38 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:38 INFO : file5.txt: Copied (new) 2025/09/27 02:01:38 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:01:38 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:38 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:38 INFO : file3.txt: Copied (new) 2025/09/27 02:01:39 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:39 INFO : file6.txt: Copied (new) 2025/09/27 02:01:39 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:01:39 INFO : file7.txt: Copied (new) 2025/09/27 02:01:39 NOTICE: checking path2 B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2 2025/09/27 02:01:39 NOTICE: (01) : test resync 2025/09/27 02:01:39 NOTICE: (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2025/09/27 02:01:39 NOTICE: (03) : purge-children /tmp/020133vi/resync/path1/ 2025/09/27 02:01:39 DEBUG : file6.txt: Remove file 2025/09/27 02:01:39 DEBUG : file4.txt: Remove file 2025/09/27 02:01:39 DEBUG : file1.txt: Remove file 2025/09/27 02:01:39 DEBUG : file3.txt: Remove file 2025/09/27 02:01:39 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:01:39 DEBUG : file5.txt: Remove file 2025/09/27 02:01:39 DEBUG : file7.txt: Remove file 2025/09/27 02:01:39 DEBUG : file2.txt: Remove file 2025/09/27 02:01:39 NOTICE: (04) : bisync resync 2025/09/27 02:01:42 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:42 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:42 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:42 INFO : Synching Path1 "/tmp/020133vi/resync/path1/" with Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:42 INFO : Copying Path2 files to Path1 2025/09/27 02:01:42 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:01:42 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:42 INFO : file1.txt: Copied (new) 2025/09/27 02:01:42 INFO : file2.txt: Copied (new) 2025/09/27 02:01:42 INFO : file3.txt: Copied (new) 2025/09/27 02:01:42 INFO : file4.txt: Copied (new) 2025/09/27 02:01:42 INFO : file5.txt: Copied (new) 2025/09/27 02:01:43 INFO : file6.txt: Copied (new) 2025/09/27 02:01:43 INFO : file7.txt: Copied (new) 2025/09/27 02:01:43 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:01:43 INFO : Resync updating listings 2025/09/27 02:01:43 INFO : Validating listings for Path1 "/tmp/020133vi/resync/path1/" vs Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:43 INFO : Bisync successful 2025/09/27 02:01:43 NOTICE: (05) : move-listings empty-path1 2025/09/27 02:01:43 NOTICE: (06) : test 2. resync with empty path2, resulting in syncing all content to path2. 2025/09/27 02:01:43 NOTICE: (07) : purge-children TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ 2025/09/27 02:01:43 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:01:43 DEBUG : file1.txt: Remove file 2025/09/27 02:01:43 DEBUG : file2.txt: Remove file 2025/09/27 02:01:44 DEBUG : file3.txt: Remove file 2025/09/27 02:01:44 DEBUG : file4.txt: Remove file 2025/09/27 02:01:44 DEBUG : file5.txt: Remove file 2025/09/27 02:01:44 DEBUG : file6.txt: Remove file 2025/09/27 02:01:44 DEBUG : file7.txt: Remove file 2025/09/27 02:01:44 NOTICE: (08) : bisync resync 2025/09/27 02:01:48 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:01:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:01:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:01:48 INFO : Synching Path1 "/tmp/020133vi/resync/path1/" with Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:48 INFO : Copying Path2 files to Path1 2025/09/27 02:01:48 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:01:48 INFO : There was nothing to transfer 2025/09/27 02:01:48 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:01:49 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:01:49 INFO : file1.txt: Copied (new) 2025/09/27 02:01:49 INFO : file2.txt: Copied (new) 2025/09/27 02:01:49 INFO : file3.txt: Copied (new) 2025/09/27 02:01:49 INFO : file4.txt: Copied (new) 2025/09/27 02:01:49 INFO : file5.txt: Copied (new) 2025/09/27 02:01:49 INFO : file6.txt: Copied (new) 2025/09/27 02:01:49 INFO : file7.txt: Copied (new) 2025/09/27 02:01:49 INFO : Resync updating listings 2025/09/27 02:01:49 INFO : Validating listings for Path1 "/tmp/020133vi/resync/path1/" vs Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:49 INFO : Bisync successful 2025/09/27 02:01:49 NOTICE: (09) : move-listings empty-path2 2025/09/27 02:01:49 NOTICE: (10) : test 3. exercise all of the various file difference scenarios during a resync. 2025/09/27 02:01:49 NOTICE: (11) : touch-glob 2002-02-02 /tmp/020133vi/datadir/test_resync-sebunac9/ fileA.txt 2025/09/27 02:01:49 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/" 2025/09/27 02:01:49 DEBUG : fs cache: renaming cache item "/tmp/020133vi/datadir/test_resync-sebunac9/" to be canonical "/tmp/020133vi/datadir/test_resync-sebunac9" 2025/09/27 02:01:49 DEBUG : fileA.txt: Set modification time 2002-02-02 2025/09/27 02:01:49 NOTICE: (12) : touch-glob 1999-09-09 /tmp/020133vi/datadir/test_resync-sebunac9/ fileB.txt 2025/09/27 02:01:49 DEBUG : fs cache: switching user supplied name "/tmp/020133vi/datadir/test_resync-sebunac9/" for canonical name "/tmp/020133vi/datadir/test_resync-sebunac9" 2025/09/27 02:01:49 DEBUG : fileB.txt: Set modification time 1999-09-09 2025/09/27 02:01:49 NOTICE: (13) : test = file - path1 - path2 - expected action - who wins 2025/09/27 02:01:49 NOTICE: (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2025/09/27 02:01:49 NOTICE: (15) : delete-file TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/file1.txt 2025/09/27 02:01:49 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:50 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" to be canonical "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2" 2025/09/27 02:01:51 INFO : file1.txt: Deleted 2025/09/27 02:01:51 NOTICE: (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2025/09/27 02:01:51 NOTICE: (17) : delete-file /tmp/020133vi/resync/path1/file2.txt 2025/09/27 02:01:51 DEBUG : Creating backend with remote "/tmp/020133vi/resync/path1/" 2025/09/27 02:01:51 DEBUG : fs cache: renaming cache item "/tmp/020133vi/resync/path1/" to be canonical "/tmp/020133vi/resync/path1" 2025/09/27 02:01:51 INFO : file2.txt: Deleted 2025/09/27 02:01:51 NOTICE: (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2025/09/27 02:01:51 NOTICE: (19) : copy-as /tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ file3.txt 2025/09/27 02:01:51 DEBUG : copyFile "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" to "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" as "file3.txt" 2025/09/27 02:01:51 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" 2025/09/27 02:01:51 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:52 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2" as "file3.txt" 2025/09/27 02:01:52 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:01:54 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:01:54 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2025/09/27 02:01:54 NOTICE: (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2025/09/27 02:01:54 NOTICE: (21) : delete-file /tmp/020133vi/resync/path1/file4.txt 2025/09/27 02:01:54 DEBUG : fs cache: switching user supplied name "/tmp/020133vi/resync/path1/" for canonical name "/tmp/020133vi/resync/path1" 2025/09/27 02:01:54 INFO : file4.txt: Deleted 2025/09/27 02:01:54 NOTICE: (22) : copy-as /tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ file4.txt 2025/09/27 02:01:54 DEBUG : copyFile "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" to "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" as "file4.txt" 2025/09/27 02:01:54 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" 2025/09/27 02:01:54 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:55 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2" as "file4.txt" 2025/09/27 02:01:55 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:01:57 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:01:57 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2025/09/27 02:01:57 NOTICE: (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2025/09/27 02:01:57 NOTICE: (24) : copy-as /tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ file5.txt 2025/09/27 02:01:57 DEBUG : copyFile "/tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt" to "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" as "file5.txt" 2025/09/27 02:01:57 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt" 2025/09/27 02:01:57 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:01:58 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2" as "file5.txt" 2025/09/27 02:01:58 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:02:00 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:02:00 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2025/09/27 02:02:00 NOTICE: (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2025/09/27 02:02:00 NOTICE: (26) : copy-as /tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt /tmp/020133vi/resync/path1/ file6.txt 2025/09/27 02:02:00 DEBUG : copyFile "/tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt" to "/tmp/020133vi/resync/path1/" as "file6.txt" 2025/09/27 02:02:00 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/fileB.txt" 2025/09/27 02:02:00 DEBUG : Creating backend with remote "/tmp/020133vi/resync/path1/" 2025/09/27 02:02:00 DEBUG : operations.CopyFile "fileB.txt" to "Local file system at /tmp/020133vi/resync/path1" as "file6.txt" 2025/09/27 02:02:00 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:02:00 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:02:00 DEBUG : file6.txt.237b502b.partial: renamed to: file6.txt 2025/09/27 02:02:00 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2025/09/27 02:02:00 NOTICE: (27) : copy-as /tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ file6.txt 2025/09/27 02:02:00 DEBUG : copyFile "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" to "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" as "file6.txt" 2025/09/27 02:02:00 DEBUG : Creating backend with remote "/tmp/020133vi/datadir/test_resync-sebunac9/fileA.txt" 2025/09/27 02:02:00 DEBUG : Creating backend with remote "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:01 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2" as "file6.txt" 2025/09/27 02:02:01 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:02:03 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:02:03 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2025/09/27 02:02:03 NOTICE: (28) : test - file7.txt - exists - exists (same) - none - same 2025/09/27 02:02:03 NOTICE: (29) : test run bisync with resync 2025/09/27 02:02:03 NOTICE: (30) : bisync resync 2025/09/27 02:02:07 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:02:07 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:02:07 INFO : Synching Path1 "/tmp/020133vi/resync/path1/" with Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:07 INFO : Copying Path2 files to Path1 2025/09/27 02:02:07 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:02:07 INFO : file2.txt: Copied (new) 2025/09/27 02:02:07 INFO : file4.txt: Copied (new) 2025/09/27 02:02:07 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:02:08 INFO : file3.txt: Copied (replaced existing) 2025/09/27 02:02:08 INFO : file5.txt: Copied (replaced existing) 2025/09/27 02:02:08 INFO : file1.txt: Copied (new) 2025/09/27 02:02:08 INFO : file6.txt: Updated modification time in destination 2025/09/27 02:02:08 INFO : Resync updating listings 2025/09/27 02:02:08 INFO : Validating listings for Path1 "/tmp/020133vi/resync/path1/" vs Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:08 INFO : Bisync successful 2025/09/27 02:02:08 NOTICE: (31) : copy-listings mixed-diffs 2025/09/27 02:02:08 NOTICE: (32) : test run normal bisync 2025/09/27 02:02:08 NOTICE: (33) : bisync 2025/09/27 02:02:11 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:11 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:02:11 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:02:11 INFO : Synching Path1 "/tmp/020133vi/resync/path1/" with Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:11 INFO : Building Path1 and Path2 listings 2025/09/27 02:02:11 INFO : Path1 checking for diffs 2025/09/27 02:02:11 INFO : Path2 checking for diffs 2025/09/27 02:02:11 INFO : No changes found 2025/09/27 02:02:11 INFO : Updating listings 2025/09/27 02:02:11 INFO : Validating listings for Path1 "/tmp/020133vi/resync/path1/" vs Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:11 INFO : Bisync successful 2025/09/27 02:02:11 NOTICE: (34) : test 4. confirm critical error on normal sync of empty path. 2025/09/27 02:02:11 NOTICE: (35) : purge-children TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/ 2025/09/27 02:02:11 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:02:11 DEBUG : file1.txt: Remove file 2025/09/27 02:02:12 DEBUG : file2.txt: Remove file 2025/09/27 02:02:12 DEBUG : file3.txt: Remove file 2025/09/27 02:02:12 DEBUG : file4.txt: Remove file 2025/09/27 02:02:12 DEBUG : file5.txt: Remove file 2025/09/27 02:02:13 DEBUG : file6.txt: Remove file 2025/09/27 02:02:13 DEBUG : file7.txt: Remove file 2025/09/27 02:02:13 NOTICE: (36) : bisync 2025/09/27 02:02:16 DEBUG : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:02:16 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/09/27 02:02:16 INFO : Synching Path1 "/tmp/020133vi/resync/path1/" with Path2 "TestB2:rclone-test-kufoyun4sizu/020133vi/resync/path2/" 2025/09/27 02:02:16 INFO : Building Path1 and Path2 listings 2025/09/27 02:02:17 INFO : Path1 checking for diffs 2025/09/27 02:02:17 INFO : Path2 checking for diffs 2025/09/27 02:02:17 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/020133vi/workdir/tmp_020133vi_resync_path1..TestB2_rclone-test-kufoyun4sizu_020133vi_resync_path2.path2.lst-new 2025/09/27 02:02:17 ERROR : Bisync critical error: empty current Path2 listing: /tmp/020133vi/workdir/tmp_020133vi_resync_path1..TestB2_rclone-test-kufoyun4sizu_020133vi_resync_path2.path2.lst-new 2025/09/27 02:02:17 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/27 02:02:17 NOTICE: Bisync error: bisync aborted 2025/09/27 02:02:17 DEBUG : Waiting for deletions to finish 2025/09/27 02:02:17 INFO : RCLONE_TEST: Deleted 2025/09/27 02:02:17 INFO : file7.txt: Deleted 2025/09/27 02:02:17 INFO : file3.txt: Deleted 2025/09/27 02:02:17 INFO : file1.txt: Deleted 2025/09/27 02:02:17 INFO : file4.txt: Deleted 2025/09/27 02:02:17 INFO : file2.txt: Deleted 2025/09/27 02:02:17 INFO : file5.txt: Deleted 2025/09/27 02:02:17 INFO : file6.txt: Deleted 2025/09/27 02:02:17 DEBUG : removing 1 level 0 directories 2025/09/27 02:02:17 INFO : Local file system at /tmp/020133vi/resync/path1: Removing directory 2025/09/27 02:02:17 INFO : B2 bucket rclone-test-kufoyun4sizu path 020133vi/resync/path2: cleaning bucket "rclone-test-kufoyun4sizu" of all files 2025/09/27 02:02:17 DEBUG : RCLONE_TEST: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a30_d20250927_m020211_c001_v7007000_t0000_u01758938531853") 2025/09/27 02:02:17 DEBUG : RCLONE_TEST: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541bee_d20250927_m020148_c001_v0001161_t0034_u01758938508929") 2025/09/27 02:02:17 DEBUG : RCLONE_TEST: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d6d_d20250927_m020143_c001_v7007000_t0000_u01758938503546") 2025/09/27 02:02:17 DEBUG : RCLONE_TEST: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10419aa798c9e118_d20250927_m020138_c001_v0001179_t0031_u01758938498866") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a32_d20250927_m020212_c001_v7007000_t0000_u01758938532035") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f107aa23019749502_d20250927_m020208_c001_v0001184_t0050_u01758938528066") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d7c_d20250927_m020151_c001_v7007000_t0000_u01758938511091") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f107aa23019749490_d20250927_m020149_c001_v0001184_t0003_u01758938509260") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d6e_d20250927_m020143_c001_v7007000_t0000_u01758938503731") 2025/09/27 02:02:17 DEBUG : file1.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f107aa23019749443_d20250927_m020138_c001_v0001184_t0009_u01758938498672") 2025/09/27 02:02:17 DEBUG : file2.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a34_d20250927_m020212_c001_v7007000_t0000_u01758938532218") 2025/09/27 02:02:18 DEBUG : file2.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb95_d20250927_m020149_c001_v0001184_t0008_u01758938509270") 2025/09/27 02:02:18 DEBUG : file2.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d6f_d20250927_m020143_c001_v7007000_t0000_u01758938503916") 2025/09/27 02:02:18 DEBUG : file2.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb4e_d20250927_m020138_c001_v0001184_t0045_u01758938498292") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a35_d20250927_m020212_c001_v7007000_t0000_u01758938532412") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbebf3_d20250927_m020208_c001_v0001184_t0034_u01758938528088") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f102594bf59dd9a49_d20250927_m020153_c001_v0001182_t0012_u01758938513975") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10419aa798c9e149_d20250927_m020149_c001_v0001179_t0037_u01758938509284") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d70_d20250927_m020144_c001_v7007000_t0000_u01758938504100") 2025/09/27 02:02:18 DEBUG : file3.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541bd0_d20250927_m020138_c001_v0001161_t0006_u01758938498468") 2025/09/27 02:02:18 DEBUG : file4.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a37_d20250927_m020212_c001_v7007000_t0000_u01758938532646") 2025/09/27 02:02:18 DEBUG : file4.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1057e3fd6fb5ec89_d20250927_m020156_c001_v0001044_t0045_u01758938516793") 2025/09/27 02:02:18 DEBUG : file4.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541bf0_d20250927_m020149_c001_v0001161_t0046_u01758938509248") 2025/09/27 02:02:18 DEBUG : file4.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d71_d20250927_m020144_c001_v7007000_t0000_u01758938504284") 2025/09/27 02:02:18 DEBUG : file4.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb52_d20250927_m020138_c001_v0001184_t0023_u01758938498495") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a39_d20250927_m020212_c001_v7007000_t0000_u01758938532935") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10419aa798c9e186_d20250927_m020208_c001_v0001179_t0040_u01758938528090") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f11416c611fd7d793_d20250927_m020159_c001_v0001115_t0017_u01758938519775") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f107aa23019749492_d20250927_m020149_c001_v0001184_t0043_u01758938509455") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d72_d20250927_m020144_c001_v7007000_t0000_u01758938504467") 2025/09/27 02:02:18 DEBUG : file5.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb56_d20250927_m020138_c001_v0001184_t0016_u01758938498689") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a3a_d20250927_m020213_c001_v7007000_t0000_u01758938533117") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f118f8903561a9127_d20250927_m020207_c001_v0001172_t0036_u01758938527947") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1098bd4d3334c89f_d20250927_m020202_c001_v0001091_t0024_u01758938522924") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb99_d20250927_m020149_c001_v0001184_t0056_u01758938509473") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d73_d20250927_m020144_c001_v7007000_t0000_u01758938504652") 2025/09/27 02:02:19 DEBUG : file6.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f107aa23019749445_d20250927_m020138_c001_v0001184_t0014_u01758938498885") 2025/09/27 02:02:19 DEBUG : file7.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a3b_d20250927_m020213_c001_v7007000_t0000_u01758938533300") 2025/09/27 02:02:19 DEBUG : file7.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10419aa798c9e14d_d20250927_m020149_c001_v0001179_t0024_u01758938509504") 2025/09/27 02:02:19 DEBUG : file7.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d74_d20250927_m020144_c001_v7007000_t0000_u01758938504837") 2025/09/27 02:02:19 DEBUG : file7.txt: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbeb58_d20250927_m020138_c001_v0001184_t0040_u01758938498899") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a44_d20250927_m020216_c001_v7007000_t0000_u01758938536847") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f11898cfd7c3c875c_d20250927_m020215_c001_v0001183_t0022_u01758938535643") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f101e3a9223cbec0f_d20250927_m020213_c001_v0001184_t0009_u01758938533869") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a2f_d20250927_m020211_c001_v7007000_t0000_u01758938531289") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1142abd4e219b1a6_d20250927_m020210_c001_v0001184_t0026_u01758938530092") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541c2a_d20250927_m020208_c001_v0001161_t0037_u01758938528601") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f4132479332c40a28_d20250927_m020206_c001_v7007000_t0000_u01758938526950") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10634d18c789633e_d20250927_m020205_c001_v0001181_t0025_u01758938525701") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541c1b_d20250927_m020203_c001_v0001161_t0040_u01758938523669") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d79_d20250927_m020148_c001_v7007000_t0000_u01758938508340") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1084a96d701e8ada_d20250927_m020146_c001_v0001146_t0013_u01758938506931") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f1012a55c93541be4_d20250927_m020145_c001_v0001161_t0040_u01758938505407") 2025/09/27 02:02:19 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f41522a5b6e710d6b_d20250927_m020142_c001_v7007000_t0000_u01758938502022") 2025/09/27 02:02:20 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f113e04e09010efab_d20250927_m020140_c001_v0001105_t0042_u01758938500751") 2025/09/27 02:02:20 DEBUG : modtime_write_test: Deleting (id "4_z8f727d83e07d8b8b9f980e1b_f10419aa798c9e11c_d20250927_m020139_c001_v0001179_t0000_u01758938499311") bisync_test.go:608: TEST resync PASSED 2025/09/27 02:02:20 DEBUG : B2 bucket rclone-test-kufoyun4sizu: Purge remote 2025/09/27 02:02:20 INFO : B2 bucket rclone-test-kufoyun4sizu: cleaning bucket "rclone-test-kufoyun4sizu" of all files --- PASS: TestBisyncLocalRemote (48.68s) --- PASS: TestBisyncLocalRemote/resync (46.99s) === RUN TestBisyncRemoteRemote 2025/09/27 02:02:21 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu" 2025/09/27 02:02:22 NOTICE: remote: TestB2:rclone-test-dewidul8qoyu === RUN TestBisyncRemoteRemote/resync 2025/09/27 02:02:22 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa" 2025/09/27 02:02:23 INFO : path1: Making directory 2025/09/27 02:02:24 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1" 2025/09/27 02:02:25 INFO : path2: Making directory 2025/09/27 02:02:25 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2" 2025/09/27 02:02:26 DEBUG : Creating backend with remote "/tmp/020222pa/initdir/test_resync-budoviv0" 2025/09/27 02:02:26 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : Local file system at /tmp/020222pa/initdir/test_resync-budoviv0: Waiting for checks to finish 2025/09/27 02:02:26 DEBUG : Local file system at /tmp/020222pa/initdir/test_resync-budoviv0: Waiting for transfers to finish 2025/09/27 02:02:26 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:02:26 INFO : file1.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:02:26 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:02:26 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:02:26 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:02:26 INFO : file2.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/27 02:02:26 INFO : file5.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:02:26 INFO : file4.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:02:26 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/27 02:02:26 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/27 02:02:26 INFO : file6.txt: Copied (new) 2025/09/27 02:02:26 INFO : file7.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:02:26 INFO : file3.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9" 2025/09/27 02:02:26 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:26 DEBUG : Local file system at /tmp/020222pa/datadir/test_resync-qoyumas9: Waiting for checks to finish 2025/09/27 02:02:26 DEBUG : Local file system at /tmp/020222pa/datadir/test_resync-qoyumas9: Waiting for transfers to finish 2025/09/27 02:02:26 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:02:26 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:02:26 DEBUG : fileB.txt.b8ec97e6.partial: renamed to: fileB.txt 2025/09/27 02:02:26 INFO : fileB.txt: Copied (new) 2025/09/27 02:02:26 DEBUG : fileA.txt.04c13af3.partial: renamed to: fileA.txt 2025/09/27 02:02:26 INFO : fileA.txt: Copied (new) 2025/09/27 02:02:26 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:02:27 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:02:28 NOTICE: checking initFs Local file system at /tmp/020222pa/initdir/test_resync-budoviv0 2025/09/27 02:02:28 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:28 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Waiting for checks to finish 2025/09/27 02:02:28 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Waiting for transfers to finish 2025/09/27 02:02:29 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file2.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file1.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file3.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file5.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file4.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file6.txt: Copied (new) 2025/09/27 02:02:29 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:29 INFO : file7.txt: Copied (new) 2025/09/27 02:02:30 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:02:30 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:02:30 NOTICE: checking Path1 B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1 2025/09/27 02:02:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:02:30 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Waiting for checks to finish 2025/09/27 02:02:30 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Waiting for transfers to finish 2025/09/27 02:02:31 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:31 INFO : file2.txt: Copied (new) 2025/09/27 02:02:31 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:31 INFO : file3.txt: Copied (new) 2025/09/27 02:02:31 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:31 INFO : file1.txt: Copied (new) 2025/09/27 02:02:32 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:32 INFO : file4.txt: Copied (new) 2025/09/27 02:02:32 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:32 INFO : file5.txt: Copied (new) 2025/09/27 02:02:32 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:32 INFO : file6.txt: Copied (new) 2025/09/27 02:02:32 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:02:32 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:02:32 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:02:32 INFO : file7.txt: Copied (new) 2025/09/27 02:02:32 NOTICE: checking path2 B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2 2025/09/27 02:02:32 NOTICE: (01) : test resync 2025/09/27 02:02:32 NOTICE: (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2025/09/27 02:02:32 NOTICE: (03) : purge-children TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/ 2025/09/27 02:02:32 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:02:33 DEBUG : file1.txt: Remove file 2025/09/27 02:02:33 DEBUG : file2.txt: Remove file 2025/09/27 02:02:33 DEBUG : file3.txt: Remove file 2025/09/27 02:02:33 DEBUG : file4.txt: Remove file 2025/09/27 02:02:33 DEBUG : file5.txt: Remove file 2025/09/27 02:02:33 DEBUG : file6.txt: Remove file 2025/09/27 02:02:34 DEBUG : file7.txt: Remove file 2025/09/27 02:02:34 NOTICE: (04) : bisync resync 2025/09/27 02:02:41 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:41 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:02:41 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:02:41 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:41 INFO : Copying Path2 files to Path1 2025/09/27 02:02:41 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:02:42 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:02:42 INFO : RCLONE_TEST: Copied (server-side copy) 2025/09/27 02:02:42 INFO : file3.txt: Copied (server-side copy) 2025/09/27 02:02:42 INFO : file2.txt: Copied (server-side copy) 2025/09/27 02:02:43 INFO : file4.txt: Copied (server-side copy) 2025/09/27 02:02:43 INFO : file7.txt: Copied (server-side copy) 2025/09/27 02:02:43 INFO : file5.txt: Copied (server-side copy) 2025/09/27 02:02:43 INFO : file6.txt: Copied (server-side copy) 2025/09/27 02:02:43 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:02:43 INFO : Resync updating listings 2025/09/27 02:02:43 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:43 INFO : Bisync successful 2025/09/27 02:02:43 NOTICE: (05) : move-listings empty-path1 2025/09/27 02:02:43 NOTICE: (06) : test 2. resync with empty path2, resulting in syncing all content to path2. 2025/09/27 02:02:43 NOTICE: (07) : purge-children TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ 2025/09/27 02:02:43 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:02:43 DEBUG : file1.txt: Remove file 2025/09/27 02:02:43 DEBUG : file2.txt: Remove file 2025/09/27 02:02:44 DEBUG : file3.txt: Remove file 2025/09/27 02:02:44 DEBUG : file4.txt: Remove file 2025/09/27 02:02:44 DEBUG : file5.txt: Remove file 2025/09/27 02:02:44 DEBUG : file6.txt: Remove file 2025/09/27 02:02:44 DEBUG : file7.txt: Remove file 2025/09/27 02:02:44 NOTICE: (08) : bisync resync 2025/09/27 02:02:52 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:52 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:02:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:02:52 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:02:52 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:52 INFO : Copying Path2 files to Path1 2025/09/27 02:02:52 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:02:53 INFO : There was nothing to transfer 2025/09/27 02:02:53 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:02:53 INFO : file2.txt: Copied (server-side copy) 2025/09/27 02:02:53 INFO : RCLONE_TEST: Copied (server-side copy) 2025/09/27 02:02:53 INFO : file3.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : file7.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : file5.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : file6.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : file4.txt: Copied (server-side copy) 2025/09/27 02:02:54 INFO : Resync updating listings 2025/09/27 02:02:54 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:54 INFO : Bisync successful 2025/09/27 02:02:54 NOTICE: (09) : move-listings empty-path2 2025/09/27 02:02:54 NOTICE: (10) : test 3. exercise all of the various file difference scenarios during a resync. 2025/09/27 02:02:54 NOTICE: (11) : touch-glob 2002-02-02 /tmp/020222pa/datadir/test_resync-qoyumas9/ fileA.txt 2025/09/27 02:02:54 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/" 2025/09/27 02:02:54 DEBUG : fs cache: renaming cache item "/tmp/020222pa/datadir/test_resync-qoyumas9/" to be canonical "/tmp/020222pa/datadir/test_resync-qoyumas9" 2025/09/27 02:02:54 DEBUG : fileA.txt: Set modification time 2002-02-02 2025/09/27 02:02:54 NOTICE: (12) : touch-glob 1999-09-09 /tmp/020222pa/datadir/test_resync-qoyumas9/ fileB.txt 2025/09/27 02:02:54 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync-qoyumas9/" for canonical name "/tmp/020222pa/datadir/test_resync-qoyumas9" 2025/09/27 02:02:54 DEBUG : fileB.txt: Set modification time 1999-09-09 2025/09/27 02:02:54 NOTICE: (13) : test = file - path1 - path2 - expected action - who wins 2025/09/27 02:02:54 NOTICE: (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2025/09/27 02:02:54 NOTICE: (15) : delete-file TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/file1.txt 2025/09/27 02:02:54 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:55 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" to be canonical "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2" 2025/09/27 02:02:55 INFO : file1.txt: Deleted 2025/09/27 02:02:55 NOTICE: (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2025/09/27 02:02:55 NOTICE: (17) : delete-file TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/file2.txt 2025/09/27 02:02:55 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" 2025/09/27 02:02:57 DEBUG : fs cache: renaming cache item "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" to be canonical "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1" 2025/09/27 02:02:57 INFO : file2.txt: Deleted 2025/09/27 02:02:57 NOTICE: (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2025/09/27 02:02:57 NOTICE: (19) : copy-as /tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ file3.txt 2025/09/27 02:02:57 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" as "file3.txt" 2025/09/27 02:02:57 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" 2025/09/27 02:02:57 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:02:59 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2" as "file3.txt" 2025/09/27 02:02:59 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:03:00 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:03:00 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2025/09/27 02:03:00 NOTICE: (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2025/09/27 02:03:00 NOTICE: (21) : delete-file TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/file4.txt 2025/09/27 02:03:00 DEBUG : fs cache: switching user supplied name "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" for canonical name "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1" 2025/09/27 02:03:01 INFO : file4.txt: Deleted 2025/09/27 02:03:01 NOTICE: (22) : copy-as /tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ file4.txt 2025/09/27 02:03:01 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" as "file4.txt" 2025/09/27 02:03:01 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" 2025/09/27 02:03:01 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:02 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2" as "file4.txt" 2025/09/27 02:03:02 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:03:04 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:03:04 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2025/09/27 02:03:04 NOTICE: (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2025/09/27 02:03:04 NOTICE: (24) : copy-as /tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ file5.txt 2025/09/27 02:03:04 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" as "file5.txt" 2025/09/27 02:03:04 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt" 2025/09/27 02:03:04 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:05 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2" as "file5.txt" 2025/09/27 02:03:05 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:03:07 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:03:07 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2025/09/27 02:03:07 NOTICE: (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2025/09/27 02:03:07 NOTICE: (26) : copy-as /tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/ file6.txt 2025/09/27 02:03:07 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" as "file6.txt" 2025/09/27 02:03:07 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/fileB.txt" 2025/09/27 02:03:07 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" 2025/09/27 02:03:08 DEBUG : operations.CopyFile "fileB.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1" as "file6.txt" 2025/09/27 02:03:08 DEBUG : fileB.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:03:10 DEBUG : fileB.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:03:10 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2025/09/27 02:03:10 NOTICE: (27) : copy-as /tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ file6.txt 2025/09/27 02:03:10 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" as "file6.txt" 2025/09/27 02:03:10 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync-qoyumas9/fileA.txt" 2025/09/27 02:03:10 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:11 DEBUG : operations.CopyFile "fileA.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2" as "file6.txt" 2025/09/27 02:03:11 DEBUG : fileA.txt: Sizes differ (src 19 vs dst 0) 2025/09/27 02:03:13 DEBUG : fileA.txt: sha1 = be91a3d4ae9841cfe52de8379de8d1d248201b9c OK 2025/09/27 02:03:13 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2025/09/27 02:03:13 NOTICE: (28) : test - file7.txt - exists - exists (same) - none - same 2025/09/27 02:03:13 NOTICE: (29) : test run bisync with resync 2025/09/27 02:03:13 NOTICE: (30) : bisync resync 2025/09/27 02:03:21 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:21 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:03:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:03:21 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:21 INFO : Copying Path2 files to Path1 2025/09/27 02:03:21 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:03:22 INFO : file4.txt: Copied (server-side copy) 2025/09/27 02:03:22 INFO : file2.txt: Copied (server-side copy) 2025/09/27 02:03:22 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:03:22 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:03:23 INFO : file3.txt: Copied (server-side copy) 2025/09/27 02:03:23 INFO : file6.txt: Updated modification time in destination 2025/09/27 02:03:23 INFO : file5.txt: Copied (server-side copy) 2025/09/27 02:03:23 INFO : Resync updating listings 2025/09/27 02:03:23 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:23 INFO : Bisync successful 2025/09/27 02:03:23 NOTICE: (31) : copy-listings mixed-diffs 2025/09/27 02:03:23 NOTICE: (32) : test run normal bisync 2025/09/27 02:03:23 NOTICE: (33) : bisync 2025/09/27 02:03:31 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:31 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:31 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:03:31 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:03:31 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:31 INFO : Building Path1 and Path2 listings 2025/09/27 02:03:31 INFO : Path1 checking for diffs 2025/09/27 02:03:31 INFO : Path2 checking for diffs 2025/09/27 02:03:31 INFO : No changes found 2025/09/27 02:03:31 INFO : Updating listings 2025/09/27 02:03:31 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:31 INFO : Bisync successful 2025/09/27 02:03:31 NOTICE: (34) : test 4. confirm critical error on normal sync of empty path. 2025/09/27 02:03:31 NOTICE: (35) : purge-children TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/ 2025/09/27 02:03:31 DEBUG : RCLONE_TEST: Remove file 2025/09/27 02:03:31 DEBUG : file1.txt: Remove file 2025/09/27 02:03:32 DEBUG : file2.txt: Remove file 2025/09/27 02:03:32 DEBUG : file3.txt: Remove file 2025/09/27 02:03:32 DEBUG : file4.txt: Remove file 2025/09/27 02:03:32 DEBUG : file5.txt: Remove file 2025/09/27 02:03:32 DEBUG : file6.txt: Remove file 2025/09/27 02:03:33 DEBUG : file7.txt: Remove file 2025/09/27 02:03:33 NOTICE: (36) : bisync 2025/09/27 02:03:41 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:41 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:03:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:03:41 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:03:41 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync/path2/" 2025/09/27 02:03:41 INFO : Building Path1 and Path2 listings 2025/09/27 02:03:41 INFO : Path1 checking for diffs 2025/09/27 02:03:41 INFO : Path2 checking for diffs 2025/09/27 02:03:41 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/020222pa/workdir/TestB2_rclone-test-dewidul8qoyu_020222pa_resync_path1..TestB2_rclone-test-dewidul8qoyu_020222pa_resync_path2.path2.lst-new 2025/09/27 02:03:41 ERROR : Bisync critical error: empty current Path2 listing: /tmp/020222pa/workdir/TestB2_rclone-test-dewidul8qoyu_020222pa_resync_path1..TestB2_rclone-test-dewidul8qoyu_020222pa_resync_path2.path2.lst-new 2025/09/27 02:03:41 ERROR : Bisync aborted. Must run --resync to recover. 2025/09/27 02:03:41 NOTICE: Bisync error: bisync aborted 2025/09/27 02:03:41 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:03:41 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f115e2d87bbacce9a_d20250927_m020242_c001_v0001181_t0040_u01758938562485") 2025/09/27 02:03:41 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb4c_d20250927_m020232_c001_v7007000_t0000_u01758938552912") 2025/09/27 02:03:41 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10012bbe5bbc1493_d20250927_m020229_c001_v0001144_t0050_u01758938549695") 2025/09/27 02:03:41 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11725fac814aeb79_d20250927_m020242_c001_v0001042_t0050_u01758938562115") 2025/09/27 02:03:41 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb4e_d20250927_m020233_c001_v7007000_t0000_u01758938553095") 2025/09/27 02:03:41 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11601c5f9c9003f8_d20250927_m020229_c001_v0001182_t0046_u01758938549294") 2025/09/27 02:03:41 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1143dc3ea75be0ab_d20250927_m020321_c001_v0001104_t0002_u01758938601651") 2025/09/27 02:03:41 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f41881426012fa105_d20250927_m020257_c001_v7007000_t0000_u01758938577865") 2025/09/27 02:03:41 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10563800f1319fb2_d20250927_m020242_c001_v0001131_t0019_u01758938562541") 2025/09/27 02:03:41 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb4f_d20250927_m020233_c001_v7007000_t0000_u01758938553279") 2025/09/27 02:03:42 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1165a0e015ff35c8_d20250927_m020229_c001_v0001101_t0021_u01758938549129") 2025/09/27 02:03:42 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f106b7d5033378d76_d20250927_m020242_c001_v0001137_t0037_u01758938562493") 2025/09/27 02:03:42 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb51_d20250927_m020233_c001_v7007000_t0000_u01758938553462") 2025/09/27 02:03:42 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1095a9d0dfd217ea_d20250927_m020229_c001_v0001181_t0046_u01758938549490") 2025/09/27 02:03:42 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1071fff8240cea44_d20250927_m020322_c001_v0001178_t0049_u01758938602001") 2025/09/27 02:03:42 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f41881426012fa10c_d20250927_m020301_c001_v7007000_t0000_u01758938581269") 2025/09/27 02:03:42 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101d8b3acc66ce55_d20250927_m020242_c001_v0001043_t0013_u01758938562577") 2025/09/27 02:03:42 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb53_d20250927_m020233_c001_v7007000_t0000_u01758938553646") 2025/09/27 02:03:42 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1165a0e015ff35cd_d20250927_m020229_c001_v0001101_t0007_u01758938549445") 2025/09/27 02:03:42 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f116ef39488a444f1_d20250927_m020242_c001_v0001161_t0048_u01758938562721") 2025/09/27 02:03:42 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb54_d20250927_m020233_c001_v7007000_t0000_u01758938553830") 2025/09/27 02:03:42 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11601c5f9c9003fa_d20250927_m020229_c001_v0001182_t0022_u01758938549494") 2025/09/27 02:03:42 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f119a4789b26c20aa_d20250927_m020309_c001_v0001137_t0002_u01758938589795") 2025/09/27 02:03:42 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10563800f1319fb4_d20250927_m020242_c001_v0001131_t0014_u01758938562841") 2025/09/27 02:03:42 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb55_d20250927_m020234_c001_v7007000_t0000_u01758938554013") 2025/09/27 02:03:42 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1095a9d0dfd217ec_d20250927_m020229_c001_v0001181_t0021_u01758938549700") 2025/09/27 02:03:42 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f111baaf6190cb6a1_d20250927_m020242_c001_v0001183_t0048_u01758938562934") 2025/09/27 02:03:43 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb57_d20250927_m020234_c001_v7007000_t0000_u01758938554197") 2025/09/27 02:03:43 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11601c5f9c9003fc_d20250927_m020229_c001_v0001182_t0028_u01758938549738") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f40944ce3cc7720bb_d20250927_m020336_c001_v7007000_t0000_u01758938616905") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114a2d3bee30c573_d20250927_m020335_c001_v0001182_t0056_u01758938615700") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1165a0e015ff3668_d20250927_m020333_c001_v0001101_t0041_u01758938613648") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f40944ce3cc7720b2_d20250927_m020327_c001_v7007000_t0000_u01758938607526") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1129a622f9675639_d20250927_m020326_c001_v0001172_t0053_u01758938606238") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10012bbe5bbc1594_d20250927_m020323_c001_v0001144_t0022_u01758938603939") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f40944ce3cc7720af_d20250927_m020317_c001_v7007000_t0000_u01758938597020") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1167e465e58fd8e6_d20250927_m020315_c001_v0001171_t0029_u01758938595716") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11601c5f9c9004bf_d20250927_m020313_c001_v0001182_t0002_u01758938593567") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f41881426012fa0f6_d20250927_m020248_c001_v7007000_t0000_u01758938568689") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f106da98c8ef8be36_d20250927_m020247_c001_v0001120_t0028_u01758938567264") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1095a9d0dfd2181a_d20250927_m020245_c001_v0001181_t0033_u01758938565441") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39eb60_d20250927_m020237_c001_v7007000_t0000_u01758938557711") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1093ee5da8ab007a_d20250927_m020236_c001_v0001182_t0055_u01758938556488") 2025/09/27 02:03:43 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1165a0e015ff35d9_d20250927_m020234_c001_v0001101_t0053_u01758938554384") 2025/09/27 02:03:44 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:03:44 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c38414f_d20250927_m020331_c001_v7007000_t0000_u01758938611825") 2025/09/27 02:03:44 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f103bf098bd448b95_d20250927_m020253_c001_v0001104_t0000_u01758938573348") 2025/09/27 02:03:44 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b22885fa_d20250927_m020243_c001_v7007000_t0000_u01758938563581") 2025/09/27 02:03:44 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101b951be2fb9e02_d20250927_m020232_c001_v0001032_t0058_u01758938552000") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384150_d20250927_m020332_c001_v7007000_t0000_u01758938612007") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1128704bbe0cf7c1_d20250927_m020322_c001_v0001182_t0007_u01758938602501") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f422b0d72a5278ded_d20250927_m020255_c001_v7007000_t0000_u01758938575837") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114cb7dbfa2f217d_d20250927_m020253_c001_v0001105_t0054_u01758938573716") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b22885fd_d20250927_m020243_c001_v7007000_t0000_u01758938563764") 2025/09/27 02:03:44 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114101c9e43a1cdc_d20250927_m020231_c001_v0001157_t0038_u01758938551609") 2025/09/27 02:03:44 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384151_d20250927_m020332_c001_v7007000_t0000_u01758938612188") 2025/09/27 02:03:45 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110d497dda5aad89_d20250927_m020253_c001_v0001101_t0021_u01758938573731") 2025/09/27 02:03:45 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b2288600_d20250927_m020243_c001_v7007000_t0000_u01758938563952") 2025/09/27 02:03:45 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11897d20949b4b2d_d20250927_m020231_c001_v0001037_t0024_u01758938551421") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384152_d20250927_m020332_c001_v7007000_t0000_u01758938612369") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1034c53ba1668be5_d20250927_m020322_c001_v0001040_t0038_u01758938602738") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1043d40baea15685_d20250927_m020300_c001_v0001153_t0019_u01758938580659") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f107c021b6e1e694a_d20250927_m020253_c001_v0001172_t0039_u01758938573735") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b2288603_d20250927_m020244_c001_v7007000_t0000_u01758938564135") 2025/09/27 02:03:45 DEBUG : file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1142abd4e219b222_d20250927_m020231_c001_v0001184_t0050_u01758938551787") 2025/09/27 02:03:45 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384153_d20250927_m020332_c001_v7007000_t0000_u01758938612549") 2025/09/27 02:03:45 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f116c8d604fc76b39_d20250927_m020304_c001_v0001178_t0028_u01758938584032") 2025/09/27 02:03:45 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110fd2dc08f54866_d20250927_m020254_c001_v0001117_t0027_u01758938574032") 2025/09/27 02:03:45 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b2288607_d20250927_m020244_c001_v7007000_t0000_u01758938564321") 2025/09/27 02:03:45 DEBUG : file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11897d20949b4b2f_d20250927_m020231_c001_v0001037_t0047_u01758938551782") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384154_d20250927_m020332_c001_v7007000_t0000_u01758938612730") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f118e2a7c7e6f7dd8_d20250927_m020322_c001_v0001091_t0042_u01758938602867") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f118666603ec19613_d20250927_m020306_c001_v0001041_t0032_u01758938586839") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1075f79e45a270af_d20250927_m020254_c001_v0001136_t0031_u01758938574058") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b228860b_d20250927_m020244_c001_v7007000_t0000_u01758938564504") 2025/09/27 02:03:45 DEBUG : file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1142abd4e219b224_d20250927_m020231_c001_v0001184_t0047_u01758938551983") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384156_d20250927_m020332_c001_v7007000_t0000_u01758938612912") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105779614e6552be_d20250927_m020322_c001_v0001109_t0006_u01758938602877") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1187d1ff1d30ed68_d20250927_m020312_c001_v0001146_t0035_u01758938592811") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10670f4509160a11_d20250927_m020254_c001_v0001100_t0014_u01758938574075") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b228860f_d20250927_m020244_c001_v7007000_t0000_u01758938564687") 2025/09/27 02:03:46 DEBUG : file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114101c9e43a1ce0_d20250927_m020231_c001_v0001157_t0051_u01758938551996") 2025/09/27 02:03:46 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c384157_d20250927_m020333_c001_v7007000_t0000_u01758938613092") 2025/09/27 02:03:46 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1072713c0f9d0dd9_d20250927_m020254_c001_v0001180_t0009_u01758938574174") 2025/09/27 02:03:46 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b2288612_d20250927_m020244_c001_v7007000_t0000_u01758938564875") 2025/09/27 02:03:46 DEBUG : file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11897d20949b4b33_d20250927_m020232_c001_v0001037_t0050_u01758938552148") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4173808e5f0ceace_d20250927_m020340_c001_v7007000_t0000_u01758938620933") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f115f4b5ecfafd89e_d20250927_m020339_c001_v0001134_t0017_u01758938619630") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1142abd4e219b3b5_d20250927_m020337_c001_v0001184_t0054_u01758938617464") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f418913363c38414e_d20250927_m020331_c001_v7007000_t0000_u01758938611262") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f102bdae0b0f77a30_d20250927_m020330_c001_v0001165_t0056_u01758938610068") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11897d20949b4b8a_d20250927_m020328_c001_v0001037_t0003_u01758938608088") 2025/09/27 02:03:46 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f407bccd386ee57e8_d20250927_m020321_c001_v7007000_t0000_u01758938601240") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f106ef21e650a269a_d20250927_m020320_c001_v0001180_t0050_u01758938600051") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101b951be2fb9e52_d20250927_m020317_c001_v0001032_t0055_u01758938597568") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f410217911d2bd5ef_d20250927_m020252_c001_v7007000_t0000_u01758938572757") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f112e24b7b53a7dba_d20250927_m020251_c001_v0001157_t0045_u01758938571475") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114101c9e43a1d07_d20250927_m020249_c001_v0001157_t0029_u01758938569240") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f403c2324b22885e0_d20250927_m020241_c001_v7007000_t0000_u01758938561688") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1000ecf7c9e5ddac_d20250927_m020240_c001_v0001165_t0018_u01758938560458") 2025/09/27 02:03:47 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1142abd4e219b23f_d20250927_m020238_c001_v0001184_t0031_u01758938558269") bisync_test.go:608: TEST resync PASSED === RUN TestBisyncRemoteRemote/resync_modes 2025/09/27 02:03:47 INFO : path1: Making directory 2025/09/27 02:03:47 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1" 2025/09/27 02:03:47 DEBUG : Config file has changed externally - reloading 2025/09/27 02:03:48 INFO : path2: Making directory 2025/09/27 02:03:48 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2" 2025/09/27 02:03:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial" 2025/09/27 02:03:49 DEBUG : Creating backend with remote "/tmp/020222pa/initdir/test_resync_modes-natixav2" 2025/09/27 02:03:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : Local file system at /tmp/020222pa/initdir/test_resync_modes-natixav2: Waiting for checks to finish 2025/09/27 02:03:49 DEBUG : Local file system at /tmp/020222pa/initdir/test_resync_modes-natixav2: Waiting for transfers to finish 2025/09/27 02:03:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:03:49 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:03:49 INFO : file1.txt: Copied (new) 2025/09/27 02:03:49 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:03:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:03:49 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:03:49 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:03:49 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:03:49 INFO : file2.txt: Copied (new) 2025/09/27 02:03:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles" 2025/09/27 02:03:49 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:03:49 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:49 DEBUG : Local file system at /tmp/020222pa/datadir/test_resync_modes-bafehep2: Waiting for checks to finish 2025/09/27 02:03:49 DEBUG : Local file system at /tmp/020222pa/datadir/test_resync_modes-bafehep2: Waiting for transfers to finish 2025/09/27 02:03:49 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK 2025/09/27 02:03:49 DEBUG : file1R.txt.d467e63a.partial: renamed to: file1R.txt 2025/09/27 02:03:49 INFO : file1R.txt: Copied (new) 2025/09/27 02:03:49 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2025/09/27 02:03:49 DEBUG : file2.txt.ac4156f2.partial: renamed to: file2.txt 2025/09/27 02:03:49 INFO : file2.txt: Copied (new) 2025/09/27 02:03:49 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2025/09/27 02:03:49 DEBUG : file1L.txt.684d937d.partial: renamed to: file1L.txt 2025/09/27 02:03:49 INFO : file1L.txt: Copied (new) 2025/09/27 02:03:49 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:03:50 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:03:51 NOTICE: checking initFs Local file system at /tmp/020222pa/initdir/test_resync_modes-natixav2 2025/09/27 02:03:52 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:03:52 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:52 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:52 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Waiting for checks to finish 2025/09/27 02:03:52 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Waiting for transfers to finish 2025/09/27 02:03:52 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:03:52 INFO : file1.txt: Copied (new) 2025/09/27 02:03:53 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:03:53 INFO : file2.txt: Copied (new) 2025/09/27 02:03:53 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:03:53 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:03:53 NOTICE: checking Path1 B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1 2025/09/27 02:03:53 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:03:53 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:53 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:03:53 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Waiting for checks to finish 2025/09/27 02:03:53 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Waiting for transfers to finish 2025/09/27 02:03:54 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:03:54 INFO : file1.txt: Copied (new) 2025/09/27 02:03:54 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:03:54 INFO : file2.txt: Copied (new) 2025/09/27 02:03:55 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:03:55 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:03:55 NOTICE: checking path2 B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2 2025/09/27 02:03:55 NOTICE: (01) : test resync-mode 2025/09/27 02:03:55 NOTICE: (02) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:03:55 NOTICE: (03) : touch-glob 2001-01-02 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:03:55 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" 2025/09/27 02:03:55 DEBUG : fs cache: renaming cache item "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" to be canonical "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:03:55 DEBUG : file1R.txt: Set modification time 2001-01-02 2025/09/27 02:03:55 NOTICE: (04) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:03:55 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:03:55 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:03:55 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:03:56 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:03:56 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0) 2025/09/27 02:03:58 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:03:58 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:03:58 NOTICE: (05) : touch-glob 2001-03-04 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:03:58 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:03:58 DEBUG : file1L.txt: Set modification time 2001-03-04 2025/09/27 02:03:58 NOTICE: (06) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:03:58 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:03:58 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:03:58 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:03:59 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:03:59 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0) 2025/09/27 02:04:01 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:04:01 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:04:01 NOTICE: (07) : test bisync run with --resync-mode=newer 2025/09/27 02:04:01 NOTICE: (08) : bisync resync resync-mode=newer 2025/09/27 02:04:09 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:09 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:09 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:04:09 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:04:09 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:09 INFO : Copying Path2 files to Path1 2025/09/27 02:04:09 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:04:09 INFO : There was nothing to transfer 2025/09/27 02:04:09 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:04:09 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:04:09 INFO : Resync updating listings 2025/09/27 02:04:10 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:10 INFO : Bisync successful 2025/09/27 02:04:10 NOTICE: (09) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:04:10 NOTICE: (10) : touch-glob 2001-07-23 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:04:10 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:10 DEBUG : file1R.txt: Set modification time 2001-07-23 2025/09/27 02:04:10 NOTICE: (11) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:04:10 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:04:10 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:04:10 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:11 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:04:11 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33) 2025/09/27 02:04:15 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:04:15 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:04:15 NOTICE: (12) : touch-glob 2001-08-26 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:04:15 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:15 DEBUG : file1L.txt: Set modification time 2001-08-26 2025/09/27 02:04:15 NOTICE: (13) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:04:15 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:04:15 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:04:15 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:04:16 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:04:16 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/09/27 02:04:16 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:04:18 INFO : file1L.txt: Updated modification time in destination 2025/09/27 02:04:18 DEBUG : file1L.txt: Unchanged skipping 2025/09/27 02:04:18 NOTICE: (14) : test bisync run with --resync-mode=path2 2025/09/27 02:04:18 NOTICE: (15) : bisync resync resync-mode=path2 2025/09/27 02:04:26 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:26 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:26 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:04:26 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:04:26 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:26 INFO : Copying Path2 files to Path1 2025/09/27 02:04:26 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:04:27 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:04:27 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:04:27 INFO : Resync updating listings 2025/09/27 02:04:27 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:27 INFO : Bisync successful 2025/09/27 02:04:27 NOTICE: (16) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:04:27 NOTICE: (17) : touch-glob 2002-07-23 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:04:27 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:27 DEBUG : file1R.txt: Set modification time 2002-07-23 2025/09/27 02:04:27 NOTICE: (18) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:04:27 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:04:27 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:04:27 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:28 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:04:28 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/09/27 02:04:28 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:04:29 INFO : file1R.txt: Updated modification time in destination 2025/09/27 02:04:29 DEBUG : file1R.txt: Unchanged skipping 2025/09/27 02:04:29 NOTICE: (19) : touch-glob 2002-08-26 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:04:29 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:29 DEBUG : file1L.txt: Set modification time 2002-08-26 2025/09/27 02:04:29 NOTICE: (20) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:04:29 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:04:29 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:04:29 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:04:31 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:04:31 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42) 2025/09/27 02:04:32 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:04:32 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:04:32 NOTICE: (21) : test bisync run with --resync-mode=larger 2025/09/27 02:04:32 NOTICE: (22) : bisync resync resync-mode=larger 2025/09/27 02:04:42 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:42 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:42 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:04:42 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:04:42 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:42 INFO : Copying Path2 files to Path1 2025/09/27 02:04:42 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:04:42 INFO : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9 2025/09/27 02:04:43 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:04:43 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:04:43 INFO : Resync updating listings 2025/09/27 02:04:43 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:43 INFO : Bisync successful 2025/09/27 02:04:43 NOTICE: (23) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:04:43 NOTICE: (24) : touch-glob 2003-07-23 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:04:43 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:43 DEBUG : file1R.txt: Set modification time 2003-07-23 2025/09/27 02:04:43 NOTICE: (25) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:04:43 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:04:43 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:04:43 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:44 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:04:44 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/09/27 02:04:44 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:04:46 INFO : file1R.txt: Updated modification time in destination 2025/09/27 02:04:46 DEBUG : file1R.txt: Unchanged skipping 2025/09/27 02:04:46 NOTICE: (26) : touch-glob 2003-09-04 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:04:46 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:46 DEBUG : file1L.txt: Set modification time 2003-09-04 2025/09/27 02:04:46 NOTICE: (27) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:04:46 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:04:46 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:04:46 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:04:47 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:04:47 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42) 2025/09/27 02:04:49 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:04:49 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:04:49 NOTICE: (28) : test bisync run with --resync-mode=older 2025/09/27 02:04:49 NOTICE: (29) : bisync resync resync-mode=older 2025/09/27 02:04:57 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:57 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:04:57 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:04:57 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:04:57 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:57 INFO : Copying Path2 files to Path1 2025/09/27 02:04:57 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:04:57 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/09/27 02:04:58 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:04:58 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:04:58 INFO : Resync updating listings 2025/09/27 02:04:58 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:58 INFO : Bisync successful 2025/09/27 02:04:58 NOTICE: (30) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:04:58 NOTICE: (31) : touch-glob 2004-07-23 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:04:58 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:04:58 DEBUG : file1R.txt: Set modification time 2004-07-23 2025/09/27 02:04:58 NOTICE: (32) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:04:58 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:04:58 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:04:58 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:04:59 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:05:00 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/09/27 02:05:00 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:05:01 INFO : file1R.txt: Updated modification time in destination 2025/09/27 02:05:01 DEBUG : file1R.txt: Unchanged skipping 2025/09/27 02:05:01 NOTICE: (33) : touch-glob 2004-07-23 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:05:01 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:05:01 DEBUG : file1L.txt: Set modification time 2004-07-23 2025/09/27 02:05:01 NOTICE: (34) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:05:01 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:05:01 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:05:01 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:05:02 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:05:02 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42) 2025/09/27 02:05:04 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:05:04 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:05:04 NOTICE: (35) : test bisync run with --resync-mode=smaller 2025/09/27 02:05:04 NOTICE: (36) : bisync resync resync-mode=smaller 2025/09/27 02:05:13 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:13 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:05:13 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:05:13 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:13 INFO : Copying Path2 files to Path1 2025/09/27 02:05:13 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:05:13 INFO : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9 2025/09/27 02:05:13 INFO : There was nothing to transfer 2025/09/27 02:05:13 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:05:13 INFO : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9 2025/09/27 02:05:14 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:05:14 INFO : Resync updating listings 2025/09/27 02:05:14 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:14 INFO : Bisync successful 2025/09/27 02:05:14 NOTICE: (37) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2025/09/27 02:05:14 NOTICE: (38) : touch-glob 2005-01-02 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:05:14 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:05:14 DEBUG : file1R.txt: Set modification time 2005-01-02 2025/09/27 02:05:14 NOTICE: (39) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:05:14 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:05:14 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:05:14 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:15 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:05:15 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33) 2025/09/27 02:05:17 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:05:17 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:05:17 NOTICE: (40) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file2.txt 2025/09/27 02:05:17 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file2.txt" 2025/09/27 02:05:17 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:05:17 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:05:18 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file2.txt" 2025/09/27 02:05:18 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 0) 2025/09/27 02:05:20 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:05:20 INFO : file1R.txt: Copied (replaced existing) to: file2.txt 2025/09/27 02:05:20 NOTICE: (41) : touch-glob 2006-03-04 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:05:20 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:05:20 DEBUG : file1L.txt: Set modification time 2006-03-04 2025/09/27 02:05:20 NOTICE: (42) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:05:20 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:05:20 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:05:20 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:05:21 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:05:22 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/09/27 02:05:22 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:05:23 INFO : file1L.txt: Updated modification time in destination 2025/09/27 02:05:23 DEBUG : file1L.txt: Unchanged skipping 2025/09/27 02:05:23 NOTICE: (43) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file2.txt 2025/09/27 02:05:23 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file2.txt" 2025/09/27 02:05:23 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:05:23 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:24 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file2.txt" 2025/09/27 02:05:24 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 0) 2025/09/27 02:05:26 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:05:26 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2025/09/27 02:05:26 NOTICE: (44) : test bisync run with --resync-mode=path1 2025/09/27 02:05:26 NOTICE: (45) : bisync resync resync-mode=path1 2025/09/27 02:05:34 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:34 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:34 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:05: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/09/27 02:05:34 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:34 INFO : Copying Path2 files to Path1 2025/09/27 02:05:34 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:05:34 INFO : There was nothing to transfer 2025/09/27 02:05:34 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:05:35 INFO : file2.txt: Copied (server-side copy) 2025/09/27 02:05:36 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:05:36 INFO : Resync updating listings 2025/09/27 02:05:36 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:36 INFO : Bisync successful 2025/09/27 02:05:36 NOTICE: (46) : test no winner 2025/09/27 02:05:36 NOTICE: (47) : touch-glob 2022-02-22 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1R.txt 2025/09/27 02:05:36 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:05:36 DEBUG : file1R.txt: Set modification time 2022-02-22 2025/09/27 02:05:36 NOTICE: (48) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file1.txt 2025/09/27 02:05:36 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file1.txt" 2025/09/27 02:05:36 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:05:36 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:37 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file1.txt" 2025/09/27 02:05:37 DEBUG : file1R.txt: Sizes differ (src 42 vs dst 33) 2025/09/27 02:05:39 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:05:39 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2025/09/27 02:05:39 NOTICE: (49) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file2.txt 2025/09/27 02:05:39 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file2.txt" 2025/09/27 02:05:39 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1R.txt" 2025/09/27 02:05:39 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:05:40 DEBUG : operations.CopyFile "file1R.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file2.txt" 2025/09/27 02:05:40 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/09/27 02:05:40 DEBUG : file1R.txt: sha1 = 2618b868824bd0a3b40f52a600bdf9f1a11b6752 OK 2025/09/27 02:05:42 INFO : file1R.txt: Updated modification time in destination 2025/09/27 02:05:42 DEBUG : file1R.txt: Unchanged skipping 2025/09/27 02:05:42 NOTICE: (50) : touch-glob 2022-02-22 /tmp/020222pa/datadir/test_resync_modes-bafehep2/ file1L.txt 2025/09/27 02:05:42 DEBUG : fs cache: switching user supplied name "/tmp/020222pa/datadir/test_resync_modes-bafehep2/" for canonical name "/tmp/020222pa/datadir/test_resync_modes-bafehep2" 2025/09/27 02:05:42 DEBUG : file1L.txt: Set modification time 2022-02-22 2025/09/27 02:05:42 NOTICE: (51) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/ file1.txt 2025/09/27 02:05:42 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" as "file1.txt" 2025/09/27 02:05:42 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:05:42 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" 2025/09/27 02:05:43 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1" as "file1.txt" 2025/09/27 02:05:43 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/09/27 02:05:43 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:05:44 INFO : file1L.txt: Updated modification time in destination 2025/09/27 02:05:44 DEBUG : file1L.txt: Unchanged skipping 2025/09/27 02:05:44 NOTICE: (52) : copy-as /tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/ file2.txt 2025/09/27 02:05:44 DEBUG : copyFile "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" to "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" as "file2.txt" 2025/09/27 02:05:44 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_resync_modes-bafehep2/file1L.txt" 2025/09/27 02:05:44 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:45 DEBUG : operations.CopyFile "file1L.txt" to "B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2" as "file2.txt" 2025/09/27 02:05:45 DEBUG : file1L.txt: Sizes differ (src 33 vs dst 42) 2025/09/27 02:05:47 DEBUG : file1L.txt: sha1 = 6ff9acdd8109de3e4aab0bc72d8fa377c85d59dd OK 2025/09/27 02:05:47 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2025/09/27 02:05:47 NOTICE: (53) : test bisync run with --resync-mode=newer 2025/09/27 02:05:47 NOTICE: (54) : bisync resync resync-mode=newer 2025/09/27 02:05:56 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:56 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: Skipping jamDirTimes as remote does not support DirSetModTime or MkdirMetadata 2025/09/27 02:05:56 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/09/27 02:05:56 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/09/27 02:05:56 INFO : Synching Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" with Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:56 INFO : Copying Path2 files to Path1 2025/09/27 02:05:56 INFO : - Path2 Resync is copying files to - Path1 2025/09/27 02:05:56 INFO : file2.txt: Copied (server-side copy) 2025/09/27 02:05:57 INFO : file1.txt: Copied (server-side copy) 2025/09/27 02:05:57 INFO : - Path1 Resync is copying files to - Path2 2025/09/27 02:05:57 INFO : Resync updating listings 2025/09/27 02:05:57 INFO : Validating listings for Path1 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path1/" vs Path2 "TestB2:rclone-test-dewidul8qoyu/020222pa/resync_modes/path2/" 2025/09/27 02:05:57 INFO : Bisync successful 2025/09/27 02:05:57 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:05:57 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f102e0370c11e53d5_d20250927_m020353_c001_v0001180_t0059_u01758938633056") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11939ef8a7ce998b_d20250927_m020556_c001_v0001104_t0050_u01758938756673") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105ee4ecdc2dc1ff_d20250927_m020544_c001_v0001184_t0057_u01758938744590") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1155435e65469281_d20250927_m020523_c001_v0001165_t0006_u01758938723057") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f102e0370c11e5537_d20250927_m020504_c001_v0001180_t0007_u01758938704341") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f108ee6bafa81ad35_d20250927_m020457_c001_v0001040_t0008_u01758938697934") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f112e9c1573a27c91_d20250927_m020448_c001_v0001161_t0030_u01758938688988") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f104ed4204298e197_d20250927_m020443_c001_v0001090_t0056_u01758938683092") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f111fd1580a53c52c_d20250927_m020432_c001_v0001161_t0022_u01758938672630") 2025/09/27 02:05:57 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f118ab8f32dd78728_d20250927_m020427_c001_v0001165_t0050_u01758938667173") 2025/09/27 02:05:58 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1183deddd807ef12_d20250927_m020417_c001_v0001120_t0053_u01758938657853") 2025/09/27 02:05:58 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f100365d83b1610f8_d20250927_m020400_c001_v0001117_t0042_u01758938640908") 2025/09/27 02:05:58 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10827aabd276201b_d20250927_m020352_c001_v0001181_t0036_u01758938632690") 2025/09/27 02:05:58 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1058b7dbf150fad7_d20250927_m020556_c001_v0001171_t0039_u01758938756336") 2025/09/27 02:05:58 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f113781f34e9e43c1_d20250927_m020541_c001_v0001041_t0015_u01758938741920") 2025/09/27 02:05:58 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1046bb3f7bfceceb_d20250927_m020519_c001_v0001117_t0008_u01758938719989") 2025/09/27 02:05:58 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f115711bd7faa1614_d20250927_m020352_c001_v0001130_t0015_u01758938632885") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f423de3327003969c_d20250927_m020551_c001_v7007000_t0000_u01758938751560") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105ee4ecdc2dc245_d20250927_m020550_c001_v0001184_t0024_u01758938750301") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10827aabd27622bc_d20250927_m020548_c001_v0001181_t0019_u01758938748143") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f42136b7bf68dea36_d20250927_m020530_c001_v7007000_t0000_u01758938730122") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f118da9bf6cf2d2ea_d20250927_m020528_c001_v0001100_t0031_u01758938728811") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f102e0370c11e55d6_d20250927_m020526_c001_v0001180_t0013_u01758938726647") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4132479332c40d20_d20250927_m020508_c001_v7007000_t0000_u01758938708615") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f113476576c02b42d_d20250927_m020507_c001_v0001136_t0058_u01758938707169") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f115711bd7faa170a_d20250927_m020504_c001_v0001130_t0040_u01758938704945") 2025/09/27 02:05:58 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f41961440aff44570_d20250927_m020453_c001_v7007000_t0000_u01758938693151") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11500886fbd42d48_d20250927_m020451_c001_v0001171_t0057_u01758938691866") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10827aabd2762131_d20250927_m020449_c001_v0001181_t0000_u01758938689657") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f423de3327003949a_d20250927_m020437_c001_v7007000_t0000_u01758938677329") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1138737c984f8148_d20250927_m020435_c001_v0001098_t0007_u01758938675521") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f102e0370c11e5498_d20250927_m020433_c001_v0001180_t0049_u01758938673311") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4132479332c40c21_d20250927_m020422_c001_v7007000_t0000_u01758938662289") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1038db0cddfec0fd_d20250927_m020420_c001_v0001157_t0055_u01758938660915") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f115711bd7faa165f_d20250927_m020418_c001_v0001130_t0023_u01758938658572") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4203db09103cad8f_d20250927_m020405_c001_v7007000_t0000_u01758938645107") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1091609c54a73109_d20250927_m020403_c001_v0001090_t0027_u01758938643805") 2025/09/27 02:05:59 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10827aabd2762047_d20250927_m020401_c001_v0001181_t0025_u01758938641655") 2025/09/27 02:06:00 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/resync_modes/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:00 DEBUG : RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101050e473649573_d20250927_m020354_c001_v0001106_t0040_u01758938634617") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f103084e2f4b528c9_d20250927_m020539_c001_v0001146_t0010_u01758938739113") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1165a0e015ff3990_d20250927_m020535_c001_v0001101_t0007_u01758938735593") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f113d514d797dbeaf_d20250927_m020517_c001_v0001178_t0003_u01758938717132") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f107f1934de87e1a6_d20250927_m020513_c001_v0001130_t0010_u01758938713997") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1083e2424d1c62aa_d20250927_m020501_c001_v0001099_t0031_u01758938701159") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f111f3d9d27812e14_d20250927_m020446_c001_v0001180_t0021_u01758938686064") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1002e46875f37be8_d20250927_m020429_c001_v0001184_t0051_u01758938669839") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1178f006f3dafece_d20250927_m020412_c001_v0001032_t0018_u01758938652692") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f107aa2301974984d_d20250927_m020409_c001_v0001184_t0013_u01758938649862") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110303df936d912a_d20250927_m020358_c001_v0001100_t0028_u01758938638004") 2025/09/27 02:06:00 DEBUG : file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110e5792a6468c3d_d20250927_m020354_c001_v0001134_t0004_u01758938634237") 2025/09/27 02:06:00 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f114cb7dbfa2f24be_d20250927_m020547_c001_v0001105_t0059_u01758938747364") 2025/09/27 02:06:00 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105ea604498da126_d20250927_m020535_c001_v0001038_t0032_u01758938735151") 2025/09/27 02:06:00 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f112f04981f5eca39_d20250927_m020525_c001_v0001150_t0045_u01758938725923") 2025/09/27 02:06:01 DEBUG : file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11707608c6fcb467_d20250927_m020354_c001_v0001109_t0029_u01758938634430") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4095ea60f3443146_d20250927_m020555_c001_v7007000_t0000_u01758938755897") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11309be4f9f642c4_d20250927_m020554_c001_v0001172_t0038_u01758938754603") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110e5792a6468e3b_d20250927_m020552_c001_v0001134_t0026_u01758938752119") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f423de332700395d6_d20250927_m020534_c001_v7007000_t0000_u01758938734565") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101fd96899d4ec58_d20250927_m020533_c001_v0001091_t0019_u01758938733240") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101050e473649704_d20250927_m020530_c001_v0001106_t0047_u01758938730704") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4203db09103caea6_d20250927_m020513_c001_v7007000_t0000_u01758938713300") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f117b7a044f785a0d_d20250927_m020512_c001_v0001165_t0038_u01758938712079") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11707608c6fcb528_d20250927_m020509_c001_v0001109_t0046_u01758938709170") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f407bccd386ee5a15_d20250927_m020457_c001_v7007000_t0000_u01758938697419") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1161636a12a988d1_d20250927_m020455_c001_v0001029_t0029_u01758938695987") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110e5792a6468d1c_d20250927_m020453_c001_v0001134_t0051_u01758938693712") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f4195ca0290ac78a0_d20250927_m020442_c001_v7007000_t0000_u01758938682041") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1187d1ff1d30ee5a_d20250927_m020440_c001_v0001146_t0002_u01758938680429") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f101050e473649612_d20250927_m020437_c001_v0001106_t0058_u01758938677900") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f40838f7c6d338555_d20250927_m020426_c001_v7007000_t0000_u01758938666666") 2025/09/27 02:06:01 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11287074b5756e24_d20250927_m020425_c001_v0001181_t0010_u01758938665459") 2025/09/27 02:06:02 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11707608c6fcb4b1_d20250927_m020422_c001_v0001109_t0033_u01758938662868") 2025/09/27 02:06:02 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f416b28e2ae39ec2c_d20250927_m020409_c001_v7007000_t0000_u01758938649281") 2025/09/27 02:06:02 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f100ca612bcd818f1_d20250927_m020407_c001_v0001137_t0004_u01758938647885") 2025/09/27 02:06:02 DEBUG : modtime_write_test: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f110e5792a6468c62_d20250927_m020405_c001_v0001134_t0055_u01758938645659") bisync_test.go:608: TEST resync_modes PASSED === RUN TestBisyncRemoteRemote/rmdirs 2025/09/27 02:06:02 INFO : path1: Making directory 2025/09/27 02:06:02 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/rmdirs/path1" 2025/09/27 02:06:03 INFO : path2: Making directory 2025/09/27 02:06:03 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/rmdirs/path2" 2025/09/27 02:06:04 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial" 2025/09/27 02:06:04 DEBUG : Creating backend with remote "/tmp/020222pa/initdir/test_rmdirs-pimafab0" 2025/09/27 02:06:04 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : subdir: Making directory with metadata 2025/09/27 02:06:04 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2025/09/27 02:06:04 DEBUG : Added delayed dir = "subdir", newDst=subdir 2025/09/27 02:06:04 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : file1.copy1.txt.21b8fa2b.partial: renamed to: file1.copy1.txt 2025/09/27 02:06:04 INFO : file1.copy1.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : Local file system at /tmp/020222pa/initdir/test_rmdirs-pimafab0: Waiting for checks to finish 2025/09/27 02:06:04 DEBUG : Local file system at /tmp/020222pa/initdir/test_rmdirs-pimafab0: Waiting for transfers to finish 2025/09/27 02:06:04 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:06:04 DEBUG : file1.copy2.txt.455881d5.partial: renamed to: file1.copy2.txt 2025/09/27 02:06:04 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 INFO : file1.copy2.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:06:04 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:04 DEBUG : file1.copy4.txt.8c987629.partial: renamed to: file1.copy4.txt 2025/09/27 02:06:04 INFO : file1.copy4.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : file1.copy3.txt.d0285540.partial: renamed to: file1.copy3.txt 2025/09/27 02:06:04 INFO : file1.copy3.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:04 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:06:04 INFO : file1.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : subdir/file20.txt.1ac3db2f.partial: renamed to: subdir/file20.txt 2025/09/27 02:06:04 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:06:04 DEBUG : file1.copy5.txt.19e8a2bc.partial: renamed to: file1.copy5.txt 2025/09/27 02:06:04 INFO : file1.copy5.txt: Copied (new) 2025/09/27 02:06:04 INFO : subdir: Set directory modification time (using SetModTime) 2025/09/27 02:06:04 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles" 2025/09/27 02:06:04 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_rmdirs-xehonoy5" 2025/09/27 02:06:04 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:04 DEBUG : Local file system at /tmp/020222pa/datadir/test_rmdirs-xehonoy5: Waiting for checks to finish 2025/09/27 02:06:04 DEBUG : Local file system at /tmp/020222pa/datadir/test_rmdirs-xehonoy5: Waiting for transfers to finish 2025/09/27 02:06:04 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:04 DEBUG : file1.txt.f41fd5ee.partial: renamed to: file1.txt 2025/09/27 02:06:04 INFO : file1.txt: Copied (new) 2025/09/27 02:06:04 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:05 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:06 NOTICE: checking initFs Local file system at /tmp/020222pa/initdir/test_rmdirs-pimafab0 2025/09/27 02:06:07 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 INFO : subdir: Making directory 2025/09/27 02:06:07 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:07 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path1: Waiting for checks to finish 2025/09/27 02:06:07 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path1: Waiting for transfers to finish 2025/09/27 02:06:07 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:07 INFO : file1.copy1.txt: Copied (new) 2025/09/27 02:06:08 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:08 INFO : file1.copy3.txt: Copied (new) 2025/09/27 02:06:08 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:08 INFO : file1.copy4.txt: Copied (new) 2025/09/27 02:06:08 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:08 INFO : file1.copy2.txt: Copied (new) 2025/09/27 02:06:08 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:08 INFO : file1.copy5.txt: Copied (new) 2025/09/27 02:06:08 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:06:08 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:08 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:08 INFO : file1.txt: Copied (new) 2025/09/27 02:06:09 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:09 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:06:09 NOTICE: checking Path1 B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path1 2025/09/27 02:06:09 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 INFO : subdir: Making directory 2025/09/27 02:06:09 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:09 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path2: Waiting for checks to finish 2025/09/27 02:06:09 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path2: Waiting for transfers to finish 2025/09/27 02:06:10 DEBUG : file1.copy1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:10 INFO : file1.copy1.txt: Copied (new) 2025/09/27 02:06:10 DEBUG : file1.copy2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:10 INFO : file1.copy2.txt: Copied (new) 2025/09/27 02:06:10 DEBUG : file1.copy4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:10 INFO : file1.copy4.txt: Copied (new) 2025/09/27 02:06:10 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:06:10 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:10 DEBUG : file1.copy5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:10 INFO : file1.copy5.txt: Copied (new) 2025/09/27 02:06:10 DEBUG : file1.copy3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:10 INFO : file1.copy3.txt: Copied (new) 2025/09/27 02:06:11 DEBUG : subdir/file20.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:11 INFO : subdir/file20.txt: Copied (new) 2025/09/27 02:06:11 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:11 INFO : file1.txt: Copied (new) 2025/09/27 02:06:11 NOTICE: checking path2 B2 bucket rclone-test-dewidul8qoyu path 020222pa/rmdirs/path2 2025/09/27 02:06:11 NOTICE: (01) : test rmdirs 2025/09/27 02:06:11 NOTICE: (02) : test initial bisync 2025/09/27 02:06:11 NOTICE: (03) : bisync resync bisync_test.go:1007: skipping test as remote does not support empty dirs === RUN TestBisyncRemoteRemote/volatile 2025/09/27 02:06:11 INFO : path1: Making directory 2025/09/27 02:06:11 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/volatile/path1" 2025/09/27 02:06:12 INFO : path2: Making directory 2025/09/27 02:06:12 DEBUG : Creating backend with remote "TestB2:rclone-test-dewidul8qoyu/020222pa/volatile/path2" 2025/09/27 02:06:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial" 2025/09/27 02:06:13 DEBUG : Creating backend with remote "/tmp/020222pa/initdir/test_volatile-jolorum5" 2025/09/27 02:06:13 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file1.txt.56d0d699.partial: renamed to: file1.txt 2025/09/27 02:06:13 INFO : file1.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file2.txt.3230ad67.partial: renamed to: file2.txt 2025/09/27 02:06:13 INFO : file2.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file3.txt.a74079f2.partial: renamed to: file3.txt 2025/09/27 02:06:13 INFO : file3.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : Local file system at /tmp/020222pa/initdir/test_volatile-jolorum5: Waiting for checks to finish 2025/09/27 02:06:13 DEBUG : Local file system at /tmp/020222pa/initdir/test_volatile-jolorum5: Waiting for transfers to finish 2025/09/27 02:06:13 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file4.txt.fbf05a9b.partial: renamed to: file4.txt 2025/09/27 02:06:13 INFO : file4.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file5.txt.6e808e0e.partial: renamed to: file5.txt 2025/09/27 02:06:13 INFO : file5.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file6.txt.0a60f5f0.partial: renamed to: file6.txt 2025/09/27 02:06:13 INFO : file6.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2025/09/27 02:06:13 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : file7.txt.9f102165.partial: renamed to: file7.txt 2025/09/27 02:06:13 INFO : file7.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file8.txt.b300b322.partial: renamed to: file8.txt 2025/09/27 02:06:13 INFO : file8.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : RCLONE_TEST.abb09051.partial: renamed to: RCLONE_TEST 2025/09/27 02:06:13 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:13 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles" 2025/09/27 02:06:13 DEBUG : Creating backend with remote "/tmp/020222pa/datadir/test_volatile-bamawih9" 2025/09/27 02:06:13 DEBUG : dummy.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file10.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file11.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:13 DEBUG : Local file system at /tmp/020222pa/datadir/test_volatile-bamawih9: Waiting for checks to finish 2025/09/27 02:06:13 DEBUG : Local file system at /tmp/020222pa/datadir/test_volatile-bamawih9: Waiting for transfers to finish 2025/09/27 02:06:13 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2025/09/27 02:06:13 DEBUG : dummy.txt.b3782999.partial: renamed to: dummy.txt 2025/09/27 02:06:13 INFO : dummy.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:13 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:13 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:13 DEBUG : file1.txt.be52b1c9.partial: renamed to: file1.txt 2025/09/27 02:06:13 INFO : file1.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file10.txt.bdde3bf8.partial: renamed to: file10.txt 2025/09/27 02:06:13 INFO : file10.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file11.txt.d4a7309d.partial: renamed to: file11.txt 2025/09/27 02:06:13 INFO : file11.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2025/09/27 02:06:13 DEBUG : file2.txt.23ff1769.partial: renamed to: file2.txt 2025/09/27 02:06:13 INFO : file2.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2025/09/27 02:06:13 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:13 DEBUG : file5R.txt.ee3b9525.partial: renamed to: file5R.txt 2025/09/27 02:06:13 INFO : file5R.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2025/09/27 02:06:13 DEBUG : file6.txt.7b4c87b3.partial: renamed to: file6.txt 2025/09/27 02:06:13 INFO : file6.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file5L.txt.8dd6541a.partial: renamed to: file5L.txt 2025/09/27 02:06:13 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2025/09/27 02:06:13 INFO : file5L.txt: Copied (new) 2025/09/27 02:06:13 DEBUG : file7.txt.38c43fbf.partial: renamed to: file7.txt 2025/09/27 02:06:13 INFO : file7.txt: Copied (new) 2025/09/27 02:06:13 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path1: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:14 INFO : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path2: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:15 NOTICE: checking initFs Local file system at /tmp/020222pa/initdir/test_volatile-jolorum5 2025/09/27 02:06:15 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:15 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path1: Waiting for checks to finish 2025/09/27 02:06:15 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path1: Waiting for transfers to finish 2025/09/27 02:06:16 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:16 INFO : file1.txt: Copied (new) 2025/09/27 02:06:16 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:16 INFO : file2.txt: Copied (new) 2025/09/27 02:06:16 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:16 INFO : file4.txt: Copied (new) 2025/09/27 02:06:16 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:16 INFO : file3.txt: Copied (new) 2025/09/27 02:06:16 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:16 INFO : file5.txt: Copied (new) 2025/09/27 02:06:17 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:17 INFO : file8.txt: Copied (new) 2025/09/27 02:06:17 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:06:17 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:17 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:17 INFO : file6.txt: Copied (new) 2025/09/27 02:06:17 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:17 INFO : file7.txt: Copied (new) 2025/09/27 02:06:17 NOTICE: checking Path1 B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path1 2025/09/27 02:06:17 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file1.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file2.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file3.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file4.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file5.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file6.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file7.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : file8.txt: Need to transfer - File not found at Destination 2025/09/27 02:06:17 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path2: Waiting for checks to finish 2025/09/27 02:06:17 DEBUG : B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path2: Waiting for transfers to finish 2025/09/27 02:06:18 DEBUG : file1.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:18 INFO : file1.txt: Copied (new) 2025/09/27 02:06:18 DEBUG : file3.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:18 INFO : file3.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : file2.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file2.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : file4.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file4.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : file5.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file5.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : RCLONE_TEST: sha1 = c799ac3b0eacf2b6fc3e741a7bea196aa129aab2 OK 2025/09/27 02:06:19 INFO : RCLONE_TEST: Copied (new) 2025/09/27 02:06:19 DEBUG : file8.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file8.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : file6.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file6.txt: Copied (new) 2025/09/27 02:06:19 DEBUG : file7.txt: sha1 = da39a3ee5e6b4b0d3255bfef95601890afd80709 OK 2025/09/27 02:06:19 INFO : file7.txt: Copied (new) 2025/09/27 02:06:19 NOTICE: checking path2 B2 bucket rclone-test-dewidul8qoyu path 020222pa/volatile/path2 2025/09/27 02:06:19 NOTICE: (01) : test volatile 2025/09/27 02:06:19 NOTICE: (02) : test initial bisync 2025/09/27 02:06:19 NOTICE: (03) : bisync resync bisync_test.go:978: skipping 'volatile' test on non-local as it requires uploading 100 files 2025/09/27 02:06:19 DEBUG : B2 bucket rclone-test-dewidul8qoyu: Purge remote 2025/09/27 02:06:19 INFO : B2 bucket rclone-test-dewidul8qoyu: cleaning bucket "rclone-test-dewidul8qoyu" of all files 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1042f864b68ff972_d20250927_m020608_c001_v0001178_t0053_u01758938768227") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.copy1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1083e2424d1c63ac_d20250927_m020607_c001_v0001099_t0055_u01758938767660") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.copy2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10894a56a82d83ae_d20250927_m020607_c001_v0001144_t0013_u01758938767836") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.copy3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10455fa706d6e7ab_d20250927_m020608_c001_v0001184_t0011_u01758938768017") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.copy4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1083e2424d1c63b1_d20250927_m020608_c001_v0001099_t0031_u01758938768046") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.copy5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10455fa706d6e7af_d20250927_m020608_c001_v0001184_t0036_u01758938768221") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1083e2424d1c63b3_d20250927_m020608_c001_v0001099_t0016_u01758938768367") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path1/subdir/file20.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f10894a56a82d83b0_d20250927_m020608_c001_v0001144_t0012_u01758938768389") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path2/RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1122324782ee452a_d20250927_m020610_c001_v0001179_t0019_u01758938770644") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path2/file1.copy1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1080cd7958461279_d20250927_m020610_c001_v0001105_t0035_u01758938770098") 2025/09/27 02:06:20 DEBUG : 020222pa/rmdirs/path2/file1.copy2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1095a9d0dfd21c5d_d20250927_m020610_c001_v0001181_t0016_u01758938770433") 2025/09/27 02:06:21 DEBUG : 020222pa/rmdirs/path2/file1.copy3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1046593f3d54b4df_d20250927_m020610_c001_v0001171_t0007_u01758938770272") 2025/09/27 02:06:21 DEBUG : 020222pa/rmdirs/path2/file1.copy4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1080cd795846127d_d20250927_m020610_c001_v0001105_t0014_u01758938770401") 2025/09/27 02:06:21 DEBUG : 020222pa/rmdirs/path2/file1.copy5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1095a9d0dfd21c63_d20250927_m020610_c001_v0001181_t0049_u01758938770724") 2025/09/27 02:06:21 DEBUG : 020222pa/rmdirs/path2/file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1080cd7958461281_d20250927_m020610_c001_v0001105_t0038_u01758938770921") 2025/09/27 02:06:21 DEBUG : 020222pa/rmdirs/path2/subdir/file20.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1122324782ee4532_d20250927_m020610_c001_v0001179_t0028_u01758938770907") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f118b6e596882cbac_d20250927_m020616_c001_v0001130_t0016_u01758938776850") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f109c64a2419ee371_d20250927_m020616_c001_v0001136_t0043_u01758938776287") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1085d171c1a993e2_d20250927_m020616_c001_v0001177_t0049_u01758938776647") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105989c98a4d55f4_d20250927_m020616_c001_v0001098_t0000_u01758938776490") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f109c64a2419ee375_d20250927_m020616_c001_v0001136_t0045_u01758938776608") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1085d171c1a993e6_d20250927_m020616_c001_v0001177_t0001_u01758938776866") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f109c64a2419ee377_d20250927_m020616_c001_v0001136_t0020_u01758938776913") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f105989c98a4d55f6_d20250927_m020616_c001_v0001098_t0056_u01758938776963") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path1/file8.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f1085d171c1a993ea_d20250927_m020617_c001_v0001177_t0019_u01758938777078") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/RCLONE_TEST: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f116ee28f6cfb316b_d20250927_m020618_c001_v0001098_t0006_u01758938778931") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/file1.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11435620a1c26cdf_d20250927_m020618_c001_v0001171_t0007_u01758938778339") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/file2.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f112a8d77a13f691b_d20250927_m020618_c001_v0001102_t0009_u01758938778559") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/file3.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11685bd628413505_d20250927_m020618_c001_v0001177_t0056_u01758938778720") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/file4.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11435620a1c26ce1_d20250927_m020618_c001_v0001171_t0053_u01758938778814") 2025/09/27 02:06:21 DEBUG : 020222pa/volatile/path2/file5.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11685bd62841350b_d20250927_m020618_c001_v0001177_t0010_u01758938778937") 2025/09/27 02:06:22 DEBUG : 020222pa/volatile/path2/file6.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f112a8d77a13f691f_d20250927_m020619_c001_v0001102_t0025_u01758938779164") 2025/09/27 02:06:22 DEBUG : 020222pa/volatile/path2/file7.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11435620a1c26cea_d20250927_m020619_c001_v0001171_t0057_u01758938779273") 2025/09/27 02:06:22 DEBUG : 020222pa/volatile/path2/file8.txt: Deleting (id "4_zef923d63e08d8b8b9f980e1b_f11685bd62841350f_d20250927_m020619_c001_v0001177_t0021_u01758938779299") --- PASS: TestBisyncRemoteRemote (241.52s) --- PASS: TestBisyncRemoteRemote/resync (85.49s) --- PASS: TestBisyncRemoteRemote/resync_modes (134.94s) --- SKIP: TestBisyncRemoteRemote/rmdirs (8.66s) --- SKIP: TestBisyncRemoteRemote/volatile (8.32s) PASS 2025/09/27 02:06:23 DEBUG : B2 bucket rclone-test-jotubah8naro: Purge remote 2025/09/27 02:06:23 INFO : B2 bucket rclone-test-jotubah8naro: cleaning bucket "rclone-test-jotubah8naro" of all files 2025/09/27 02:06:23 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^resync$|^TestBisyncRemoteLocal$/^check_access$|^TestBisyncRemoteRemote$/^(resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 5m40.380680215s (try 2/5)