"./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(all_changed|check_sync)$|^TestBisyncRemoteLocal$/^max_delete_path1$|^TestBisyncRemoteRemote$/^filtersfile_checks$'" - Starting (try 2/5) === RUN TestBisyncRemoteLocal 2025/06/12 08:23:05 NOTICE: remote: TestB2:rclone-test-yijoyof2pulo === RUN TestBisyncRemoteLocal/max_delete_path1 2025/06/12 08:23:09 ERROR : error listing: directory not found 2025/06/12 08:23:09 NOTICE: checking initFs Local file system at /tmp/082305cu/initdir/test_max_delete_path1-makusoc3 2025/06/12 08:23:11 NOTICE: checking Path1 B2 bucket rclone-test-yijoyof2pulo path 082305cu/max_delete_path1/path1 2025/06/12 08:23:11 NOTICE: checking path2 Local file system at /tmp/082305cu/max_delete_path1/path2 2025/06/12 08:23:12 NOTICE: (01) : test max-delete-path1 2025/06/12 08:23:12 NOTICE: (02) : test initial bisync 2025/06/12 08:23:12 NOTICE: (03) : bisync resync 2025/06/12 08:23:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:13 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:13 INFO : Synching Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/" with Path2 "/tmp/082305cu/max_delete_path1/path2/" 2025/06/12 08:23:13 INFO : Copying Path2 files to Path1 2025/06/12 08:23:13 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:23:13 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:23:13 INFO : Resync updating listings 2025/06/12 08:23:13 INFO : Validating listings for Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/" vs Path2 "/tmp/082305cu/max_delete_path1/path2/" 2025/06/12 08:23:13 INFO : Bisync successful 2025/06/12 08:23:13 NOTICE: (04) : test delete >50% of local files 2025/06/12 08:23:13 NOTICE: (05) : delete-file TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/file1.txt 2025/06/12 08:23:14 NOTICE: (06) : delete-file TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/file2.txt 2025/06/12 08:23:15 NOTICE: (07) : delete-file TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/file3.txt 2025/06/12 08:23:15 NOTICE: (08) : delete-file TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/file4.txt 2025/06/12 08:23:16 NOTICE: (09) : delete-file TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/file5.txt 2025/06/12 08:23:16 NOTICE: (10) : test sync should fail due to too many local deletes 2025/06/12 08:23:16 NOTICE: (11) : bisync 2025/06/12 08:23:17 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:17 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:17 INFO : Synching Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/" with Path2 "/tmp/082305cu/max_delete_path1/path2/" 2025/06/12 08:23:17 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:18 INFO : Path1 checking for diffs 2025/06/12 08:23:18 INFO : - Path1 File was deleted - file1.txt 2025/06/12 08:23:18 INFO : - Path1 File was deleted - file2.txt 2025/06/12 08:23:18 INFO : - Path1 File was deleted - file3.txt 2025/06/12 08:23:18 INFO : - Path1 File was deleted - file4.txt 2025/06/12 08:23:18 INFO : - Path1 File was deleted - file5.txt 2025/06/12 08:23:18 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2025/06/12 08:23:18 INFO : Path2 checking for diffs 2025/06/12 08:23:18 ERROR : Safety abort: too many deletes (>50%, 5 of 9) on Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/". Run with --force if desired. 2025/06/12 08:23:18 NOTICE: Bisync aborted. Please try again. 2025/06/12 08:23:18 NOTICE: Bisync error: too many deletes 2025/06/12 08:23:18 NOTICE: (12) : copy-listings initial-fail 2025/06/12 08:23:18 NOTICE: (13) : test change max-delete limit to 60%. sync should run. 2025/06/12 08:23:18 NOTICE: (14) : bisync max-delete=60 2025/06/12 08:23:19 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:19 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:19 INFO : Synching Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/" with Path2 "/tmp/082305cu/max_delete_path1/path2/" 2025/06/12 08:23:19 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:19 INFO : Path1 checking for diffs 2025/06/12 08:23:19 INFO : - Path1 File was deleted - file1.txt 2025/06/12 08:23:19 INFO : - Path1 File was deleted - file2.txt 2025/06/12 08:23:19 INFO : - Path1 File was deleted - file3.txt 2025/06/12 08:23:19 INFO : - Path1 File was deleted - file4.txt 2025/06/12 08:23:19 INFO : - Path1 File was deleted - file5.txt 2025/06/12 08:23:19 INFO : Path1: 5 changes:  0 new,  0 modified,  5 deleted 2025/06/12 08:23:19 INFO : Path2 checking for diffs 2025/06/12 08:23:19 INFO : Applying changes 2025/06/12 08:23:19 INFO : - Path2 Queue delete - /tmp/082305cu/max_delete_path1/path2/file1.txt 2025/06/12 08:23:19 INFO : - Path2 Queue delete - /tmp/082305cu/max_delete_path1/path2/file2.txt 2025/06/12 08:23:19 INFO : - Path2 Queue delete - /tmp/082305cu/max_delete_path1/path2/file3.txt 2025/06/12 08:23:19 INFO : - Path2 Queue delete - /tmp/082305cu/max_delete_path1/path2/file4.txt 2025/06/12 08:23:19 INFO : - Path2 Queue delete - /tmp/082305cu/max_delete_path1/path2/file5.txt 2025/06/12 08:23:19 INFO : - Path1 Do queued copies to - Path2 2025/06/12 08:23:19 INFO : file4.txt: Deleted 2025/06/12 08:23:19 INFO : file2.txt: Deleted 2025/06/12 08:23:19 INFO : file5.txt: Deleted 2025/06/12 08:23:19 INFO : file1.txt: Deleted 2025/06/12 08:23:19 INFO : file3.txt: Deleted 2025/06/12 08:23:19 INFO : Updating listings 2025/06/12 08:23:19 INFO : Validating listings for Path1 "TestB2:rclone-test-yijoyof2pulo/082305cu/max_delete_path1/path1/" vs Path2 "/tmp/082305cu/max_delete_path1/path2/" 2025/06/12 08:23:19 INFO : Bisync successful bisync_test.go:560: TEST max_delete_path1 PASSED --- PASS: TestBisyncRemoteLocal (17.55s) --- PASS: TestBisyncRemoteLocal/max_delete_path1 (15.74s) === RUN TestBisyncLocalRemote 2025/06/12 08:23:23 NOTICE: remote: TestB2:rclone-test-niricux2zodi === RUN TestBisyncLocalRemote/all_changed 2025/06/12 08:23:27 ERROR : error listing: directory not found 2025/06/12 08:23:27 NOTICE: checking initFs Local file system at /tmp/082323vi/initdir/test_all_changed-cukuyoc6 2025/06/12 08:23:27 NOTICE: checking Path1 Local file system at /tmp/082323vi/all_changed/path1 2025/06/12 08:23:29 NOTICE: checking path2 B2 bucket rclone-test-niricux2zodi path 082323vi/all_changed/path2 2025/06/12 08:23:29 NOTICE: (01) : test all-changed 2025/06/12 08:23:29 NOTICE: (02) : test initial bisync 2025/06/12 08:23:29 NOTICE: (03) : bisync resync 2025/06/12 08:23:30 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:30 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:30 INFO : Synching Path1 "/tmp/082323vi/all_changed/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:30 INFO : Copying Path2 files to Path1 2025/06/12 08:23:30 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:23:31 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:23:31 INFO : Resync updating listings 2025/06/12 08:23:31 INFO : Validating listings for Path1 "/tmp/082323vi/all_changed/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:31 INFO : Bisync successful 2025/06/12 08:23:31 NOTICE: (04) : test change timestamp on all files except RCLONE_TEST 2025/06/12 08:23:31 NOTICE: (05) : touch-glob 2005-01-02 /tmp/082323vi/all_changed/path1/ file* 2025/06/12 08:23:31 NOTICE: (06) : touch-glob 2005-01-02 /tmp/082323vi/all_changed/path1/subdir file* 2025/06/12 08:23:31 NOTICE: (07) : test sync should pass 2025/06/12 08:23:31 NOTICE: (08) : bisync 2025/06/12 08:23:32 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:32 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:32 INFO : Synching Path1 "/tmp/082323vi/all_changed/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:32 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:33 INFO : Path1 checking for diffs 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.copy1.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.copy2.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.copy3.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.copy4.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.copy5.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - file1.txt 2025/06/12 08:23:33 INFO : - Path1 File changed: time (newer) - subdir/file20.txt 2025/06/12 08:23:33 INFO : Path1: 7 changes:  0 new,  7 modified,  0 deleted 2025/06/12 08:23:33 INFO : (Modified:  7 newer,  0 older) 2025/06/12 08:23:33 INFO : Path2 checking for diffs 2025/06/12 08:23:33 INFO : Applying changes 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy1.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy2.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy3.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy4.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy5.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.txt 2025/06/12 08:23:33 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/subdir/file20.txt 2025/06/12 08:23:33 INFO : - Path1 Do queued copies to - Path2 2025/06/12 08:23:34 INFO : file1.copy2.txt: Updated modification time in destination 2025/06/12 08:23:34 INFO : file1.copy1.txt: Updated modification time in destination 2025/06/12 08:23:34 INFO : file1.copy3.txt: Updated modification time in destination 2025/06/12 08:23:34 INFO : file1.copy5.txt: Updated modification time in destination 2025/06/12 08:23:34 INFO : file1.copy4.txt: Updated modification time in destination 2025/06/12 08:23:34 INFO : file1.txt: Updated modification time in destination 2025/06/12 08:23:35 INFO : subdir/file20.txt: Updated modification time in destination 2025/06/12 08:23:35 INFO : Updating listings 2025/06/12 08:23:35 INFO : Validating listings for Path1 "/tmp/082323vi/all_changed/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:35 INFO : Bisync successful 2025/06/12 08:23:35 NOTICE: (09) : test change timestamp on all files including RCLONE_TEST 2025/06/12 08:23:35 NOTICE: (10) : touch-glob 2004-01-02 /tmp/082323vi/all_changed/path1/ * 2025/06/12 08:23:35 NOTICE: (11) : touch-glob 2004-01-02 /tmp/082323vi/all_changed/path1/subdir * 2025/06/12 08:23:35 NOTICE: (12) : test sync should fail 2025/06/12 08:23:35 NOTICE: (13) : bisync 2025/06/12 08:23:36 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:36 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:36 INFO : Synching Path1 "/tmp/082323vi/all_changed/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:36 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:36 INFO : Path1 checking for diffs 2025/06/12 08:23:36 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - file1.txt 2025/06/12 08:23:36 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/06/12 08:23:36 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/06/12 08:23:36 INFO : (Modified:  1 newer,  7 older) 2025/06/12 08:23:36 INFO : Path2 checking for diffs 2025/06/12 08:23:36 ERROR : Safety abort: all files were changed on Path1 "/tmp/082323vi/all_changed/path1/". Run with --force if desired. 2025/06/12 08:23:36 NOTICE: Bisync aborted. Please try again. 2025/06/12 08:23:36 NOTICE: Bisync error: all files were changed 2025/06/12 08:23:36 NOTICE: (14) : test sync with force should pass 2025/06/12 08:23:36 NOTICE: (15) : bisync force 2025/06/12 08:23:38 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:38 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:38 INFO : Synching Path1 "/tmp/082323vi/all_changed/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:38 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:38 INFO : Path1 checking for diffs 2025/06/12 08:23:38 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - file1.txt 2025/06/12 08:23:38 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/06/12 08:23:38 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/06/12 08:23:38 INFO : (Modified:  1 newer,  7 older) 2025/06/12 08:23:38 INFO : Path2 checking for diffs 2025/06/12 08:23:38 INFO : Applying changes 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/RCLONE_TEST 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy1.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy2.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy3.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy4.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.copy5.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/file1.txt 2025/06/12 08:23:38 INFO : - Path1 Queue copy to Path2 - TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/subdir/file20.txt 2025/06/12 08:23:38 INFO : - Path1 Do queued copies to - Path2 2025/06/12 08:23:39 INFO : file1.copy3.txt: Updated modification time in destination 2025/06/12 08:23:39 INFO : file1.txt: Updated modification time in destination 2025/06/12 08:23:39 INFO : RCLONE_TEST: Updated modification time in destination 2025/06/12 08:23:39 INFO : subdir/file20.txt: Updated modification time in destination 2025/06/12 08:23:39 INFO : file1.copy2.txt: Updated modification time in destination 2025/06/12 08:23:39 INFO : file1.copy1.txt: Updated modification time in destination 2025/06/12 08:23:39 INFO : file1.copy5.txt: Updated modification time in destination 2025/06/12 08:23:40 INFO : file1.copy4.txt: Updated modification time in destination 2025/06/12 08:23:40 INFO : Updating listings 2025/06/12 08:23:40 INFO : Validating listings for Path1 "/tmp/082323vi/all_changed/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/all_changed/path2/" 2025/06/12 08:23:40 INFO : Bisync successful bisync_test.go:560: TEST all_changed PASSED === RUN TestBisyncLocalRemote/check_sync 2025/06/12 08:23:43 ERROR : error listing: directory not found 2025/06/12 08:23:44 NOTICE: checking initFs Local file system at /tmp/082323vi/initdir/test_check_sync-qocugok4 2025/06/12 08:23:44 NOTICE: checking Path1 Local file system at /tmp/082323vi/check_sync/path1 2025/06/12 08:23:45 NOTICE: checking path2 B2 bucket rclone-test-niricux2zodi path 082323vi/check_sync/path2 2025/06/12 08:23:46 NOTICE: (01) : test check-sync 2025/06/12 08:23:46 NOTICE: (02) : test initial bisync 2025/06/12 08:23:46 NOTICE: (03) : bisync resync 2025/06/12 08:23:47 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:47 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:47 INFO : Synching Path1 "/tmp/082323vi/check_sync/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:47 INFO : Copying Path2 files to Path1 2025/06/12 08:23:47 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:23:47 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:23:47 INFO : Resync updating listings 2025/06/12 08:23:47 INFO : Validating listings for Path1 "/tmp/082323vi/check_sync/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:47 INFO : Bisync successful 2025/06/12 08:23:47 NOTICE: (04) : test 1. run check-sync-only on a clean sync 2025/06/12 08:23:47 NOTICE: (05) : bisync check-sync-only 2025/06/12 08:23:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23: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/06/12 08:23:48 INFO : Validating listings for Path1 "/tmp/082323vi/check_sync/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:48 INFO : Bisync successful 2025/06/12 08:23:48 NOTICE: (06) : test 2. inject modified listings into the workdir 2025/06/12 08:23:48 NOTICE: (07) : copy-as /tmp/082323vi/datadir/test_check_sync-tocudun3/_testdir_path1.._testdir_path2.path1.lst /tmp/082323vi/workdir/ tmp_082323vi_check_sync_path1..TestB2_rclone-test-niricux2zodi_082323vi_check_sync_path2.path1.lst 2025/06/12 08:23:48 NOTICE: (08) : copy-as /tmp/082323vi/datadir/test_check_sync-tocudun3/_testdir_path1.._testdir_path2.path2.lst /tmp/082323vi/workdir/ tmp_082323vi_check_sync_path1..TestB2_rclone-test-niricux2zodi_082323vi_check_sync_path2.path2.lst 2025/06/12 08:23:48 NOTICE: (09) : test 3. run check-sync-only on modified listings 2025/06/12 08:23:48 NOTICE: (10) : bisync check-sync-only 2025/06/12 08:23:49 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:49 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:49 INFO : Validating listings for Path1 "/tmp/082323vi/check_sync/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:49 ERROR : -  Path1 file not found in Path2 - file2.txt 2025/06/12 08:23:49 ERROR : -  Path2 file not found in Path1 - file1.txt 2025/06/12 08:23:49 ERROR : Bisync critical error: path1 and path2 are out of sync, run --resync to recover 2025/06/12 08:23:49 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:23:49 NOTICE: Bisync error: bisync aborted 2025/06/12 08:23:49 NOTICE: (11) : copy-listings check-sync-only 2025/06/12 08:23:49 NOTICE: (12) : test 4. run normal sync to check that it aborts 2025/06/12 08:23:49 NOTICE: (13) : bisync 2025/06/12 08:23:50 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:50 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:50 INFO : Synching Path1 "/tmp/082323vi/check_sync/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:50 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/082323vi/workdir/tmp_082323vi_check_sync_path1..TestB2_rclone-test-niricux2zodi_082323vi_check_sync_path2.path1.lst Path2: /tmp/082323vi/workdir/tmp_082323vi_check_sync_path1..TestB2_rclone-test-niricux2zodi_082323vi_check_sync_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/082323vi/workdir" 2025/06/12 08:23:50 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:23:50 NOTICE: Bisync error: bisync aborted 2025/06/12 08:23:50 NOTICE: (14) : test 5. prune failure listings after critical abort 2025/06/12 08:23:50 NOTICE: (15) : delete-glob /tmp/082323vi/workdir/ *.lst 2025/06/12 08:23:50 NOTICE: (16) : delete-glob /tmp/082323vi/workdir/ *.lst-err 2025/06/12 08:23:50 NOTICE: (17) : delete-glob /tmp/082323vi/workdir/ *.lst-new 2025/06/12 08:23:50 NOTICE: (18) : test 6. run resync 2025/06/12 08:23:50 NOTICE: (19) : bisync resync 2025/06/12 08:23:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:51 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:51 INFO : Synching Path1 "/tmp/082323vi/check_sync/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:51 INFO : Copying Path2 files to Path1 2025/06/12 08:23:51 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:23:51 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:23:51 INFO : Resync updating listings 2025/06/12 08:23:51 INFO : Validating listings for Path1 "/tmp/082323vi/check_sync/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:51 INFO : Bisync successful 2025/06/12 08:23:51 NOTICE: (20) : test 7. run normal sync with check-sync enabled (default) 2025/06/12 08:23:51 NOTICE: (21) : bisync 2025/06/12 08:23:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23:52 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/06/12 08:23:52 INFO : Synching Path1 "/tmp/082323vi/check_sync/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:52 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:52 INFO : Path1 checking for diffs 2025/06/12 08:23:52 INFO : Path2 checking for diffs 2025/06/12 08:23:52 INFO : No changes found 2025/06/12 08:23:52 INFO : Updating listings 2025/06/12 08:23:52 INFO : Validating listings for Path1 "/tmp/082323vi/check_sync/path1/" vs Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:52 INFO : Bisync successful 2025/06/12 08:23:52 NOTICE: (22) : test 8. run normal sync with no-check-sync 2025/06/12 08:23:52 NOTICE: (23) : bisync no-check-sync 2025/06/12 08:23:53 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:23: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/06/12 08:23:53 INFO : Synching Path1 "/tmp/082323vi/check_sync/path1/" with Path2 "TestB2:rclone-test-niricux2zodi/082323vi/check_sync/path2/" 2025/06/12 08:23:53 INFO : Building Path1 and Path2 listings 2025/06/12 08:23:54 INFO : Path1 checking for diffs 2025/06/12 08:23:54 INFO : Path2 checking for diffs 2025/06/12 08:23:54 INFO : No changes found 2025/06/12 08:23:54 INFO : Updating listings 2025/06/12 08:23:54 INFO : Bisync successful bisync_test.go:560: TEST check_sync PASSED --- PASS: TestBisyncLocalRemote (34.69s) --- PASS: TestBisyncLocalRemote/all_changed (18.54s) --- PASS: TestBisyncLocalRemote/check_sync (14.12s) === RUN TestBisyncRemoteRemote 2025/06/12 08:23:58 NOTICE: remote: TestB2:rclone-test-tecodem8hase === RUN TestBisyncRemoteRemote/filtersfile_checks 2025/06/12 08:24:04 NOTICE: checking initFs Local file system at /tmp/082358da/initdir/test_filtersfile_checks-dayoxed4 2025/06/12 08:24:05 NOTICE: checking Path1 B2 bucket rclone-test-tecodem8hase path 082358da/filtersfile_checks/path1 2025/06/12 08:24:07 NOTICE: checking path2 B2 bucket rclone-test-tecodem8hase path 082358da/filtersfile_checks/path2 2025/06/12 08:24:07 NOTICE: (01) : test filtersfile-checks 2025/06/12 08:24:07 NOTICE: (02) : test initial bisync 2025/06/12 08:24:07 NOTICE: (03) : bisync resync 2025/06/12 08:24:10 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:10 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:10 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:10 INFO : Copying Path2 files to Path1 2025/06/12 08:24:10 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:24:10 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:24:11 INFO : Resync updating listings 2025/06/12 08:24:11 INFO : Validating listings for Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:11 INFO : Bisync successful 2025/06/12 08:24:11 NOTICE: (04) : test 1. inject filters file in workdir. 2025/06/12 08:24:11 NOTICE: (05) : copy-file /tmp/082358da/datadir/test_filtersfile_checks-kabesut0/filtersfile.txt /tmp/082358da/workdir/ 2025/06/12 08:24:11 NOTICE: (06) : test 2. run with filters-file but without md5. should abort. 2025/06/12 08:24:11 NOTICE: (07) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24: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/06/12 08:24:13 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:13 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:13 ERROR : Bisync critical error: filters file md5 hash not found (must run --resync): /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:13 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:24:13 NOTICE: Bisync error: bisync aborted 2025/06/12 08:24:13 NOTICE: (08) : test 3. run without filters-file. should be blocked due to prior abort. 2025/06/12 08:24:13 NOTICE: (09) : bisync 2025/06/12 08:24:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:16 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:16 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:16 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/082358da/workdir/TestB2_rclone-test-tecodem8hase_082358da_filtersfile_checks_path1..TestB2_rclone-test-tecodem8hase_082358da_filtersfile_checks_path2.path1.lst Path2: /tmp/082358da/workdir/TestB2_rclone-test-tecodem8hase_082358da_filtersfile_checks_path1..TestB2_rclone-test-tecodem8hase_082358da_filtersfile_checks_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/082358da/workdir" 2025/06/12 08:24:16 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:24:16 NOTICE: Bisync error: bisync aborted 2025/06/12 08:24:16 NOTICE: (10) : test 4. run with filters-file and resync. 2025/06/12 08:24:16 NOTICE: (11) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt resync 2025/06/12 08:24:18 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:18 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:18 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:18 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:18 INFO : Storing filters file hash to /tmp/082358da/workdir/filtersfile.txt.md5 2025/06/12 08:24:18 INFO : Copying Path2 files to Path1 2025/06/12 08:24:18 INFO : - Path2 Resync is copying files to - Path1 2025/06/12 08:24:19 INFO : - Path1 Resync is copying files to - Path2 2025/06/12 08:24:19 INFO : Resync updating listings 2025/06/12 08:24:19 INFO : Validating listings for Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:19 INFO : Bisync successful 2025/06/12 08:24:19 NOTICE: (12) : test 5. run with filters-file alone. should run. 2025/06/12 08:24:19 NOTICE: (13) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:22 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:22 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:22 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:22 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:22 INFO : Building Path1 and Path2 listings 2025/06/12 08:24:22 INFO : Path1 checking for diffs 2025/06/12 08:24:22 INFO : Path2 checking for diffs 2025/06/12 08:24:22 INFO : No changes found 2025/06/12 08:24:22 INFO : Updating listings 2025/06/12 08:24:22 INFO : Validating listings for Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" vs Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:22 INFO : Bisync successful 2025/06/12 08:24:22 NOTICE: (14) : test 6. push changed filters-file to workdir. 2025/06/12 08:24:22 NOTICE: (15) : copy-as /tmp/082358da/datadir/test_filtersfile_checks-kabesut0/filtersfile2.txt /tmp/082358da/workdir/ filtersfile.txt 2025/06/12 08:24:22 NOTICE: (16) : test 7. run with filters-file alone. should abort. 2025/06/12 08:24:22 NOTICE: (17) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:25 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:25 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:25 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:25 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:25 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:25 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:24:25 NOTICE: Bisync error: bisync aborted 2025/06/12 08:24:25 NOTICE: (18) : test 8. run with filters-file and resync and dry-run. should do the dry-run but still cause next non-resync run to abort. 2025/06/12 08:24:25 NOTICE: (19) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt resync dry-run 2025/06/12 08:24:29 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:29 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:29 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:29 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:29 INFO : Skipped storing filters file hash to /tmp/082358da/workdir/filtersfile.txt.md5 as --dry-run is set 2025/06/12 08:24:29 INFO : Copying Path2 files to Path1 2025/06/12 08:24:29 NOTICE: - Path2 Resync is copying files to - Path1 2025/06/12 08:24:29 NOTICE: - Path1 Resync is copying files to - Path2 2025/06/12 08:24:29 INFO : Resync updating listings 2025/06/12 08:24:29 INFO : Bisync successful 2025/06/12 08:24:29 NOTICE: (20) : test 9. run with filters-file alone. should abort. 2025/06/12 08:24:29 NOTICE: (21) : bisync filters-file=/tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:32 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/06/12 08:24:32 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": false, "DownloadHash": false } 2025/06/12 08:24:32 INFO : Synching Path1 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path1/" with Path2 "TestB2:rclone-test-tecodem8hase/082358da/filtersfile_checks/path2/" 2025/06/12 08:24:32 INFO : Using filters file /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:32 ERROR : Bisync critical error: filters file has changed (must run --resync): /tmp/082358da/workdir/filtersfile.txt 2025/06/12 08:24:32 ERROR : Bisync aborted. Must run --resync to recover. 2025/06/12 08:24:32 NOTICE: Bisync error: bisync aborted bisync_test.go:560: TEST filtersfile_checks PASSED --- PASS: TestBisyncRemoteRemote (40.56s) --- PASS: TestBisyncRemoteRemote/filtersfile_checks (38.75s) PASS 2025/06/12 08:24:38 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestB2: -list-retries 5 -verbose -test.run '^TestBisyncLocalRemote$/^(all_changed|check_sync)$|^TestBisyncRemoteLocal$/^max_delete_path1$|^TestBisyncRemoteRemote$/^filtersfile_checks$'" - Finished OK in 1m34.172265485s (try 2/5)