"./bisync.test -test.v -test.timeout 1h0m0s -remote TestCompressSwift: -verbose" - Starting (try 1/5) === RUN TestBisyncRemoteLocal 2025/05/30 01:38:04 NOTICE: remote: TestCompressSwift:rclone-test-dadizoj1sidu === RUN TestBisyncRemoteLocal/all_changed 2025/05/30 01:38:27 ERROR : error listing: directory not found 2025/05/30 01:38:27 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_all_changed-gamirul5 2025/05/30 01:38:54 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1 2025/05/30 01:39:15 NOTICE: checking path2 Local file system at /tmp/013804ve/all_changed/path2 2025/05/30 01:39:15 NOTICE: (01) : test all-changed 2025/05/30 01:39:15 NOTICE: (02) : test initial bisync 2025/05/30 01:39:15 NOTICE: (03) : bisync resync 2025/05/30 01:39:51 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:39: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/05/30 01:39:51 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" with Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:39:51 INFO : Copying Path2 files to Path1 2025/05/30 01:39:51 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 01:39:57 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 01:40:17 INFO : Resync updating listings 2025/05/30 01:40:17 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" vs Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:40:17 INFO : Bisync successful 2025/05/30 01:40:17 NOTICE: (04) : test change timestamp on all files except RCLONE_TEST 2025/05/30 01:40:17 NOTICE: (05) : touch-glob 2005-01-02 TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/ file* 2025/05/30 01:41:29 NOTICE: (06) : touch-glob 2005-01-02 TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/subdir file* 2025/05/30 01:42:06 NOTICE: (07) : test sync should pass 2025/05/30 01:42:06 NOTICE: (08) : bisync 2025/05/30 01:42:42 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:42: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/05/30 01:42:42 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" with Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:42:42 INFO : Building Path1 and Path2 listings 2025/05/30 01:42:47 INFO : Path1 checking for diffs 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.copy1.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.copy2.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.copy3.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.copy4.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.copy5.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - file1.txt 2025/05/30 01:42:47 INFO : - Path1 File changed: time (newer) - subdir/file20.txt 2025/05/30 01:42:47 INFO : Path1: 7 changes:  0 new,  7 modified,  0 deleted 2025/05/30 01:42:47 INFO : (Modified:  7 newer,  0 older) 2025/05/30 01:42:47 INFO : Path2 checking for diffs 2025/05/30 01:42:47 INFO : Applying changes 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy1.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy2.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy3.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy4.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy5.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.txt 2025/05/30 01:42:47 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/subdir/file20.txt 2025/05/30 01:42:47 INFO : - Path1 Do queued copies to - Path2 2025/05/30 01:42:48 INFO : file1.copy1.txt: Updated modification time in destination 2025/05/30 01:42:48 INFO : file1.copy2.txt: Updated modification time in destination 2025/05/30 01:42:48 INFO : file1.copy3.txt: Updated modification time in destination 2025/05/30 01:42:48 INFO : file1.copy5.txt: Updated modification time in destination 2025/05/30 01:42:48 INFO : file1.copy4.txt: Updated modification time in destination 2025/05/30 01:42:48 INFO : file1.txt: Updated modification time in destination 2025/05/30 01:42:53 INFO : subdir/file20.txt: Updated modification time in destination 2025/05/30 01:42:53 INFO : Updating listings 2025/05/30 01:42:53 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" vs Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:42:53 INFO : Bisync successful 2025/05/30 01:42:53 NOTICE: (09) : test change timestamp on all files including RCLONE_TEST 2025/05/30 01:42:53 NOTICE: (10) : touch-glob 2004-01-02 TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/ * 2025/05/30 01:43:44 NOTICE: (11) : touch-glob 2004-01-02 TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/subdir * 2025/05/30 01:44:09 NOTICE: (12) : test sync should fail 2025/05/30 01:44:09 NOTICE: (13) : bisync 2025/05/30 01:44:45 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:44:45 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:44:45 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" with Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:44:45 INFO : Building Path1 and Path2 listings 2025/05/30 01:44:51 INFO : Path1 checking for diffs 2025/05/30 01:44:51 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - file1.txt 2025/05/30 01:44:51 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/05/30 01:44:51 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/05/30 01:44:51 INFO : (Modified:  1 newer,  7 older) 2025/05/30 01:44:51 INFO : Path2 checking for diffs 2025/05/30 01:44:51 ERROR : Safety abort: all files were changed on Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/". Run with --force if desired. 2025/05/30 01:44:51 NOTICE: Bisync aborted. Please try again. 2025/05/30 01:44:51 NOTICE: Bisync error: all files were changed 2025/05/30 01:44:51 NOTICE: (14) : test sync with force should pass 2025/05/30 01:44:51 NOTICE: (15) : bisync force 2025/05/30 01:45:26 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:45:26 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:45:26 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" with Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:45:26 INFO : Building Path1 and Path2 listings 2025/05/30 01:45:37 INFO : Path1 checking for diffs 2025/05/30 01:45:37 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - file1.txt 2025/05/30 01:45:37 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/05/30 01:45:37 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/05/30 01:45:37 INFO : (Modified:  1 newer,  7 older) 2025/05/30 01:45:37 INFO : Path2 checking for diffs 2025/05/30 01:45:37 INFO : Applying changes 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/RCLONE_TEST 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy1.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy2.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy3.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy4.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.copy5.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/file1.txt 2025/05/30 01:45:37 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/all_changed/path2/subdir/file20.txt 2025/05/30 01:45:37 INFO : - Path1 Do queued copies to - Path2 2025/05/30 01:45:43 INFO : file1.copy1.txt: Updated modification time in destination 2025/05/30 01:45:43 INFO : file1.copy2.txt: Updated modification time in destination 2025/05/30 01:45:43 INFO : file1.copy4.txt: Updated modification time in destination 2025/05/30 01:45:43 INFO : RCLONE_TEST: Updated modification time in destination 2025/05/30 01:45:43 INFO : file1.copy3.txt: Updated modification time in destination 2025/05/30 01:45:43 INFO : subdir/file20.txt: Updated modification time in destination 2025/05/30 01:45:48 INFO : file1.copy5.txt: Updated modification time in destination 2025/05/30 01:45:48 INFO : file1.txt: Updated modification time in destination 2025/05/30 01:45:48 INFO : Updating listings 2025/05/30 01:45:48 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/all_changed/path1/" vs Path2 "/tmp/013804ve/all_changed/path2/" 2025/05/30 01:45:48 INFO : Bisync successful bisync_test.go:560: TEST all_changed PASSED === RUN TestBisyncRemoteLocal/backupdir 2025/05/30 01:46:24 ERROR : error listing: directory not found 2025/05/30 01:46:24 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_backupdir-pivozav7 2025/05/30 01:46:57 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/backupdir/path1 2025/05/30 01:47:13 NOTICE: checking path2 Local file system at /tmp/013804ve/backupdir/path2 2025/05/30 01:47:13 NOTICE: (01) : test backupdir 2025/05/30 01:47:13 NOTICE: (02) : test initial bisync 2025/05/30 01:47:13 NOTICE: (03) : bisync resync backupdir1=/tmp/013804ve/workdir/backupdirs/backupdir1 backupdir2=/tmp/013804ve/workdir/backupdirs/backupdir2 bisync_test.go:934: backupdir test currently only works on local (it uses the workdir) === RUN TestBisyncRemoteLocal/basic 2025/05/30 01:47:25 ERROR : error listing: directory not found 2025/05/30 01:47:25 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_basic-suqudex8 2025/05/30 01:47:51 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1 2025/05/30 01:48:08 NOTICE: checking path2 Local file system at /tmp/013804ve/basic/path2 2025/05/30 01:48:08 NOTICE: (01) : test basic 2025/05/30 01:48:08 NOTICE: (02) : test initial bisync 2025/05/30 01:48:08 NOTICE: (03) : bisync resync 2025/05/30 01:48:44 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:48:44 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:48:44 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/" with Path2 "/tmp/013804ve/basic/path2/" 2025/05/30 01:48:44 INFO : Copying Path2 files to Path1 2025/05/30 01:48:44 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 01:48:44 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 01:48:50 INFO : Resync updating listings 2025/05/30 01:48:50 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/" vs Path2 "/tmp/013804ve/basic/path2/" 2025/05/30 01:48:50 INFO : Bisync successful 2025/05/30 01:48:50 NOTICE: (04) : test place newer files on both paths 2025/05/30 01:48:50 NOTICE: (05) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_basic-cafeciy1/file1.txt /tmp/013804ve/basic/path2/ 2025/05/30 01:48:50 NOTICE: (06) : copy-as /tmp/013804ve/datadir/test_basic-cafeciy1/file1.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/subdir file20.txt 2025/05/30 01:49:17 NOTICE: (07) : test bisync run 2025/05/30 01:49:17 NOTICE: (08) : bisync 2025/05/30 01:49:52 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:49: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/05/30 01:49:52 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/" with Path2 "/tmp/013804ve/basic/path2/" 2025/05/30 01:49:52 INFO : Building Path1 and Path2 listings 2025/05/30 01:49:58 INFO : Path1 checking for diffs 2025/05/30 01:49:58 INFO : - Path1 File changed: size (larger), time (newer) - subdir/file20.txt 2025/05/30 01:49:58 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2025/05/30 01:49:58 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/05/30 01:49:58 INFO : Path2 checking for diffs 2025/05/30 01:49:58 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/05/30 01:49:58 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/05/30 01:49:58 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/05/30 01:49:58 INFO : Applying changes 2025/05/30 01:49:58 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/basic/path2/subdir/file20.txt 2025/05/30 01:49:58 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/file1.txt 2025/05/30 01:49:58 INFO : - Path2 Do queued copies to - Path1 2025/05/30 01:50:09 INFO : file1.txt: Copied (replaced existing) 2025/05/30 01:50:09 INFO : - Path1 Do queued copies to - Path2 2025/05/30 01:50:14 INFO : subdir/file20.txt: Copied (replaced existing) 2025/05/30 01:50:14 INFO : Updating listings 2025/05/30 01:50:14 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/basic/path1/" vs Path2 "/tmp/013804ve/basic/path2/" 2025/05/30 01:50:14 INFO : Bisync successful bisync_test.go:560: TEST basic PASSED === RUN TestBisyncRemoteLocal/changes 2025/05/30 01:50:42 ERROR : error listing: directory not found 2025/05/30 01:50:42 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_changes-kevenec6 2025/05/30 01:51:14 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1 2025/05/30 01:51:25 NOTICE: checking path2 Local file system at /tmp/013804ve/changes/path2 2025/05/30 01:51:25 NOTICE: (01) : test changes 2025/05/30 01:51:25 NOTICE: (02) : test initial bisync 2025/05/30 01:51:25 NOTICE: (03) : bisync resync 2025/05/30 01:51:56 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:51:56 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:51:56 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/" with Path2 "/tmp/013804ve/changes/path2/" 2025/05/30 01:51:56 INFO : Copying Path2 files to Path1 2025/05/30 01:51:56 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 01:52:07 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 01:52:12 INFO : Resync updating listings 2025/05/30 01:52:12 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/" vs Path2 "/tmp/013804ve/changes/path2/" 2025/05/30 01:52:12 INFO : Bisync successful 2025/05/30 01:52:12 NOTICE: (04) : test make modifications on both paths 2025/05/30 01:52:12 NOTICE: (05) : test new on path2 - file10 2025/05/30 01:52:12 NOTICE: (06) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file10.txt /tmp/013804ve/changes/path2/ 2025/05/30 01:52:12 NOTICE: (07) : test newer on path2 - file1 2025/05/30 01:52:12 NOTICE: (08) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file1.txt /tmp/013804ve/changes/path2/ 2025/05/30 01:52:12 NOTICE: (09) : test new on path1 - file11 2025/05/30 01:52:12 NOTICE: (10) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file11.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/ 2025/05/30 01:52:54 NOTICE: (11) : test newer on path1 - file2 2025/05/30 01:52:54 NOTICE: (12) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file2.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/ 2025/05/30 01:53:16 NOTICE: (13) : test deleted on path2 - file3 2025/05/30 01:53:16 NOTICE: (14) : delete-file /tmp/013804ve/changes/path2/file3.txt 2025/05/30 01:53:16 NOTICE: (15) : test deleted on path1 - file4 2025/05/30 01:53:16 NOTICE: (16) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file4.txt 2025/05/30 01:53:33 NOTICE: (17) : test deleted on both paths - file8 2025/05/30 01:53:33 NOTICE: (18) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file8.txt 2025/05/30 01:53:38 NOTICE: (19) : delete-file /tmp/013804ve/changes/path2/file8.txt 2025/05/30 01:53:38 NOTICE: (20) : test changed on both paths - file5 (file5R, file5L) 2025/05/30 01:53:38 NOTICE: (21) : touch-glob 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/ file5R.txt 2025/05/30 01:53:38 NOTICE: (22) : copy-as /tmp/013804ve/datadir/test_changes-keyunij6/file5R.txt /tmp/013804ve/changes/path2/ file5.txt 2025/05/30 01:53:38 NOTICE: (23) : touch-glob 2001-03-04 /tmp/013804ve/datadir/test_changes-keyunij6/ file5L.txt 2025/05/30 01:53:38 NOTICE: (24) : copy-as /tmp/013804ve/datadir/test_changes-keyunij6/file5L.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/ file5.txt 2025/05/30 01:54:00 NOTICE: (25) : test newer on path2 and deleted on path1 - file6 2025/05/30 01:54:00 NOTICE: (26) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file6.txt /tmp/013804ve/changes/path2/ 2025/05/30 01:54:00 NOTICE: (27) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file6.txt 2025/05/30 01:54:05 NOTICE: (28) : test newer on path1 and deleted on path2 - file7 2025/05/30 01:54:05 NOTICE: (29) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_changes-keyunij6/file7.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/ 2025/05/30 01:54:37 NOTICE: (30) : delete-file /tmp/013804ve/changes/path2/file7.txt 2025/05/30 01:54:37 NOTICE: (31) : test bisync run 2025/05/30 01:54:37 NOTICE: (32) : bisync 2025/05/30 01:55:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:55: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/05/30 01:55:07 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/" with Path2 "/tmp/013804ve/changes/path2/" 2025/05/30 01:55:07 INFO : Building Path1 and Path2 listings 2025/05/30 01:55:13 INFO : Path1 checking for diffs 2025/05/30 01:55:13 INFO : - Path1 File changed: size (larger), time (newer) - file2.txt 2025/05/30 01:55:13 INFO : - Path1 File was deleted - file4.txt 2025/05/30 01:55:13 INFO : - Path1 File changed: size (larger), time (newer) - file5.txt 2025/05/30 01:55:13 INFO : - Path1 File was deleted - file6.txt 2025/05/30 01:55:13 INFO : - Path1 File changed: size (larger), time (newer) - file7.txt 2025/05/30 01:55:13 INFO : - Path1 File was deleted - file8.txt 2025/05/30 01:55:13 INFO : - Path1 File is new - file11.txt 2025/05/30 01:55:13 INFO : Path1: 7 changes:  1 new,  3 modified,  3 deleted 2025/05/30 01:55:13 INFO : (Modified:  3 newer,  0 older,  3 larger,  0 smaller) 2025/05/30 01:55:13 INFO : Path2 checking for diffs 2025/05/30 01:55:13 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/05/30 01:55:13 INFO : - Path2 File was deleted - file3.txt 2025/05/30 01:55:13 INFO : - Path2 File changed: size (larger), time (newer) - file5.txt 2025/05/30 01:55:13 INFO : - Path2 File changed: size (larger), time (newer) - file6.txt 2025/05/30 01:55:13 INFO : - Path2 File was deleted - file7.txt 2025/05/30 01:55:13 INFO : - Path2 File was deleted - file8.txt 2025/05/30 01:55:13 INFO : - Path2 File is new - file10.txt 2025/05/30 01:55:13 INFO : Path2: 7 changes:  1 new,  3 modified,  3 deleted 2025/05/30 01:55:13 INFO : (Modified:  3 newer,  0 older,  3 larger,  0 smaller) 2025/05/30 01:55:13 INFO : Applying changes 2025/05/30 01:55:13 INFO : Checking potential conflicts... 2025/05/30 01:55:18 ERROR : file5.txt: md5 differ 2025/05/30 01:55:18 NOTICE: Local file system at /tmp/013804ve/changes/path2: 1 differences found 2025/05/30 01:55:18 NOTICE: Local file system at /tmp/013804ve/changes/path2: 1 errors while checking 2025/05/30 01:55:18 INFO : Finished checking the potential conflicts. 1 differences found 2025/05/30 01:55:18 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/changes/path2/file11.txt 2025/05/30 01:55:18 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/changes/path2/file2.txt 2025/05/30 01:55:18 INFO : - Path2 Queue delete - /tmp/013804ve/changes/path2/file4.txt 2025/05/30 01:55:18 NOTICE: - WARNING New or changed in both paths - file5.txt 2025/05/30 01:55:18 NOTICE: - Path1 Renaming Path1 copy - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file5.txt.conflict1 2025/05/30 01:55:55 INFO : file5.txt: Copied (server-side copy) to: file5.txt.conflict1 2025/05/30 01:56:00 INFO : file5.txt: Deleted 2025/05/30 01:56:00 NOTICE: - Path1 Queue copy to Path2 - /tmp/013804ve/changes/path2/file5.txt.conflict1 2025/05/30 01:56:00 NOTICE: - Path2 Renaming Path2 copy - /tmp/013804ve/changes/path2/file5.txt.conflict2 2025/05/30 01:56:00 INFO : file5.txt: Moved (server-side) to: file5.txt.conflict2 2025/05/30 01:56:00 NOTICE: - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file5.txt.conflict2 2025/05/30 01:56:00 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file6.txt 2025/05/30 01:56:00 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/changes/path2/file7.txt 2025/05/30 01:56:00 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file1.txt 2025/05/30 01:56:00 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file10.txt 2025/05/30 01:56:00 INFO : - Path1 Queue delete - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/file3.txt 2025/05/30 01:56:00 INFO : - Path2 Do queued copies to - Path1 2025/05/30 01:56:01 INFO : file1.txt: Copied (replaced existing) 2025/05/30 01:56:11 INFO : file6.txt: Copied (new) 2025/05/30 01:56:11 INFO : file10.txt: Copied (new) 2025/05/30 01:56:11 INFO : file5.txt.conflict2: Copied (new) 2025/05/30 01:56:26 INFO : file3.txt: Deleted 2025/05/30 01:56:26 INFO : - Path1 Do queued copies to - Path2 2025/05/30 01:56:32 INFO : file11.txt: Copied (new) 2025/05/30 01:56:37 INFO : file5.txt.conflict1: Copied (new) 2025/05/30 01:56:37 INFO : file7.txt: Copied (new) 2025/05/30 01:56:42 INFO : file2.txt: Copied (replaced existing) 2025/05/30 01:56:42 INFO : file4.txt: Deleted 2025/05/30 01:56:42 INFO : Updating listings 2025/05/30 01:56:42 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/changes/path1/" vs Path2 "/tmp/013804ve/changes/path2/" 2025/05/30 01:56:42 INFO : Bisync successful bisync_test.go:560: TEST changes PASSED === RUN TestBisyncRemoteLocal/check_access 2025/05/30 01:57:14 ERROR : error listing: directory not found 2025/05/30 01:57:14 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_check_access-mobukaq5 2025/05/30 01:57:36 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1 2025/05/30 01:57:47 NOTICE: checking path2 Local file system at /tmp/013804ve/check_access/path2 2025/05/30 01:57:47 NOTICE: (01) : test check-access 2025/05/30 01:57:47 NOTICE: (02) : test initial bisync 2025/05/30 01:57:47 NOTICE: (03) : bisync resync 2025/05/30 01:57:59 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:57:59 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:57:59 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:57:59 INFO : Copying Path2 files to Path1 2025/05/30 01:57:59 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 01:58:04 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 01:58:05 INFO : Resync updating listings 2025/05/30 01:58:05 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:58:05 INFO : Bisync successful 2025/05/30 01:58:05 NOTICE: (04) : test 1. see that check-access passes with the initial setup 2025/05/30 01:58:05 NOTICE: (05) : bisync check-access 2025/05/30 01:58:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:58:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:58:15 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:58:15 INFO : Building Path1 and Path2 listings 2025/05/30 01:58:21 INFO : Path1 checking for diffs 2025/05/30 01:58:21 INFO : Path2 checking for diffs 2025/05/30 01:58:21 INFO : Checking access health 2025/05/30 01:58:21 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/05/30 01:58:21 INFO : No changes found 2025/05/30 01:58:21 INFO : Updating listings 2025/05/30 01:58:21 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:58:21 INFO : Bisync successful 2025/05/30 01:58:21 NOTICE: (06) : test 2. delete the path2 subdir RCLONE_TEST and run sync. should fail critical. 2025/05/30 01:58:21 NOTICE: (07) : delete-file /tmp/013804ve/check_access/path2/subdir/RCLONE_TEST 2025/05/30 01:58:21 NOTICE: (08) : bisync check-access 2025/05/30 01:58:32 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:58: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/05/30 01:58:32 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:58:32 INFO : Building Path1 and Path2 listings 2025/05/30 01:58:37 INFO : Path1 checking for diffs 2025/05/30 01:58:37 INFO : Path2 checking for diffs 2025/05/30 01:58:37 INFO : - Path2 File was deleted - subdir/RCLONE_TEST 2025/05/30 01:58:37 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 01:58:37 INFO : Checking access health 2025/05/30 01:58:37 ERROR : Access test failed: Path1 count 2, Path2 count 1 - RCLONE_TEST 2025/05/30 01:58:37 ERROR : -  Access test failed: Path1 file not found in Path2 - subdir/RCLONE_TEST 2025/05/30 01:58:37 ERROR : Bisync critical error: check file check failed 2025/05/30 01:58:37 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 01:58:37 NOTICE: Bisync error: bisync aborted 2025/05/30 01:58:37 NOTICE: (09) : copy-listings path2-missing 2025/05/30 01:58:37 NOTICE: (10) : test 3. put the path2 subdir RCLONE_TEST back, resync. 2025/05/30 01:58:37 NOTICE: (11) : copy-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/subdir/RCLONE_TEST /tmp/013804ve/check_access/path2/ 2025/05/30 01:58:48 NOTICE: (12) : bisync resync 2025/05/30 01:58:59 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:58:59 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:58:59 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:58:59 INFO : Copying Path2 files to Path1 2025/05/30 01:58:59 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 01:59:04 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 01:59:05 INFO : subdir/RCLONE_TEST: Copied (new) 2025/05/30 01:59:05 INFO : Resync updating listings 2025/05/30 01:59:05 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:59:05 INFO : Bisync successful 2025/05/30 01:59:05 NOTICE: (13) : test 4. run sync with check-access. should pass. 2025/05/30 01:59:05 NOTICE: (14) : bisync check-access 2025/05/30 01:59:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:59:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 01:59:15 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:59:15 INFO : Building Path1 and Path2 listings 2025/05/30 01:59:16 INFO : Path1 checking for diffs 2025/05/30 01:59:16 INFO : Path2 checking for diffs 2025/05/30 01:59:16 INFO : Checking access health 2025/05/30 01:59:16 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/05/30 01:59:16 INFO : No changes found 2025/05/30 01:59:16 INFO : Updating listings 2025/05/30 01:59:16 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:59:16 INFO : Bisync successful 2025/05/30 01:59:16 NOTICE: (15) : test 5. delete path1 top level RCLONE_TEST, run sync. should fail critical. 2025/05/30 01:59:16 NOTICE: (16) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/RCLONE_TEST 2025/05/30 01:59:42 NOTICE: (17) : bisync check-access 2025/05/30 01:59:53 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 01:59: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/05/30 01:59:53 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 01:59:53 INFO : Building Path1 and Path2 listings 2025/05/30 01:59:59 INFO : Path1 checking for diffs 2025/05/30 01:59:59 INFO : - Path1 File was deleted - RCLONE_TEST 2025/05/30 01:59:59 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 01:59:59 INFO : Path2 checking for diffs 2025/05/30 01:59:59 INFO : Checking access health 2025/05/30 01:59:59 ERROR : Access test failed: Path1 count 1, Path2 count 2 - RCLONE_TEST 2025/05/30 01:59:59 ERROR : -  Access test failed: Path2 file not found in Path1 - RCLONE_TEST 2025/05/30 01:59:59 ERROR : Bisync critical error: check file check failed 2025/05/30 01:59:59 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 01:59:59 NOTICE: Bisync error: bisync aborted 2025/05/30 01:59:59 NOTICE: (18) : copy-listings path1-missing 2025/05/30 01:59:59 NOTICE: (19) : test 6. run again. should fail critical due to missing listings. 2025/05/30 01:59:59 NOTICE: (20) : bisync check-access 2025/05/30 02:00:09 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:00:09 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:00:09 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 02:00:09 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/013804ve/workdir/TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_access_path1..tmp_013804ve_check_access_path2.path1.lst Path2: /tmp/013804ve/workdir/TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_access_path1..tmp_013804ve_check_access_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/013804ve/workdir" 2025/05/30 02:00:09 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:00:09 NOTICE: Bisync error: bisync aborted 2025/05/30 02:00:09 NOTICE: (21) : move-listings missing-listings 2025/05/30 02:00:09 NOTICE: (22) : test 7. run resync, which will copy the path2 top level back to path1. 2025/05/30 02:00:09 NOTICE: (23) : bisync resync 2025/05/30 02:00:20 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:00: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/05/30 02:00:20 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 02:00:20 INFO : Copying Path2 files to Path1 2025/05/30 02:00:20 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:00:31 INFO : RCLONE_TEST: Copied (new) 2025/05/30 02:00:31 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:00:36 INFO : Resync updating listings 2025/05/30 02:00:36 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 02:00:36 INFO : Bisync successful 2025/05/30 02:00:36 NOTICE: (24) : test 8. run sync with --check-access. should pass. 2025/05/30 02:00:36 NOTICE: (25) : bisync check-access 2025/05/30 02:00:47 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:00: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/05/30 02:00:47 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" with Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 02:00:47 INFO : Building Path1 and Path2 listings 2025/05/30 02:00:48 INFO : Path1 checking for diffs 2025/05/30 02:00:48 INFO : Path2 checking for diffs 2025/05/30 02:00:48 INFO : Checking access health 2025/05/30 02:00:48 INFO : Found 2 matching "RCLONE_TEST" files on both paths 2025/05/30 02:00:48 INFO : No changes found 2025/05/30 02:00:48 INFO : Updating listings 2025/05/30 02:00:48 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access/path1/" vs Path2 "/tmp/013804ve/check_access/path2/" 2025/05/30 02:00:48 INFO : Bisync successful bisync_test.go:560: TEST check_access PASSED === RUN TestBisyncRemoteLocal/check_access_filters 2025/05/30 02:01:15 ERROR : error listing: directory not found 2025/05/30 02:01:15 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_check_access_filters-xahubir7 2025/05/30 02:02:11 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1 2025/05/30 02:02:44 NOTICE: checking path2 Local file system at /tmp/013804ve/check_access_filters/path2 2025/05/30 02:02:44 NOTICE: (01) : test check-access-filters 2025/05/30 02:02:44 NOTICE: (02) : test EXCLUDE - OTHER TESTS 2025/05/30 02:02:44 NOTICE: (03) : copy-file /tmp/013804ve/datadir/test_check_access_filters-cocokuw0/exclude-other-filtersfile.txt /tmp/013804ve/workdir/ 2025/05/30 02:02:44 NOTICE: (04) : test resync to get the filters file md5 built. 2025/05/30 02:02:44 NOTICE: (05) : bisync resync filters-file=/tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:02:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:02:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:02:55 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:02:55 INFO : Using filters file /tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:02:55 INFO : Storing filters file hash to /tmp/013804ve/workdir/exclude-other-filtersfile.txt.md5 2025/05/30 02:02:55 INFO : Copying Path2 files to Path1 2025/05/30 02:02:55 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:03:01 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:03:17 INFO : Resync updating listings 2025/05/30 02:03:17 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:03:17 INFO : Bisync successful 2025/05/30 02:03:17 NOTICE: (06) : test EXCLUDE - test filters for check access 2025/05/30 02:03:17 NOTICE: (07) : bisync check-access filters-file=/tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:03:28 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:03:28 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:03:28 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:03:28 INFO : Using filters file /tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:03:28 INFO : Building Path1 and Path2 listings 2025/05/30 02:03:44 INFO : Path1 checking for diffs 2025/05/30 02:03:44 INFO : Path2 checking for diffs 2025/05/30 02:03:44 INFO : Checking access health 2025/05/30 02:03:44 INFO : Found 3 matching "RCLONE_TEST" files on both paths 2025/05/30 02:03:44 INFO : No changes found 2025/05/30 02:03:44 INFO : Updating listings 2025/05/30 02:03:44 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:03:44 INFO : Bisync successful 2025/05/30 02:03:44 NOTICE: (08) : copy-listings exclude-initial 2025/05/30 02:03:44 NOTICE: (09) : test EXCLUDE - delete RCLONE_TEST files in excluded directories 2025/05/30 02:03:44 NOTICE: (10) : delete-file /tmp/013804ve/check_access_filters/path2/subdir/subdirA/RCLONE_TEST 2025/05/30 02:03:44 NOTICE: (11) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdir-not/RCLONE_TEST 2025/05/30 02:04:20 NOTICE: (12) : delete-file /tmp/013804ve/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST 2025/05/30 02:04:20 NOTICE: (13) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdirX/RCLONE_TEST 2025/05/30 02:04:37 NOTICE: (14) : test EXCLUDE - test should PASS 2025/05/30 02:04:37 NOTICE: (15) : bisync check-access filters-file=/tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:04:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:04: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/05/30 02:04:48 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:04:48 INFO : Using filters file /tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:04:48 INFO : Building Path1 and Path2 listings 2025/05/30 02:04:59 INFO : Path1 checking for diffs 2025/05/30 02:04:59 INFO : Path2 checking for diffs 2025/05/30 02:04:59 INFO : Checking access health 2025/05/30 02:04:59 INFO : Found 3 matching "RCLONE_TEST" files on both paths 2025/05/30 02:04:59 INFO : No changes found 2025/05/30 02:04:59 INFO : Updating listings 2025/05/30 02:04:59 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:04:59 INFO : Bisync successful 2025/05/30 02:04:59 NOTICE: (16) : copy-listings exclude-pass-run 2025/05/30 02:04:59 NOTICE: (17) : test EXCLUDE - delete RCLONE_TEST files in included directories 2025/05/30 02:04:59 NOTICE: (18) : delete-file /tmp/013804ve/check_access_filters/path2/RCLONE_TEST 2025/05/30 02:04:59 NOTICE: (19) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdir/RCLONE_TEST 2025/05/30 02:05:10 NOTICE: (20) : test EXCLUDE - test should ABORT 2025/05/30 02:05:10 NOTICE: (21) : bisync check-access filters-file=/tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:05:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:05:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:05:21 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:05:21 INFO : Using filters file /tmp/013804ve/workdir/exclude-other-filtersfile.txt 2025/05/30 02:05:21 INFO : Building Path1 and Path2 listings 2025/05/30 02:05:27 INFO : Path1 checking for diffs 2025/05/30 02:05:27 INFO : - Path1 File was deleted - subdir/RCLONE_TEST 2025/05/30 02:05:27 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 02:05:27 INFO : Path2 checking for diffs 2025/05/30 02:05:27 INFO : - Path2 File was deleted - RCLONE_TEST 2025/05/30 02:05:27 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 02:05:27 INFO : Checking access health 2025/05/30 02:05:27 ERROR : -  Access test failed: Path1 file not found in Path2 - RCLONE_TEST 2025/05/30 02:05:27 ERROR : -  Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST 2025/05/30 02:05:27 ERROR : Bisync critical error: check file check failed 2025/05/30 02:05:27 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:05:27 NOTICE: Bisync error: bisync aborted 2025/05/30 02:05:27 NOTICE: (22) : move-listings exclude-error-run 2025/05/30 02:05:27 NOTICE: (23) : test INCLUDE - OTHER TESTS 2025/05/30 02:05:27 NOTICE: (24) : test reset to the initial state 2025/05/30 02:05:27 NOTICE: (25) : copy-dir /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_check_access_filters/initial TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/ 2025/05/30 02:06:12 NOTICE: (26) : sync-dir TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/ /tmp/013804ve/check_access_filters/path2/ 2025/05/30 02:06:38 NOTICE: (27) : copy-file /tmp/013804ve/datadir/test_check_access_filters-cocokuw0/include-other-filtersfile.txt /tmp/013804ve/workdir/ 2025/05/30 02:06:38 NOTICE: (28) : bisync resync filters-file=/tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:06:49 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:06: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/05/30 02:06:49 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:06:49 INFO : Using filters file /tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:06:49 INFO : Storing filters file hash to /tmp/013804ve/workdir/include-other-filtersfile.txt.md5 2025/05/30 02:06:49 INFO : Copying Path2 files to Path1 2025/05/30 02:06:49 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:06:59 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:07:05 INFO : Resync updating listings 2025/05/30 02:07:05 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:07:05 INFO : Bisync successful 2025/05/30 02:07:05 NOTICE: (29) : test INCLUDE - test include/exclude filters for check access 2025/05/30 02:07:05 NOTICE: (30) : bisync check-access filters-file=/tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:07:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:07:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:07:15 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:07:15 INFO : Using filters file /tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:07:15 INFO : Building Path1 and Path2 listings 2025/05/30 02:07:32 INFO : Path1 checking for diffs 2025/05/30 02:07:32 INFO : Path2 checking for diffs 2025/05/30 02:07:32 INFO : Checking access health 2025/05/30 02:07:32 INFO : Found 5 matching "RCLONE_TEST" files on both paths 2025/05/30 02:07:32 INFO : No changes found 2025/05/30 02:07:32 INFO : Updating listings 2025/05/30 02:07:32 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:07:32 INFO : Bisync successful 2025/05/30 02:07:32 NOTICE: (31) : copy-listings include-initial 2025/05/30 02:07:32 NOTICE: (32) : test INCLUDE - delete RCLONE_TEST files in excluded directories 2025/05/30 02:07:32 NOTICE: (33) : delete-file /tmp/013804ve/check_access_filters/path2/subdir/subdirA/RCLONE_TEST 2025/05/30 02:07:32 NOTICE: (34) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdir-not/RCLONE_TEST 2025/05/30 02:07:53 NOTICE: (35) : delete-file /tmp/013804ve/check_access_filters/path2/subdir-not/subdir-not2/RCLONE_TEST 2025/05/30 02:07:53 NOTICE: (36) : test INCLUDE - test should PASS 2025/05/30 02:07:53 NOTICE: (37) : bisync check-access filters-file=/tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:08:03 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:08:03 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:08:03 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:08:03 INFO : Using filters file /tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:08:03 INFO : Building Path1 and Path2 listings 2025/05/30 02:08:14 INFO : Path1 checking for diffs 2025/05/30 02:08:14 INFO : Path2 checking for diffs 2025/05/30 02:08:14 INFO : Checking access health 2025/05/30 02:08:14 INFO : Found 5 matching "RCLONE_TEST" files on both paths 2025/05/30 02:08:14 INFO : No changes found 2025/05/30 02:08:14 INFO : Updating listings 2025/05/30 02:08:14 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" vs Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:08:14 INFO : Bisync successful 2025/05/30 02:08:14 NOTICE: (38) : copy-listings include-pass-run 2025/05/30 02:08:14 NOTICE: (39) : test INCLUDE - delete RCLONE_TEST files in included directories 2025/05/30 02:08:14 NOTICE: (40) : delete-file /tmp/013804ve/check_access_filters/path2/RCLONE_TEST 2025/05/30 02:08:14 NOTICE: (41) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdir/RCLONE_TEST 2025/05/30 02:08:15 NOTICE: (42) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/subdirX/subdirX1/RCLONE_TEST 2025/05/30 02:08:46 NOTICE: (43) : test INCLUDE - test should ABORT 2025/05/30 02:08:46 NOTICE: (44) : bisync check-access filters-file=/tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:08:57 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:08: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/05/30 02:08:57 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_access_filters/path1/" with Path2 "/tmp/013804ve/check_access_filters/path2/" 2025/05/30 02:08:57 INFO : Using filters file /tmp/013804ve/workdir/include-other-filtersfile.txt 2025/05/30 02:08:57 INFO : Building Path1 and Path2 listings 2025/05/30 02:09:03 INFO : Path1 checking for diffs 2025/05/30 02:09:03 INFO : - Path1 File was deleted - subdir/RCLONE_TEST 2025/05/30 02:09:03 INFO : - Path1 File was deleted - subdirX/subdirX1/RCLONE_TEST 2025/05/30 02:09:03 INFO : Path1: 2 changes:  0 new,  0 modified,  2 deleted 2025/05/30 02:09:03 INFO : Path2 checking for diffs 2025/05/30 02:09:03 INFO : - Path2 File was deleted - RCLONE_TEST 2025/05/30 02:09:03 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 02:09:03 INFO : Checking access health 2025/05/30 02:09:03 ERROR : Access test failed: Path1 count 3, Path2 count 4 - RCLONE_TEST 2025/05/30 02:09:03 ERROR : -  Access test failed: Path1 file not found in Path2 - RCLONE_TEST 2025/05/30 02:09:03 ERROR : -  Access test failed: Path2 file not found in Path1 - subdir/RCLONE_TEST 2025/05/30 02:09:03 ERROR : -  Access test failed: Path2 file not found in Path1 - subdirX/subdirX1/RCLONE_TEST 2025/05/30 02:09:03 ERROR : Bisync critical error: check file check failed 2025/05/30 02:09:03 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:09:03 NOTICE: Bisync error: bisync aborted 2025/05/30 02:09:03 NOTICE: (45) : move-listings include-error-run bisync_test.go:560: TEST check_access_filters PASSED === RUN TestBisyncRemoteLocal/check_filename 2025/05/30 02:09:45 ERROR : error listing: directory not found 2025/05/30 02:09:45 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_check_filename-vararew9 2025/05/30 02:10:08 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1 2025/05/30 02:10:19 NOTICE: checking path2 Local file system at /tmp/013804ve/check_filename/path2 2025/05/30 02:10:19 NOTICE: (01) : test check-filename 2025/05/30 02:10:19 NOTICE: (02) : test initial bisync 2025/05/30 02:10:19 NOTICE: (03) : bisync resync 2025/05/30 02:11:03 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:11:03 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:11:03 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" with Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:11:03 INFO : Copying Path2 files to Path1 2025/05/30 02:11:03 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:11:04 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:11:09 INFO : Resync updating listings 2025/05/30 02:11:09 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" vs Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:11:09 INFO : Bisync successful 2025/05/30 02:11:09 NOTICE: (04) : test 1. see that check-access passes with the initial setup 2025/05/30 02:11:09 NOTICE: (05) : bisync check-access check-filename=.chk_file 2025/05/30 02:11:50 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:11: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/05/30 02:11:50 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" with Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:11:50 INFO : Building Path1 and Path2 listings 2025/05/30 02:11:56 INFO : Path1 checking for diffs 2025/05/30 02:11:56 INFO : Path2 checking for diffs 2025/05/30 02:11:56 INFO : Checking access health 2025/05/30 02:11:56 INFO : Found 2 matching ".chk_file" files on both paths 2025/05/30 02:11:56 INFO : No changes found 2025/05/30 02:11:56 INFO : Updating listings 2025/05/30 02:11:56 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" vs Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:11:56 INFO : Bisync successful 2025/05/30 02:11:56 NOTICE: (06) : copy-listings initial-pass 2025/05/30 02:11:56 NOTICE: (07) : test 2. delete the remote subdir .chk_file, run sync. should fail critical. 2025/05/30 02:11:56 NOTICE: (08) : delete-file /tmp/013804ve/check_filename/path2/subdir/.chk_file 2025/05/30 02:11:56 NOTICE: (09) : bisync check-access check-filename=.chk_file 2025/05/30 02:12:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:12:37 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:12:37 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" with Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:12:37 INFO : Building Path1 and Path2 listings 2025/05/30 02:12:42 INFO : Path1 checking for diffs 2025/05/30 02:12:42 INFO : Path2 checking for diffs 2025/05/30 02:12:42 INFO : - Path2 File was deleted - subdir/.chk_file 2025/05/30 02:12:42 INFO : Path2: 1 changes:  0 new,  0 modified,  1 deleted 2025/05/30 02:12:42 INFO : Checking access health 2025/05/30 02:12:42 ERROR : Access test failed: Path1 count 2, Path2 count 1 - .chk_file 2025/05/30 02:12:42 ERROR : -  Access test failed: Path1 file not found in Path2 - subdir/.chk_file 2025/05/30 02:12:42 ERROR : Bisync critical error: check file check failed 2025/05/30 02:12:42 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:12:42 NOTICE: Bisync error: bisync aborted 2025/05/30 02:12:42 NOTICE: (10) : move-listings path2-missing 2025/05/30 02:12:42 NOTICE: (11) : test 3. put the remote subdir .chk_file back, run resync. 2025/05/30 02:12:42 NOTICE: (12) : copy-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/subdir/.chk_file /tmp/013804ve/check_filename/path2/subdir/ 2025/05/30 02:12:43 NOTICE: (13) : bisync check-access resync check-filename=.chk_file 2025/05/30 02:13:27 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:13:27 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:13:27 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" with Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:13:27 INFO : Copying Path2 files to Path1 2025/05/30 02:13:27 INFO : Checking access health 2025/05/30 02:13:33 INFO : Found 2 matching ".chk_file" files on both paths 2025/05/30 02:13:33 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:13:43 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:13:43 INFO : Resync updating listings 2025/05/30 02:13:43 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" vs Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:13:43 INFO : Bisync successful 2025/05/30 02:13:43 NOTICE: (14) : test 4. run sync with check-access. should pass. 2025/05/30 02:13:43 NOTICE: (15) : bisync check-access check-filename=.chk_file 2025/05/30 02:14:24 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:14: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/05/30 02:14:24 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" with Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:14:24 INFO : Building Path1 and Path2 listings 2025/05/30 02:14:35 INFO : Path1 checking for diffs 2025/05/30 02:14:35 INFO : Path2 checking for diffs 2025/05/30 02:14:35 INFO : Checking access health 2025/05/30 02:14:35 INFO : Found 2 matching ".chk_file" files on both paths 2025/05/30 02:14:35 INFO : No changes found 2025/05/30 02:14:35 INFO : Updating listings 2025/05/30 02:14:35 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_filename/path1/" vs Path2 "/tmp/013804ve/check_filename/path2/" 2025/05/30 02:14:35 INFO : Bisync successful bisync_test.go:560: TEST check_filename PASSED === RUN TestBisyncRemoteLocal/check_sync 2025/05/30 02:15:13 ERROR : error listing: directory not found 2025/05/30 02:15:13 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_check_sync-wayebuk4 2025/05/30 02:15:51 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1 2025/05/30 02:16:17 NOTICE: checking path2 Local file system at /tmp/013804ve/check_sync/path2 2025/05/30 02:16:17 NOTICE: (01) : test check-sync 2025/05/30 02:16:17 NOTICE: (02) : test initial bisync 2025/05/30 02:16:17 NOTICE: (03) : bisync resync 2025/05/30 02:16:43 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:16:43 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:16:43 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" with Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:16:43 INFO : Copying Path2 files to Path1 2025/05/30 02:16:43 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:16:55 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:17:10 INFO : Resync updating listings 2025/05/30 02:17:10 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" vs Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:17:10 INFO : Bisync successful 2025/05/30 02:17:10 NOTICE: (04) : test 1. run check-sync-only on a clean sync 2025/05/30 02:17:10 NOTICE: (05) : bisync check-sync-only 2025/05/30 02:17:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:17:37 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:17:37 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" vs Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:17:37 INFO : Bisync successful 2025/05/30 02:17:37 NOTICE: (06) : test 2. inject modified listings into the workdir 2025/05/30 02:17:37 NOTICE: (07) : copy-as /tmp/013804ve/datadir/test_check_sync-lufowiw6/_testdir_path1.._testdir_path2.path1.lst /tmp/013804ve/workdir/ TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_sync_path1..tmp_013804ve_check_sync_path2.path1.lst 2025/05/30 02:17:37 NOTICE: (08) : copy-as /tmp/013804ve/datadir/test_check_sync-lufowiw6/_testdir_path1.._testdir_path2.path2.lst /tmp/013804ve/workdir/ TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_sync_path1..tmp_013804ve_check_sync_path2.path2.lst 2025/05/30 02:17:37 NOTICE: (09) : test 3. run check-sync-only on modified listings 2025/05/30 02:17:37 NOTICE: (10) : bisync check-sync-only 2025/05/30 02:18:02 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:18:02 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:18:02 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" vs Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:18:02 ERROR : -  Path1 file not found in Path2 - file2.txt 2025/05/30 02:18:02 ERROR : -  Path2 file not found in Path1 - file1.txt 2025/05/30 02:18:02 ERROR : Bisync critical error: path1 and path2 are out of sync, run --resync to recover 2025/05/30 02:18:02 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:18:02 NOTICE: Bisync error: bisync aborted 2025/05/30 02:18:02 NOTICE: (11) : copy-listings check-sync-only 2025/05/30 02:18:02 NOTICE: (12) : test 4. run normal sync to check that it aborts 2025/05/30 02:18:02 NOTICE: (13) : bisync 2025/05/30 02:18:29 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:18: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/05/30 02:18:29 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" with Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:18:29 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/013804ve/workdir/TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_sync_path1..tmp_013804ve_check_sync_path2.path1.lst Path2: /tmp/013804ve/workdir/TestCompressSwift_rclone-test-dadizoj1sidu_013804ve_check_sync_path1..tmp_013804ve_check_sync_path2.path2.lst Try running this command to inspect the work dir: rclone lsl "/tmp/013804ve/workdir" 2025/05/30 02:18:29 ERROR : Bisync aborted. Must run --resync to recover. 2025/05/30 02:18:29 NOTICE: Bisync error: bisync aborted 2025/05/30 02:18:29 NOTICE: (14) : test 5. prune failure listings after critical abort 2025/05/30 02:18:29 NOTICE: (15) : delete-glob /tmp/013804ve/workdir/ *.lst 2025/05/30 02:18:29 NOTICE: (16) : delete-glob /tmp/013804ve/workdir/ *.lst-err 2025/05/30 02:18:29 NOTICE: (17) : delete-glob /tmp/013804ve/workdir/ *.lst-new 2025/05/30 02:18:29 NOTICE: (18) : test 6. run resync 2025/05/30 02:18:29 NOTICE: (19) : bisync resync 2025/05/30 02:18:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:18:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:18:55 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" with Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:18:55 INFO : Copying Path2 files to Path1 2025/05/30 02:18:55 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:19:10 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:19:16 INFO : Resync updating listings 2025/05/30 02:19:16 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" vs Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:19:16 INFO : Bisync successful 2025/05/30 02:19:16 NOTICE: (20) : test 7. run normal sync with check-sync enabled (default) 2025/05/30 02:19:16 NOTICE: (21) : bisync 2025/05/30 02:19:45 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:19:45 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:19:45 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" with Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:19:45 INFO : Building Path1 and Path2 listings 2025/05/30 02:19:50 INFO : Path1 checking for diffs 2025/05/30 02:19:50 INFO : Path2 checking for diffs 2025/05/30 02:19:50 INFO : No changes found 2025/05/30 02:19:50 INFO : Updating listings 2025/05/30 02:19:50 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" vs Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:19:50 INFO : Bisync successful 2025/05/30 02:19:50 NOTICE: (22) : test 8. run normal sync with no-check-sync 2025/05/30 02:19:50 NOTICE: (23) : bisync no-check-sync 2025/05/30 02:20:16 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:20: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/05/30 02:20:16 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/check_sync/path1/" with Path2 "/tmp/013804ve/check_sync/path2/" 2025/05/30 02:20:16 INFO : Building Path1 and Path2 listings 2025/05/30 02:20:16 INFO : Path1 checking for diffs 2025/05/30 02:20:16 INFO : Path2 checking for diffs 2025/05/30 02:20:16 INFO : No changes found 2025/05/30 02:20:16 INFO : Updating listings 2025/05/30 02:20:16 INFO : Bisync successful bisync_test.go:560: TEST check_sync PASSED === RUN TestBisyncRemoteLocal/compare_all 2025/05/30 02:20:44 ERROR : error listing: directory not found 2025/05/30 02:20:44 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_compare_all-jirotum9 2025/05/30 02:21:16 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1 2025/05/30 02:21:23 NOTICE: checking path2 Local file system at /tmp/013804ve/compare_all/path2 2025/05/30 02:21:23 NOTICE: (01) : test changes compare-all 2025/05/30 02:21:23 NOTICE: (02) : test initial bisync 2025/05/30 02:21:23 NOTICE: (03) : bisync resync compare-all 2025/05/30 02:21:58 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": true, "HashType1": 1, "HashType2": 1, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": true } 2025/05/30 02:21:58 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/" with Path2 "/tmp/013804ve/compare_all/path2/" 2025/05/30 02:21:58 INFO : Copying Path2 files to Path1 2025/05/30 02:21:58 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:22:30 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:22:41 INFO : Resync updating listings 2025/05/30 02:22:41 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/" vs Path2 "/tmp/013804ve/compare_all/path2/" 2025/05/30 02:22:41 INFO : Bisync successful 2025/05/30 02:22:41 NOTICE: (04) : test make modifications on both paths 2025/05/30 02:22:41 NOTICE: (05) : test new on path2 - file10 2025/05/30 02:22:41 NOTICE: (06) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file10.txt /tmp/013804ve/compare_all/path2/ 2025/05/30 02:22:41 NOTICE: (07) : test newer on path2 - file1 2025/05/30 02:22:41 NOTICE: (08) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file1.txt /tmp/013804ve/compare_all/path2/ 2025/05/30 02:22:41 NOTICE: (09) : test new on path1 - file11 2025/05/30 02:22:41 NOTICE: (10) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file11.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/ 2025/05/30 02:23:09 NOTICE: (11) : test newer on path1 - file2 2025/05/30 02:23:09 NOTICE: (12) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file2.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/ 2025/05/30 02:23:26 NOTICE: (13) : test deleted on path2 - file3 2025/05/30 02:23:26 NOTICE: (14) : delete-file /tmp/013804ve/compare_all/path2/file3.txt 2025/05/30 02:23:26 NOTICE: (15) : test deleted on path1 - file4 2025/05/30 02:23:26 NOTICE: (16) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file4.txt 2025/05/30 02:23:48 NOTICE: (17) : test deleted on both paths - file8 2025/05/30 02:23:48 NOTICE: (18) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file8.txt 2025/05/30 02:23:53 NOTICE: (19) : delete-file /tmp/013804ve/compare_all/path2/file8.txt 2025/05/30 02:23:53 NOTICE: (20) : test changed on both paths - file5 (file5R, file5L) 2025/05/30 02:23:53 NOTICE: (21) : touch-glob 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/ file5R.txt 2025/05/30 02:23:53 NOTICE: (22) : copy-as /tmp/013804ve/datadir/test_compare_all-gevunis5/file5R.txt /tmp/013804ve/compare_all/path2/ file5.txt 2025/05/30 02:23:53 NOTICE: (23) : touch-glob 2001-03-04 /tmp/013804ve/datadir/test_compare_all-gevunis5/ file5L.txt 2025/05/30 02:23:53 NOTICE: (24) : copy-as /tmp/013804ve/datadir/test_compare_all-gevunis5/file5L.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/ file5.txt 2025/05/30 02:24:15 NOTICE: (25) : test newer on path2 and deleted on path1 - file6 2025/05/30 02:24:15 NOTICE: (26) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file6.txt /tmp/013804ve/compare_all/path2/ 2025/05/30 02:24:15 NOTICE: (27) : delete-file TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file6.txt 2025/05/30 02:24:20 NOTICE: (28) : test newer on path1 and deleted on path2 - file7 2025/05/30 02:24:20 NOTICE: (29) : touch-copy 2001-01-02 /tmp/013804ve/datadir/test_compare_all-gevunis5/file7.txt TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/ 2025/05/30 02:24:37 NOTICE: (30) : delete-file /tmp/013804ve/compare_all/path2/file7.txt 2025/05/30 02:24:37 NOTICE: (31) : test bisync run 2025/05/30 02:24:37 NOTICE: (32) : bisync compare-all 2025/05/30 02:25:12 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": true, "HashType1": 1, "HashType2": 1, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": true } 2025/05/30 02:25:12 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/" with Path2 "/tmp/013804ve/compare_all/path2/" 2025/05/30 02:25:12 INFO : Building Path1 and Path2 listings 2025/05/30 02:25:44 INFO : Path1 checking for diffs 2025/05/30 02:25:44 INFO : - Path1 File changed: size (larger), time (newer), hash - file2.txt 2025/05/30 02:25:44 INFO : - Path1 File was deleted - file4.txt 2025/05/30 02:25:44 INFO : - Path1 File changed: size (larger), time (newer), hash - file5.txt 2025/05/30 02:25:44 INFO : - Path1 File was deleted - file6.txt 2025/05/30 02:25:44 INFO : - Path1 File changed: size (larger), time (newer), hash - file7.txt 2025/05/30 02:25:44 INFO : - Path1 File was deleted - file8.txt 2025/05/30 02:25:44 INFO : - Path1 File is new - file11.txt 2025/05/30 02:25:44 INFO : Path1: 7 changes:  1 new,  3 modified,  3 deleted 2025/05/30 02:25:44 INFO : (Modified:  3 newer,  0 older,  3 larger,  0 smaller,  3 hash differs) 2025/05/30 02:25:44 INFO : Path2 checking for diffs 2025/05/30 02:25:44 INFO : - Path2 File changed: size (larger), time (newer), hash - file1.txt 2025/05/30 02:25:44 INFO : - Path2 File was deleted - file3.txt 2025/05/30 02:25:44 INFO : - Path2 File changed: size (larger), time (newer), hash - file5.txt 2025/05/30 02:25:44 INFO : - Path2 File changed: size (larger), time (newer), hash - file6.txt 2025/05/30 02:25:44 INFO : - Path2 File was deleted - file7.txt 2025/05/30 02:25:44 INFO : - Path2 File was deleted - file8.txt 2025/05/30 02:25:44 INFO : - Path2 File is new - file10.txt 2025/05/30 02:25:44 INFO : Path2: 7 changes:  1 new,  3 modified,  3 deleted 2025/05/30 02:25:44 INFO : (Modified:  3 newer,  0 older,  3 larger,  0 smaller,  3 hash differs) 2025/05/30 02:25:44 INFO : Applying changes 2025/05/30 02:25:44 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/compare_all/path2/file11.txt 2025/05/30 02:25:44 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/compare_all/path2/file2.txt 2025/05/30 02:25:44 INFO : - Path2 Queue delete - /tmp/013804ve/compare_all/path2/file4.txt 2025/05/30 02:25:44 NOTICE: - WARNING New or changed in both paths - file5.txt 2025/05/30 02:25:44 NOTICE: - Path1 Renaming Path1 copy - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file5.txt.conflict1 2025/05/30 02:26:10 INFO : file5.txt: Copied (server-side copy) to: file5.txt.conflict1 2025/05/30 02:26:15 INFO : file5.txt: Deleted 2025/05/30 02:26:15 NOTICE: - Path1 Queue copy to Path2 - /tmp/013804ve/compare_all/path2/file5.txt.conflict1 2025/05/30 02:26:15 NOTICE: - Path2 Renaming Path2 copy - /tmp/013804ve/compare_all/path2/file5.txt.conflict2 2025/05/30 02:26:15 INFO : file5.txt: Moved (server-side) to: file5.txt.conflict2 2025/05/30 02:26:15 NOTICE: - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file5.txt.conflict2 2025/05/30 02:26:15 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file6.txt 2025/05/30 02:26:15 INFO : - Path1 Queue copy to Path2 - /tmp/013804ve/compare_all/path2/file7.txt 2025/05/30 02:26:15 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file1.txt 2025/05/30 02:26:15 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file10.txt 2025/05/30 02:26:15 INFO : - Path1 Queue delete - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/file3.txt 2025/05/30 02:26:15 INFO : - Path2 Do queued copies to - Path1 2025/05/30 02:26:26 INFO : file10.txt: Copied (new) 2025/05/30 02:26:31 INFO : file1.txt: Copied (replaced existing) 2025/05/30 02:26:36 INFO : file6.txt: Copied (new) 2025/05/30 02:26:39 INFO : file5.txt.conflict2: Copied (new) 2025/05/30 02:26:50 INFO : file3.txt: Deleted 2025/05/30 02:26:50 INFO : - Path1 Do queued copies to - Path2 2025/05/30 02:27:00 INFO : file11.txt: Copied (new) 2025/05/30 02:27:00 INFO : file5.txt.conflict1: Copied (new) 2025/05/30 02:27:05 INFO : file2.txt: Copied (replaced existing) 2025/05/30 02:27:10 INFO : file7.txt: Copied (new) 2025/05/30 02:27:10 INFO : file4.txt: Deleted 2025/05/30 02:27:10 INFO : Updating listings 2025/05/30 02:27:10 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/compare_all/path1/" vs Path2 "/tmp/013804ve/compare_all/path2/" 2025/05/30 02:27:10 INFO : Bisync successful bisync_test.go:560: TEST compare_all PASSED === RUN TestBisyncRemoteLocal/concurrent 2025/05/30 02:27:32 ERROR : error listing: directory not found 2025/05/30 02:27:32 NOTICE: checking initFs Local file system at /tmp/013804ve/initdir/test_concurrent-tivoxaj8 2025/05/30 02:28:15 NOTICE: checking Path1 Compressed: TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1 2025/05/30 02:28:52 NOTICE: checking path2 Local file system at /tmp/013804ve/concurrent/path2 2025/05/30 02:28:52 NOTICE: (01) : test concurrent 2025/05/30 02:28:52 NOTICE: (02) : test initial bisync 2025/05/30 02:28:52 NOTICE: (03) : bisync resync 2025/05/30 02:29:18 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:29:18 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:29:18 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" with Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:29:18 INFO : Copying Path2 files to Path1 2025/05/30 02:29:18 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:29:34 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:29:44 INFO : Resync updating listings 2025/05/30 02:29:44 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" vs Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:29:44 INFO : Bisync successful 2025/05/30 02:29:44 NOTICE: (04) : test changed on one path - file1 2025/05/30 02:29:44 NOTICE: (05) : touch-glob 2001-01-02 /tmp/013804ve/datadir/test_concurrent-huxasuq1/ file5R.txt 2025/05/30 02:29:44 NOTICE: (06) : touch-glob 2023-08-26 /tmp/013804ve/datadir/test_concurrent-huxasuq1/ file7.txt 2025/05/30 02:29:44 NOTICE: (07) : copy-as /tmp/013804ve/datadir/test_concurrent-huxasuq1/file5R.txt /tmp/013804ve/concurrent/path2/ file1.txt 2025/05/30 02:29:44 NOTICE: (08) : test bisync with file changed during 2025/05/30 02:29:44 NOTICE: (09) : concurrent-func 2025/05/30 02:29:44 NOTICE: (10) : bisync 2025/05/30 02:30:10 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:30:10 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:30:10 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" with Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:30:10 INFO : Building Path1 and Path2 listings 2025/05/30 02:30:20 INFO : Path1 checking for diffs 2025/05/30 02:30:20 INFO : Path2 checking for diffs 2025/05/30 02:30:20 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2025/05/30 02:30:20 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2025/05/30 02:30:20 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2025/05/30 02:30:20 INFO : Applying changes 2025/05/30 02:30:20 INFO : - Path2 Queue copy to Path1 - TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/file1.txt 2025/05/30 02:30:20 INFO : - Path2 Do queued copies to - Path1 2025/05/30 02:30:20 INFO : file7.txt: Copied (replaced existing) to: file1.txt 2025/05/30 02:30:41 INFO : file1.txt: Copied (replaced existing) 2025/05/30 02:30:41 INFO : Updating listings 2025/05/30 02:30:41 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" vs Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:30:41 INFO : Bisync successful 2025/05/30 02:30:41 NOTICE: (11) : bisync 2025/05/30 02:31:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:31: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/05/30 02:31:07 INFO : Synching Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" with Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:31:07 INFO : Building Path1 and Path2 listings 2025/05/30 02:31:18 INFO : Path1 checking for diffs 2025/05/30 02:31:18 INFO : Path2 checking for diffs 2025/05/30 02:31:18 INFO : No changes found 2025/05/30 02:31:18 INFO : Updating listings 2025/05/30 02:31:18 INFO : Validating listings for Path1 "TestCompressSwift:rclone-test-dadizoj1sidu/013804ve/concurrent/path1/" vs Path2 "/tmp/013804ve/concurrent/path2/" 2025/05/30 02:31:18 INFO : Bisync successful bisync_test.go:560: TEST concurrent PASSED === RUN TestBisyncRemoteLocal/createemptysrcdirs bisync_test.go:355: test createemptysrcdirs timed out - not enough time to start test (6m29.14705489s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/dry_run bisync_test.go:355: test dry_run timed out - not enough time to start test (6m29.146945484s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/equal bisync_test.go:355: test equal timed out - not enough time to start test (6m29.146857489s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/ext_paths bisync_test.go:355: test ext_paths timed out - not enough time to start test (6m29.146780043s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/extended_filenames bisync_test.go:355: test extended_filenames timed out - not enough time to start test (6m29.146672512s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/filters bisync_test.go:355: test filters timed out - not enough time to start test (6m29.146548059s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/filtersfile_checks bisync_test.go:355: test filtersfile_checks timed out - not enough time to start test (6m29.146465934s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/ignorelistingchecksum bisync_test.go:355: test ignorelistingchecksum timed out - not enough time to start test (6m29.146406302s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/max_delete_path1 bisync_test.go:355: test max_delete_path1 timed out - not enough time to start test (6m29.146330731s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/max_delete_path2_force bisync_test.go:355: test max_delete_path2_force timed out - not enough time to start test (6m29.146262553s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/nomodtime bisync_test.go:355: test nomodtime timed out - not enough time to start test (6m29.146208141s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/normalization bisync_test.go:355: test normalization timed out - not enough time to start test (6m29.146140885s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/rclone_args bisync_test.go:355: test rclone_args timed out - not enough time to start test (6m29.146066915s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/resolve bisync_test.go:355: test resolve timed out - not enough time to start test (6m29.145999087s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/resync bisync_test.go:355: test resync timed out - not enough time to start test (6m29.145915711s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/resync_modes bisync_test.go:355: test resync_modes timed out - not enough time to start test (6m29.145861209s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/rmdirs bisync_test.go:355: test rmdirs timed out - not enough time to start test (6m29.14579248s remaining, need 8m30.507335038s for test) === RUN TestBisyncRemoteLocal/volatile bisync_test.go:355: test volatile timed out - not enough time to start test (6m29.145659521s remaining, need 8m30.507335038s for test) --- FAIL: TestBisyncRemoteLocal (3241.92s) --- PASS: TestBisyncRemoteLocal/all_changed (489.32s) --- SKIP: TestBisyncRemoteLocal/backupdir (60.19s) --- PASS: TestBisyncRemoteLocal/basic (196.69s) --- PASS: TestBisyncRemoteLocal/changes (387.18s) --- PASS: TestBisyncRemoteLocal/check_access (246.26s) --- PASS: TestBisyncRemoteLocal/check_access_filters (510.51s) --- PASS: TestBisyncRemoteLocal/check_filename (312.31s) --- PASS: TestBisyncRemoteLocal/check_sync (346.06s) --- PASS: TestBisyncRemoteLocal/compare_all (408.79s) --- PASS: TestBisyncRemoteLocal/concurrent (252.88s) --- FAIL: TestBisyncRemoteLocal/createemptysrcdirs (0.00s) --- FAIL: TestBisyncRemoteLocal/dry_run (0.00s) --- FAIL: TestBisyncRemoteLocal/equal (0.00s) --- FAIL: TestBisyncRemoteLocal/ext_paths (0.00s) --- FAIL: TestBisyncRemoteLocal/extended_filenames (0.00s) --- FAIL: TestBisyncRemoteLocal/filters (0.00s) --- FAIL: TestBisyncRemoteLocal/filtersfile_checks (0.00s) --- FAIL: TestBisyncRemoteLocal/ignorelistingchecksum (0.00s) --- FAIL: TestBisyncRemoteLocal/max_delete_path1 (0.00s) --- FAIL: TestBisyncRemoteLocal/max_delete_path2_force (0.00s) --- FAIL: TestBisyncRemoteLocal/nomodtime (0.00s) --- FAIL: TestBisyncRemoteLocal/normalization (0.00s) --- FAIL: TestBisyncRemoteLocal/rclone_args (0.00s) --- FAIL: TestBisyncRemoteLocal/resolve (0.00s) --- FAIL: TestBisyncRemoteLocal/resync (0.00s) --- FAIL: TestBisyncRemoteLocal/resync_modes (0.00s) --- FAIL: TestBisyncRemoteLocal/rmdirs (0.00s) --- FAIL: TestBisyncRemoteLocal/volatile (0.00s) === RUN TestBisyncLocalRemote 2025/05/30 02:32:06 NOTICE: remote: TestCompressSwift:rclone-test-kifatap7faqa === RUN TestBisyncLocalRemote/all_changed 2025/05/30 02:32:29 ERROR : error listing: directory not found 2025/05/30 02:32:29 NOTICE: checking initFs Local file system at /tmp/023206vu/initdir/test_all_changed-xabijop3 2025/05/30 02:32:29 NOTICE: checking Path1 Local file system at /tmp/023206vu/all_changed/path1 2025/05/30 02:33:02 NOTICE: checking path2 Compressed: TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2 2025/05/30 02:33:28 NOTICE: (01) : test all-changed 2025/05/30 02:33:28 NOTICE: (02) : test initial bisync 2025/05/30 02:33:28 NOTICE: (03) : bisync resync 2025/05/30 02:34:07 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:34: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/05/30 02:34:07 INFO : Synching Path1 "/tmp/023206vu/all_changed/path1/" with Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:34:07 INFO : Copying Path2 files to Path1 2025/05/30 02:34:07 INFO : - Path2 Resync is copying files to - Path1 2025/05/30 02:34:17 INFO : - Path1 Resync is copying files to - Path2 2025/05/30 02:34:23 INFO : Resync updating listings 2025/05/30 02:34:23 INFO : Validating listings for Path1 "/tmp/023206vu/all_changed/path1/" vs Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:34:23 INFO : Bisync successful 2025/05/30 02:34:23 NOTICE: (04) : test change timestamp on all files except RCLONE_TEST 2025/05/30 02:34:23 NOTICE: (05) : touch-glob 2005-01-02 /tmp/023206vu/all_changed/path1/ file* 2025/05/30 02:34:23 NOTICE: (06) : touch-glob 2005-01-02 /tmp/023206vu/all_changed/path1/subdir file* 2025/05/30 02:34:23 NOTICE: (07) : test sync should pass 2025/05/30 02:34:23 NOTICE: (08) : bisync 2025/05/30 02:35:00 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:35:00 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:35:00 INFO : Synching Path1 "/tmp/023206vu/all_changed/path1/" with Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:35:00 INFO : Building Path1 and Path2 listings 2025/05/30 02:35:11 INFO : Path1 checking for diffs 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.copy1.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.copy2.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.copy3.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.copy4.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.copy5.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - file1.txt 2025/05/30 02:35:11 INFO : - Path1 File changed: time (newer) - subdir/file20.txt 2025/05/30 02:35:11 INFO : Path1: 7 changes:  0 new,  7 modified,  0 deleted 2025/05/30 02:35:11 INFO : (Modified:  7 newer,  0 older) 2025/05/30 02:35:11 INFO : Path2 checking for diffs 2025/05/30 02:35:11 INFO : Applying changes 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy1.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy2.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy3.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy4.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy5.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.txt 2025/05/30 02:35:11 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/subdir/file20.txt 2025/05/30 02:35:11 INFO : - Path1 Do queued copies to - Path2 2025/05/30 02:35:26 INFO : file1.copy4.txt: Updated modification time in destination 2025/05/30 02:35:31 INFO : file1.copy1.txt: Updated modification time in destination 2025/05/30 02:35:31 INFO : file1.copy2.txt: Updated modification time in destination 2025/05/30 02:35:32 INFO : file1.copy3.txt: Updated modification time in destination 2025/05/30 02:35:33 INFO : file1.txt: Updated modification time in destination 2025/05/30 02:35:41 INFO : file1.copy5.txt: Updated modification time in destination 2025/05/30 02:35:41 INFO : subdir/file20.txt: Updated modification time in destination 2025/05/30 02:35:41 INFO : Updating listings 2025/05/30 02:35:41 INFO : Validating listings for Path1 "/tmp/023206vu/all_changed/path1/" vs Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:35:41 INFO : Bisync successful 2025/05/30 02:35:41 NOTICE: (09) : test change timestamp on all files including RCLONE_TEST 2025/05/30 02:35:41 NOTICE: (10) : touch-glob 2004-01-02 /tmp/023206vu/all_changed/path1/ * 2025/05/30 02:35:41 NOTICE: (11) : touch-glob 2004-01-02 /tmp/023206vu/all_changed/path1/subdir * 2025/05/30 02:35:41 NOTICE: (12) : test sync should fail 2025/05/30 02:35:41 NOTICE: (13) : bisync 2025/05/30 02:36:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:36:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:36:21 INFO : Synching Path1 "/tmp/023206vu/all_changed/path1/" with Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:36:21 INFO : Building Path1 and Path2 listings 2025/05/30 02:36:36 INFO : Path1 checking for diffs 2025/05/30 02:36:36 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - file1.txt 2025/05/30 02:36:36 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/05/30 02:36:36 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/05/30 02:36:36 INFO : (Modified:  1 newer,  7 older) 2025/05/30 02:36:36 INFO : Path2 checking for diffs 2025/05/30 02:36:36 ERROR : Safety abort: all files were changed on Path1 "/tmp/023206vu/all_changed/path1/". Run with --force if desired. 2025/05/30 02:36:36 NOTICE: Bisync aborted. Please try again. 2025/05/30 02:36:36 NOTICE: Bisync error: all files were changed 2025/05/30 02:36:36 NOTICE: (14) : test sync with force should pass 2025/05/30 02:36:36 NOTICE: (15) : bisync force 2025/05/30 02:37:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2025/05/30 02:37:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2025/05/30 02:37:15 INFO : Synching Path1 "/tmp/023206vu/all_changed/path1/" with Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:37:15 INFO : Building Path1 and Path2 listings 2025/05/30 02:37:31 INFO : Path1 checking for diffs 2025/05/30 02:37:31 INFO : - Path1 File changed: time (newer) - RCLONE_TEST 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.copy1.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.copy2.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.copy3.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.copy4.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.copy5.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - file1.txt 2025/05/30 02:37:31 INFO : - Path1 File changed: time (older) - subdir/file20.txt 2025/05/30 02:37:31 INFO : Path1: 8 changes:  0 new,  8 modified,  0 deleted 2025/05/30 02:37:31 INFO : (Modified:  1 newer,  7 older) 2025/05/30 02:37:31 INFO : Path2 checking for diffs 2025/05/30 02:37:31 INFO : Applying changes 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/RCLONE_TEST 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy1.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy2.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy3.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy4.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.copy5.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/file1.txt 2025/05/30 02:37:31 INFO : - Path1 Queue copy to Path2 - TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/subdir/file20.txt 2025/05/30 02:37:31 INFO : - Path1 Do queued copies to - Path2 2025/05/30 02:37:52 INFO : file1.copy4.txt: Updated modification time in destination 2025/05/30 02:37:52 INFO : RCLONE_TEST: Updated modification time in destination 2025/05/30 02:37:57 INFO : file1.copy2.txt: Updated modification time in destination 2025/05/30 02:37:57 INFO : file1.copy3.txt: Updated modification time in destination 2025/05/30 02:37:57 INFO : file1.copy5.txt: Updated modification time in destination 2025/05/30 02:37:57 INFO : file1.txt: Updated modification time in destination 2025/05/30 02:38:02 INFO : file1.copy1.txt: Updated modification time in destination 2025/05/30 02:38:02 INFO : subdir/file20.txt: Updated modification time in destination 2025/05/30 02:38:02 INFO : Updating listings 2025/05/30 02:38:02 INFO : Validating listings for Path1 "/tmp/023206vu/all_changed/path1/" vs Path2 "TestCompressSwift:rclone-test-kifatap7faqa/023206vu/all_changed/path2/" 2025/05/30 02:38:02 INFO : Bisync successful panic: test timed out after 1h0m0s running tests: TestBisyncLocalRemote (5m58s) TestBisyncLocalRemote/all_changed (5m57s) goroutine 17592 [running]: testing.(*M).startAlarm.func1() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2373 +0x385 created by time.goFunc /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/time/sleep.go:215 +0x2d goroutine 1 [chan receive, 6 minutes]: testing.(*T).Run(0xc0000fe820, {0x269908a?, 0x0?}, 0x27d3b58) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1751 +0x3ab testing.runTests.func1(0xc0000fe820) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2168 +0x37 testing.tRunner(0xc0000fe820, 0xc0007d9c18) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 testing.runTests(0xc000c08588, {0x3e07a20, 0x3, 0x3}, {0x3e45c60?, 0x1?, 0x3e45c60?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2166 +0x43d testing.(*M).Run(0xc0006f3720) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:2034 +0x64a github.com/rclone/rclone/fstest.TestMain(0xc0006f3720) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5 github.com/rclone/rclone/cmd/bisync_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:229 main.main() _testmain.go:53 +0xa9 goroutine 10895 [chan receive (nil chan), 28 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 10135 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 37 [syscall, 60 minutes]: os/signal.signal_recv() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/os/signal/signal.go:151 +0x1f goroutine 38 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 16133 [chan receive, 6 minutes]: testing.(*T).Run(0xc000402000, {0xc000e2e705?, 0x2a85e98?}, 0xc000b27800) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1751 +0x3ab github.com/rclone/rclone/cmd/bisync_test.testBisync(0xc000402000, {0x0, 0x0}, {0xc000e9e210, 0x2a}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:351 +0x925 github.com/rclone/rclone/cmd/bisync_test.TestBisyncLocalRemote(0xc000402000) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:253 +0x165 testing.tRunner(0xc000402000, 0x27d3b58) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 created by testing.(*T).Run in goroutine 1 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1743 +0x390 goroutine 17471 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f729a8, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0019dec80?, 0xc0009df300?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0019dec80, {0xc0009df300, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0019dec80, {0xc0009df300?, 0xc0006dbdd0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000a7a1a0, {0xc0009df300?, 0x3e093a0?, 0xc000a5d9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000c08f00, {0xc0009df300?, 0x18?, 0x7fc20859cb78?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09cc8, {0xc0009df300?, 0x0?, 0xc000c09cc8?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0006dbeb8, {0x2a99f60, 0xc000c09cc8}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0006dbc08, {0x7fc208453c98, 0xc000c08f00}, 0xc000a5da40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0006dbc08, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0006dbc08, {0xc000aa4000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc001c13200, {0xc000aa4000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc0008d9ce0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc0008d9ce0, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc001c13200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17430 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 85 [chan receive, 60 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x29 created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 45 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 12557 [chan receive (nil chan), 19 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 11501 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 84 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 45 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 17473 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f72270, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc002530a80?, 0xc0009ee000?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc002530a80, {0xc0009ee000, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc002530a80, {0xc0009ee000?, 0xc0004248d0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00075c160, {0xc0009ee000?, 0x3e093a0?, 0xc000aad9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc002756960, {0xc0009ee000?, 0x18?, 0x7fc208562498?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09c38, {0xc0009ee000?, 0x0?, 0xc000c09c38?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004249b8, {0x2a99f60, 0xc000c09c38}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000424708, {0x7fc208453c98, 0xc002756960}, 0xc000aada40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000424708, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000424708, {0xc000aae000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc001c13320, {0xc000aae000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc0008d9f20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc0008d9f20, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc001c13320) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17433 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 1066 [chan receive (nil chan), 57 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 86 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17499 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc000cb8a20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17466 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 1402 [chan receive (nil chan), 53 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 86 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 8665 [chan receive (nil chan), 33 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 16183 [select]: github.com/ncw/swift/v2.(*Connection).doTimeoutRequest(0xc0008fe700, 0xc000890f60, 0xc000370dc0) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:424 +0x115 github.com/ncw/swift/v2.(*Connection).Call(0xc0008fe700, {0x2abed20, 0x3e693c0}, {0xc001180730, 0x4f}, {{0xc002689220, 0x18}, {0xc002689239, 0x2e}, {0x2665dbc, ...}, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:776 +0x8b6 github.com/ncw/swift/v2.(*Connection).storage(0xc0008fe700, {0x2abed20, 0x3e693c0}, {{0xc002689220, 0x18}, {0xc002689239, 0x2e}, {0x2665dbc, 0x4}, 0x0, ...}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:846 +0x151 github.com/ncw/swift/v2.(*Connection).objectBase(0xc00006ef28?, {0x2abed20?, 0x3e693c0?}, {0xc002689220?, 0xc00006ef90?}, {0xc002689239, 0x2e}) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:2142 +0xf8 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1.(*Connection).Object.1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:2131 +0x85 github.com/ncw/swift/v2.withLORetry(0x0, 0xc00006f2c8) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/largeobjects.go:303 +0xa2 github.com/ncw/swift/v2.(*Connection).Object(...) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:2130 github.com/rclone/rclone/backend/swift.(*Object).readMetaData.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1334 +0x156 github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x60?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc000890ea0, 0xc000890e40, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:197 +0x7c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc000890ea0, 0xc000890e40) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x92 github.com/rclone/rclone/backend/swift.(*Object).readMetaData(0xc000890de0, {0x2abed20, 0x3e693c0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1333 +0x172 github.com/rclone/rclone/backend/swift.(*Fs).newObjectWithInfo(0xc001c2c700, {0x2abed20, 0x3e693c0}, {0xc000b6ba5b, 0x13}, 0xc0009fc128) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:649 +0x114 github.com/rclone/rclone/backend/swift.(*Fs).list.func1({0xc000b6ba5b?, 0xc0019c82f0?}, 0xc000b6ba40?, 0x2e?) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:759 +0x116 github.com/rclone/rclone/backend/swift.(*Fs).listContainerRoot.func1({0x2abed20, 0x3e693c0}, 0xc0007ca240) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:737 +0x56b github.com/ncw/swift/v2.(*Connection).ObjectsWalk(0xc0008fe700, {0x2abed20, 0x3e693c0}, {0x2a85bf0?, 0x28?}, 0xc0007ca1e0, 0xc00006f758) /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:1192 +0xf0 github.com/rclone/rclone/backend/swift.(*Fs).listContainerRoot(0xc001c2c700, {0x2abed20, 0x3e693c0}, {0xc001a0c140, 0x18}, {0xc001a0c159, 0x1a}, {0xc0009a87d9?, 0x1a?}, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:704 +0x2f4 github.com/rclone/rclone/backend/swift.(*Fs).list(0xa4e4e6?, {0x2abed20?, 0x3e693c0?}, {0xc001a0c140?, 0x0?}, {0xc001a0c159?, 0x2abee00?}, {0xc0009a87d9?, 0x2?}, 0x0, ...) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:751 +0xc5 github.com/rclone/rclone/backend/swift.(*Fs).Purge(0xc001c2c700, {0x2abed20, 0x3e693c0}, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1020 +0x19e github.com/rclone/rclone/backend/compress.(*Fs).Purge(0x9e1efc?, {0x2abed20, 0x3e693c0}, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/compress/compress.go:850 +0x51 github.com/rclone/rclone/fs/operations.Purge({0x2abed20, 0x3e693c0}, {0x2ad41d0, 0xc000c7cc00}, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:1151 +0xfe github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).cleanupCase.func2() /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:606 +0x32 github.com/rclone/rclone/cmd/bisync/bilib.CaptureOutput(0xc00006fb18) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bilib/output.go:22 +0x182 github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).cleanupCase(0xc000c168c0, {0x2abed20, 0x3e693c0}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:605 +0x7a github.com/rclone/rclone/cmd/bisync_test.(*bisyncTest).runTestCase(0xc000c168c0, {0x2abed20, 0x3e693c0}, 0x605?, {0xc000e2e705?, 0x5599c0?}) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:536 +0x19ac github.com/rclone/rclone/cmd/bisync_test.testBisync.func4(0xc0000ff040) /home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/bisync_test.go:358 +0x234 testing.tRunner(0xc0000ff040, 0xc000b27800) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1690 +0xf4 created by testing.(*T).Run in goroutine 16133 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/testing/testing.go:1743 +0x390 goroutine 14238 [chan receive (nil chan), 13 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 12784 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 16287 [select]: github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0xc000c17500) /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:343 +0x13d created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 16183 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:408 +0xb6 goroutine 17606 [select]: net/http.(*persistConn).roundTrip(0xc00070ed80, 0xc000685220) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2755 +0x7b0 net/http.(*Transport).roundTrip(0xc0006fa900, 0xc000370dc0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:644 +0xb91 net/http.(*Transport).RoundTrip(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/roundtrip.go:30 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0xc00030ddc0, 0xc000370dc0) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:349 +0x345 net/http.send(0xc000370dc0, {0x2a98a00, 0xc00030ddc0}, {0x1?, 0xffffffffffffffff?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:259 +0x5e4 net/http.(*Client).send(0xc001ab98f0, 0xc000370dc0, {0xc000a8a000?, 0xc00065c000?, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:180 +0x98 net/http.(*Client).do(0xc001ab98f0, 0xc000370dc0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:725 +0x8bc net/http.(*Client).Do(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/client.go:590 github.com/ncw/swift/v2.(*Connection).doTimeoutRequest.func1() /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:420 +0x2b created by github.com/ncw/swift/v2.(*Connection).doTimeoutRequest in goroutine 16183 /home/rclone/go/pkg/mod/github.com/ncw/swift/v2@v2.0.3/swift.go:419 +0xb8 goroutine 17332 [chan receive (nil chan), 1 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 16183 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17438 [IO wait]: internal/poll.runtime_pollWait(0x7fc209f72fd8, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0019ded00?, 0xc001c39300?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0019ded00, {0xc001c39300, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0019ded00, {0xc001c39300?, 0xc0005b16d0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000a7a210, {0xc001c39300?, 0x3e093a0?, 0xc00183a9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000c090b0, {0xc001c39300?, 0x18?, 0x7fc208563898?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc0009b8108, {0xc001c39300?, 0x0?, 0xc0009b8108?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0005b17b8, {0x2a99f60, 0xc0009b8108}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0005b1508, {0x7fc208453c98, 0xc000c090b0}, 0xc00183aa40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0005b1508, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0005b1508, {0xc000b80000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc0006f18c0, {0xc000b80000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc0027a2d80) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc0027a2d80, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc0006f18c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17407 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 17518 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc0006f1c20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17454 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 1356 [chan receive (nil chan), 53 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 86 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17266 [chan receive (nil chan), 3 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 16183 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17581 [semacquire]: sync.runtime_Semacquire(0x0?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/sema.go:71 +0x25 sync.(*WaitGroup).Wait(0x2abed07?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/sync/waitgroup.go:118 +0x48 github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir({0x2abed20, 0x3e693c0}, 0xc00065c0e0, {0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:615 +0x1cf github.com/rclone/rclone/fs/operations.DeleteFiles(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:628 github.com/rclone/rclone/backend/swift.(*Fs).Purge.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1018 +0x2c created by github.com/rclone/rclone/backend/swift.(*Fs).Purge in goroutine 16183 /home/rclone/go/src/github.com/rclone/rclone/backend/swift/swift.go:1017 +0x125 goroutine 9483 [chan receive (nil chan), 31 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17476 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc001c130e0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17427 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 17439 [select]: net/http.(*persistConn).writeLoop(0xc0006f18c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17407 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 4938 [chan receive (nil chan), 43 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3735 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17589 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 8396 [chan receive (nil chan), 35 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 15612 [chan receive (nil chan), 8 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 14758 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 12620 [chan receive (nil chan), 19 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 11501 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17472 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc001c13200) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17430 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 6843 [chan receive (nil chan), 37 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 5307 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17493 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc001c13440) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17436 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 6244 [chan receive (nil chan), 39 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 5307 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17164 [IO wait]: internal/poll.runtime_pollWait(0x7fc209f72cc0, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001184480?, 0xc0009dc000?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001184480, {0xc0009dc000, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001184480, {0xc0009dc000?, 0xc000c4ba50?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00075c188, {0xc0009dc000?, 0x3e093a0?, 0xc001a929d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc002756de0, {0xc0009dc000?, 0x18?, 0x7fc208349b58?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c08180, {0xc0009dc000?, 0x0?, 0xc000c08180?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c4bb38, {0x2a99f60, 0xc000c08180}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c4b888, {0x7fc208453c98, 0xc002756de0}, 0xc001a92a40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c4b888, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c4b888, {0xc000b51000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc001c12240, {0xc000b51000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc001ba2d20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc001ba2d20, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc001c12240) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17118 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 9610 [chan receive (nil chan), 31 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 8795 [chan receive (nil chan), 33 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 6502 [chan receive (nil chan), 39 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 5307 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 10962 [chan receive (nil chan), 26 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 10135 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 11062 [chan receive (nil chan), 26 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 10135 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17498 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f72378, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc0019dee80?, 0xc0009e0c80?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc0019dee80, {0xc0009e0c80, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc0019dee80, {0xc0009e0c80?, 0xc002033408?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0005ae380, {0xc0009e0c80?, 0x2?, 0xc000763ad0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000b832c0, {0xc0009e0c80?, 0x18?, 0x3?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000b83830, {0xc0009e0c80?, 0x0?, 0xc000b83830?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c4a9b8, {0x2a99f60, 0xc000b83830}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c4a708, {0x7fc208453c98, 0xc000b832c0}, 0xc00009aa40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c4a708, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c4a708, {0xc000c1e000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc000cb8a20, {0xc000c1e000?, 0x40a8a5?, 0x68?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000890900) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc000890900, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc000cb8a20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17466 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 3342 [chan receive (nil chan), 48 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 2439 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 6181 [chan receive (nil chan), 40 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 5307 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17587 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 17506 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc001c13320) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17433 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 17440 [IO wait]: internal/poll.runtime_pollWait(0x7fc2083b6e10, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001184900?, 0xc0009ef980?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001184900, {0xc0009ef980, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001184900, {0xc0009ef980?, 0xc000c4a1d0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0005ae320, {0xc0009ef980?, 0x3e093a0?, 0xc0000999d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000b82de0, {0xc0009ef980?, 0x18?, 0x44a880?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc002756d50, {0xc0009ef980?, 0x0?, 0xc002756d50?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c4a2b8, {0x2a99f60, 0xc002756d50}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c4a008, {0x7fc208453c98, 0xc000b82de0}, 0xc000099a40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c4a008, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c4a008, {0xc000b8e000, 0x1000, 0xc000437308?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc00070ed80, {0xc000b8e000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc0027a2f60) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc0027a2f60, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc00070ed80) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17417 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 17502 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 9779 [chan receive (nil chan), 29 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 7029 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 6616 [chan receive (nil chan), 38 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 5307 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17490 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc2083b6d08, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001184a00?, 0xc001c37980?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001184a00, {0xc001c37980, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001184a00, {0xc001c37980?, 0xc0005b0fd0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc000a7a1d0, {0xc001c37980?, 0x3e093a0?, 0xc0018399d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000c08fa8, {0xc001c37980?, 0x18?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09bf0, {0xc001c37980?, 0x0?, 0xc000c09bf0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0005b10b8, {0x2a99f60, 0xc000c09bf0}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc0005b0e08, {0x7fc208453c98, 0xc000c08fa8}, 0xc001839a40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc0005b0e08, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc0005b0e08, {0xc000b48000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc00070ec60, {0xc000b48000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000b43500) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc000b43500, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc00070ec60) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17414 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 17491 [select, 1 minutes]: net/http.(*persistConn).writeLoop(0xc00070ec60) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17414 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 4960 [chan receive (nil chan), 43 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 3735 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17475 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f72588, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc000c7c780?, 0xc001c36000?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc000c7c780, {0xc001c36000, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc000c7c780, {0xc001c36000?, 0xc000c4a550?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0005ae328, {0xc001c36000?, 0x3e093a0?, 0xc000aaa9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000b82e70, {0xc001c36000?, 0x18?, 0x7fc208544f08?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09c80, {0xc001c36000?, 0x0?, 0xc000c09c80?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c4a638, {0x2a99f60, 0xc000c09c80}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c4a388, {0x7fc208453c98, 0xc000b82e70}, 0xc000aaaa40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c4a388, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c4a388, {0xc000a2e000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc001c130e0, {0xc000a2e000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000bd7860) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc000bd7860, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc001c130e0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17427 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 15743 [chan receive (nil chan), 8 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 14758 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 11246 [chan receive (nil chan), 24 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 10135 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17165 [select]: net/http.(*persistConn).writeLoop(0xc001c12240) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17118 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 17492 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f72bb8, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001184980?, 0xc001c3c600?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001184980, {0xc001c3c600, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001184980, {0xc001c3c600?, 0xc0004256d0?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc00075c168, {0xc001c3c600?, 0x3e093a0?, 0xc000aac9d0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc0027569a8, {0xc001c3c600?, 0x18?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09d10, {0xc001c3c600?, 0x0?, 0xc000c09d10?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc0004257b8, {0x2a99f60, 0xc000c09d10}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000425508, {0x7fc208453c98, 0xc0027569a8}, 0xc000aaca40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000425508, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000425508, {0xc000c1a000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc001c13440, {0xc000c1a000?, 0x823425?, 0x21d2940?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc000b43740) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc000b43740, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc001c13440) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17436 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 16182 [chan receive, 6 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 16133 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7 goroutine 17588 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 17012 [chan receive (nil chan), 3 minutes]: github.com/rclone/rclone/fs/march.(*March).Run.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:234 +0x3c created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 16183 /home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:232 +0x378 goroutine 17517 [IO wait, 1 minutes]: internal/poll.runtime_pollWait(0x7fc209f72ed0, 0x72) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0xc001184b80?, 0xc000b9c000?, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0xc001184b80, {0xc000b9c000, 0x1980, 0x1980}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/internal/poll/fd_unix.go:165 +0x27a net.(*netFD).Read(0xc001184b80, {0xc000b9c000?, 0xc00194ade9?, 0x2?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/fd_posix.go:55 +0x25 net.(*conn).Read(0xc0005ae3b8, {0xc000b9c000?, 0x2?, 0xc000763ad0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/net.go:189 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0xc000b836c8, {0xc000b9c000?, 0x18?, 0x3?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x2c crypto/tls.(*atLeastReader).Read(0xc000c09aa0, {0xc000b9c000?, 0x0?, 0xc000c09aa0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:809 +0x3b bytes.(*Buffer).ReadFrom(0xc000c4ad38, {0x2a99f60, 0xc000c09aa0}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bytes/buffer.go:211 +0x98 crypto/tls.(*Conn).readFromUntil(0xc000c4aa88, {0x7fc208453c98, 0xc000b836c8}, 0xc000a5ba40?) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:831 +0xde crypto/tls.(*Conn).readRecordOrCCS(0xc000c4aa88, 0x0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:629 +0x3cf crypto/tls.(*Conn).readRecord(...) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:591 crypto/tls.(*Conn).Read(0xc000c4aa88, {0xc000ab4000, 0x1000, 0x0?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/crypto/tls/conn.go:1385 +0x150 net/http.(*persistConn).Read(0xc0006f1c20, {0xc000ab4000?, 0x40a8a5?, 0x68?}) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2052 +0x4a bufio.(*Reader).fill(0xc001a549c0) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:110 +0x103 bufio.(*Reader).Peek(0xc001a549c0, 0x1) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/bufio/bufio.go:148 +0x53 net/http.(*persistConn).readLoop(0xc0006f1c20) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2205 +0x185 created by net/http.(*Transport).dialConn in goroutine 17454 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1874 +0x154f goroutine 17441 [select]: net/http.(*persistConn).writeLoop(0xc00070ed80) /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:2519 +0xe7 created by net/http.(*Transport).dialConn in goroutine 17417 /home/rclone/go/pkg/mod/golang.org/toolchain@v0.0.1-go1.23.0.linux-amd64/src/net/http/transport.go:1875 +0x15a5 goroutine 17505 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 17586 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 17503 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 goroutine 17504 [chan receive]: github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:599 +0xc5 created by github.com/rclone/rclone/fs/operations.DeleteFilesWithBackupDir in goroutine 17581 /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations.go:597 +0xe5 "./bisync.test -test.v -test.timeout 1h0m0s -remote TestCompressSwift: -verbose" - Finished ERROR in 1h0m0.716731609s (try 1/5): exit status 2: Failed [TestBisyncRemoteLocal/createemptysrcdirs TestBisyncRemoteLocal/dry_run TestBisyncRemoteLocal/equal TestBisyncRemoteLocal/ext_paths TestBisyncRemoteLocal/extended_filenames TestBisyncRemoteLocal/filters TestBisyncRemoteLocal/filtersfile_checks TestBisyncRemoteLocal/ignorelistingchecksum TestBisyncRemoteLocal/max_delete_path1 TestBisyncRemoteLocal/max_delete_path2_force TestBisyncRemoteLocal/nomodtime TestBisyncRemoteLocal/normalization TestBisyncRemoteLocal/rclone_args TestBisyncRemoteLocal/resolve TestBisyncRemoteLocal/resync TestBisyncRemoteLocal/resync_modes TestBisyncRemoteLocal/rmdirs TestBisyncRemoteLocal/volatile]