"./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestBisyncLocalRemote$/^(resolve|resync|resync_modes|rmdirs|volatile)$'" - Starting (try 2/5) 2024/05/06 05:03:48 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-luwadiq7cebuneh8lufamog8" 2024/05/06 05:03:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/05/06 05:03:48 DEBUG : Creating backend with remote "TestBox:rclone-test-luwadiq7cebuneh8lufamog8" 2024/05/06 05:03:48 DEBUG : box root 'rclone-test-luwadiq7cebuneh8lufamog8': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:03:48 DEBUG : Reset feature "ListR" 2024/05/06 05:03:48 DEBUG : Creating backend with remote "/tmp/rclone2697936085" === RUN TestBisyncLocalRemote 2024/05/06 05:03:48 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6" 2024/05/06 05:03:48 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6" 2024/05/06 05:03:49 DEBUG : box root 'rclone-test-diyuqip7woleroz6vozinor6': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:03:49 DEBUG : Reset feature "ListR" 2024/05/06 05:03:49 remote: TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6 === RUN TestBisyncLocalRemote/resolve 2024/05/06 05:03:49 DEBUG : Creating backend with remote "/tmp/050349pe" 2024/05/06 05:03:49 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/05/06 05:03:49 DEBUG : Creating backend with remote "/tmp/050349pe/resolve/path1" 2024/05/06 05:03:49 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe" 2024/05/06 05:03:49 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe" 2024/05/06 05:03:50 DEBUG : box root 'rclone-test-diyuqip7woleroz6vozinor6/050349pe': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:03:50 DEBUG : box root 'rclone-test-diyuqip7woleroz6vozinor6': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:03:50 DEBUG : Reset feature "ListR" 2024/05/06 05:03:50 DEBUG : path2: Making directory 2024/05/06 05:03:51 DEBUG : box root 'rclone-test-diyuqip7woleroz6vozinor6/050349pe': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:03:53 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2" 2024/05/06 05:03:53 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2" 2024/05/06 05:03:55 DEBUG : Reset feature "ListR" 2024/05/06 05:03:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/initial" 2024/05/06 05:03:55 DEBUG : Creating backend with remote "/tmp/050349pe/initdir/test_resolve-ticokek5" 2024/05/06 05:03:55 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : Local file system at /tmp/050349pe/initdir/test_resolve-ticokek5: Waiting for checks to finish 2024/05/06 05:03:55 DEBUG : Local file system at /tmp/050349pe/initdir/test_resolve-ticokek5: Waiting for transfers to finish 2024/05/06 05:03:55 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:03:55 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:03:55 DEBUG : file1.txt.mifupuf5.partial: renamed to: file1.txt 2024/05/06 05:03:55 INFO : file1.txt: Copied (new) 2024/05/06 05:03:55 DEBUG : RCLONE_TEST.dekewaz8.partial: renamed to: RCLONE_TEST 2024/05/06 05:03:55 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:03:55 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:03:55 DEBUG : file2.txt.tupimem5.partial: renamed to: file2.txt 2024/05/06 05:03:55 INFO : file2.txt: Copied (new) 2024/05/06 05:03:55 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resolve/modfiles" 2024/05/06 05:03:55 DEBUG : Creating backend with remote "/tmp/050349pe/datadir/test_resolve-jadoqah8" 2024/05/06 05:03:55 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:55 DEBUG : Local file system at /tmp/050349pe/datadir/test_resolve-jadoqah8: Waiting for checks to finish 2024/05/06 05:03:55 DEBUG : Local file system at /tmp/050349pe/datadir/test_resolve-jadoqah8: Waiting for transfers to finish 2024/05/06 05:03:55 DEBUG : file1R.txt: md5 = 2b4975bb20f7be674e66d78570ba2fb1 OK 2024/05/06 05:03:55 DEBUG : file1R.txt.mogexip7.partial: renamed to: file1R.txt 2024/05/06 05:03:55 INFO : file1R.txt: Copied (new) 2024/05/06 05:03:55 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2024/05/06 05:03:55 DEBUG : file2.txt.defilig2.partial: renamed to: file2.txt 2024/05/06 05:03:55 INFO : file2.txt: Copied (new) 2024/05/06 05:03:55 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2024/05/06 05:03:55 DEBUG : file1L.txt.xiwakiq0.partial: renamed to: file1L.txt 2024/05/06 05:03:55 INFO : file1L.txt: Copied (new) 2024/05/06 05:03:56 ERROR : : error listing: directory not found 2024/05/06 05:03:56 ERROR : : error listing: directory not found 2024/05/06 05:03:56 checking initFs Local file system at /tmp/050349pe/initdir/test_resolve-ticokek5 2024/05/06 05:03:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : Local file system at /tmp/050349pe/resolve/path1: Waiting for checks to finish 2024/05/06 05:03:56 DEBUG : Local file system at /tmp/050349pe/resolve/path1: Waiting for transfers to finish 2024/05/06 05:03:56 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:03:56 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:03:56 DEBUG : file1.txt.carekik8.partial: renamed to: file1.txt 2024/05/06 05:03:56 INFO : file1.txt: Copied (new) 2024/05/06 05:03:56 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:03:56 DEBUG : RCLONE_TEST.defoqem0.partial: renamed to: RCLONE_TEST 2024/05/06 05:03:56 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:03:56 DEBUG : file2.txt.vadaduj0.partial: renamed to: file2.txt 2024/05/06 05:03:56 INFO : file2.txt: Copied (new) 2024/05/06 05:03:56 checking Path1 Local file system at /tmp/050349pe/resolve/path1 2024/05/06 05:03:56 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:03:56 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': Waiting for checks to finish 2024/05/06 05:03:56 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': Waiting for transfers to finish 2024/05/06 05:03:57 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:03:57 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:03:58 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:04:03 INFO : file1.txt.rclone_chunk.001_1y94na: Moved (server-side) to: file1.txt 2024/05/06 05:04:03 INFO : file1.txt: Copied (new) 2024/05/06 05:04:03 INFO : file2.txt.rclone_chunk.001_1y94cx: Moved (server-side) to: file2.txt 2024/05/06 05:04:03 INFO : file2.txt: Copied (new) 2024/05/06 05:04:06 INFO : RCLONE_TEST.rclone_chunk.001_1y95rr: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:04:06 INFO : RCLONE_TEST.rclone_chunk.002_1y95rr: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:04:07 INFO : RCLONE_TEST.rclone_chunk.003_1y95rr: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:04:08 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:04:08 checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2' 2024/05/06 05:04:08 (01) : test resolve 2024/05/06 05:04:08 (02) : test initial bisync 2024/05/06 05:04:08 (03) : bisync resync 2024/05/06 05:04:11 INFO : modtime_write_test.rclone_chunk.001_1y9gi9: Moved (server-side) to: modtime_write_test 2024/05/06 05:04:13 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:04:13 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:04:13 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:13 INFO : Copying Path2 files to Path1 2024/05/06 05:04:13 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:04:13 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:04:13 INFO : Resync updating listings 2024/05/06 05:04:13 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:13 INFO : Bisync successful 2024/05/06 05:04:13 (04) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:04:13 (05) : touch-glob 2001-01-02 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:04:13 (06) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:04:18 INFO : file1.txt: Deleted 2024/05/06 05:04:19 INFO : file1.txt.rclone_chunk.001_1y9lvx: Moved (server-side) to: file1.txt 2024/05/06 05:04:19 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:04:19 (07) : touch-glob 2001-03-04 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:04:19 (08) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:04:19 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:04:19 (09) : test bisync run with --conflict-resolve=newer --conflict-loser=delete --conflict-suffix=potato 2024/05/06 05:04:19 (10) : bisync conflict-resolve=newer conflict-loser=delete conflict-suffix=potato 2024/05/06 05:04:22 INFO : modtime_write_test.rclone_chunk.001_1y9q6c: Moved (server-side) to: modtime_write_test 2024/05/06 05:04:23 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:04:23 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:04:23 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:23 INFO : Building Path1 and Path2 listings 2024/05/06 05:04:24 INFO : Path1 checking for diffs 2024/05/06 05:04:24 INFO : - Path1 File changed: size (larger), time (newer) - file1.txt 2024/05/06 05:04:24 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2024/05/06 05:04:24 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/05/06 05:04:24 INFO : Path2 checking for diffs 2024/05/06 05:04:24 INFO : - Path2 File changed: size (larger), time (newer) - file1.txt 2024/05/06 05:04:24 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2024/05/06 05:04:24 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/05/06 05:04:24 INFO : Applying changes 2024/05/06 05:04:24 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:04:24 INFO : Checking potential conflicts... 2024/05/06 05:04:25 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 differences found 2024/05/06 05:04:25 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 errors while checking 2024/05/06 05:04:25 INFO : Finished checking the potential conflicts. 1 differences found 2024/05/06 05:04:25 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:04:25 INFO : file1.txt: Path1 is newer. Path1: 2001-03-04 00:00:00 +0000 UTC, Path2: 2001-01-02 00:00:00 +0000 UTC, Difference: 1464h0m0s 2024/05/06 05:04:25 INFO : file1.txt: The winner is: Path1 2024/05/06 05:04:25 NOTICE: - Path2 Deleting Path2 copy - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt 2024/05/06 05:04:26 INFO : file1.txt: Deleted 2024/05/06 05:04:26 INFO : - Path1 Queue copy to Path2 - file1.txt 2024/05/06 05:04:26 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:04:30 INFO : file1.txt.rclone_chunk.001_1y9yzm: Moved (server-side) to: file1.txt 2024/05/06 05:04:30 INFO : file1.txt: Copied (new) 2024/05/06 05:04:30 INFO : Updating listings 2024/05/06 05:04:30 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:30 INFO : Bisync successful 2024/05/06 05:04:30 (11) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:04:30 (12) : touch-glob 2001-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:04:30 (13) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:04:35 INFO : file1.txt: Deleted 2024/05/06 05:04:35 INFO : file1.txt.rclone_chunk.001_1ya2go: Moved (server-side) to: file1.txt 2024/05/06 05:04:35 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:04:35 (14) : touch-glob 2001-08-26 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:04:35 (15) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:04:35 INFO : file1L.txt: Updated modification time in destination 2024/05/06 05:04:35 (16) : test bisync run with --conflict-resolve=path2 --conflict-loser=num --conflict-suffix=dinosaur 2024/05/06 05:04:35 (17) : bisync conflict-resolve=path2 conflict-loser=num conflict-suffix=dinosaur 2024/05/06 05:04:39 INFO : modtime_write_test.rclone_chunk.001_1ya7gg: Moved (server-side) to: modtime_write_test 2024/05/06 05:04:40 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:04:40 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:04:40 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:40 INFO : Building Path1 and Path2 listings 2024/05/06 05:04:40 INFO : Path1 checking for diffs 2024/05/06 05:04:40 INFO : - Path1 File changed: time (newer) - file1.txt 2024/05/06 05:04:40 INFO : Path1: 1 changes:  0 new,  1 modified,  0 deleted 2024/05/06 05:04:40 INFO : (Modified:  1 newer,  0 older) 2024/05/06 05:04:40 INFO : Path2 checking for diffs 2024/05/06 05:04:40 INFO : - Path2 File changed: time (newer) - file1.txt 2024/05/06 05:04:40 INFO : Path2: 1 changes:  0 new,  1 modified,  0 deleted 2024/05/06 05:04:40 INFO : (Modified:  1 newer,  0 older) 2024/05/06 05:04:40 INFO : Applying changes 2024/05/06 05:04:40 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:04:40 INFO : Checking potential conflicts... 2024/05/06 05:04:41 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 differences found 2024/05/06 05:04:41 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 errors while checking 2024/05/06 05:04:41 INFO : Finished checking the potential conflicts. 1 differences found 2024/05/06 05:04:41 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:04:41 INFO : file1.txt: The winner is: Path2 2024/05/06 05:04:41 NOTICE: - Path1 Renaming Path1 copy - /tmp/050349pe/resolve/path1/file1.txt.dinosaur1 2024/05/06 05:04:41 INFO : file1.txt: Moved (server-side) to: file1.txt.dinosaur1 2024/05/06 05:04:41 NOTICE: - Path1 Queue copy to Path2 - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt.dinosaur1 2024/05/06 05:04:41 NOTICE: - Path2 Not renaming Path2 copy, as it was determined the winner - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt 2024/05/06 05:04:41 NOTICE: - Path2 Queue copy to Path1 - /tmp/050349pe/resolve/path1/file1.txt 2024/05/06 05:04:41 INFO : - Path2 Do queued copies to - Path1 2024/05/06 05:04:42 INFO : file1.txt: Copied (new) 2024/05/06 05:04:42 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:04:46 INFO : file1.txt.dinosaur1.rclone_chunk.001_1yaewe: Moved (server-side) to: file1.txt.dinosaur1 2024/05/06 05:04:46 INFO : file1.txt.dinosaur1: Copied (new) 2024/05/06 05:04:46 INFO : Updating listings 2024/05/06 05:04:46 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:46 INFO : Bisync successful 2024/05/06 05:04:46 (18) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:04:46 (19) : touch-glob 2002-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:04:46 (20) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:04:51 INFO : file1.txt: Deleted 2024/05/06 05:04:51 INFO : file1.txt.rclone_chunk.001_1yaira: Moved (server-side) to: file1.txt 2024/05/06 05:04:51 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:04:51 (21) : touch-glob 2002-08-26 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:04:51 (22) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:04:51 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:04:51 (23) : test bisync run with --conflict-resolve=larger --conflict-loser=num --conflict-suffix=apple 2024/05/06 05:04:51 (24) : bisync conflict-resolve=larger conflict-loser=num conflict-suffix=apple 2024/05/06 05:04:54 INFO : modtime_write_test.rclone_chunk.001_1yamnd: Moved (server-side) to: modtime_write_test 2024/05/06 05:04:55 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:04:55 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:04:55 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:04:55 INFO : Building Path1 and Path2 listings 2024/05/06 05:04:56 INFO : Path1 checking for diffs 2024/05/06 05:04:56 INFO : - Path1 File is new - file1.txt 2024/05/06 05:04:56 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:04:56 INFO : Path2 checking for diffs 2024/05/06 05:04:56 INFO : - Path2 File is new - file1.txt 2024/05/06 05:04:56 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:04:56 INFO : Applying changes 2024/05/06 05:04:56 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:04:56 INFO : Checking potential conflicts... 2024/05/06 05:04:57 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 differences found 2024/05/06 05:04:57 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 errors while checking 2024/05/06 05:04:57 INFO : Finished checking the potential conflicts. 1 differences found 2024/05/06 05:04:57 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:04:57 INFO : file1.txt: Winner cannot be determined as sizes are equal. Path1: 33, Path2: 33, Difference: 0 2024/05/06 05:04:57 INFO : file1.txt: A winner could not be determined. 2024/05/06 05:04:57 NOTICE: - Path1 Renaming Path1 copy - /tmp/050349pe/resolve/path1/file1.txt.apple1 2024/05/06 05:04:57 INFO : file1.txt: Moved (server-side) to: file1.txt.apple1 2024/05/06 05:04:57 NOTICE: - Path1 Queue copy to Path2 - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt.apple1 2024/05/06 05:04:57 NOTICE: - Path2 Renaming Path2 copy - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt.apple2 2024/05/06 05:04:59 INFO : file1.txt: Moved (server-side) to: file1.txt.apple2 2024/05/06 05:04:59 INFO : file1.txt: Moved (server-side) to: file1.txt.apple2 2024/05/06 05:04:59 NOTICE: - Path2 Queue copy to Path1 - /tmp/050349pe/resolve/path1/file1.txt.apple2 2024/05/06 05:04:59 INFO : - Path2 Do queued copies to - Path1 2024/05/06 05:05:00 INFO : file1.txt.apple2: Copied (new) 2024/05/06 05:05:00 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:05:03 INFO : file1.txt.apple1.rclone_chunk.001_1yavtb: Moved (server-side) to: file1.txt.apple1 2024/05/06 05:05:03 INFO : file1.txt.apple1: Copied (new) 2024/05/06 05:05:03 INFO : Updating listings 2024/05/06 05:05:03 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:03 INFO : Bisync successful 2024/05/06 05:05:03 (25) : test different suffixes 2024/05/06 05:05:03 (26) : touch-glob 2003-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:05:03 (27) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:05:07 INFO : file1.txt.rclone_chunk.001_1yaz4h: Moved (server-side) to: file1.txt 2024/05/06 05:05:07 INFO : file1R.txt: Copied (new) to: file1.txt 2024/05/06 05:05:07 (28) : touch-glob 2003-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:05:07 (29) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:05:07 INFO : file1L.txt: Copied (new) to: file1.txt 2024/05/06 05:05:07 (30) : test bisync run with --conflict-resolve=older --conflict-loser=num --conflict-suffix=cloud,local 2024/05/06 05:05:07 (31) : bisync conflict-resolve=older conflict-loser=num conflict-suffix=cloud,local 2024/05/06 05:05:10 INFO : modtime_write_test.rclone_chunk.001_1yb23q: Moved (server-side) to: modtime_write_test 2024/05/06 05:05:11 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:05:11 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:05:11 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:11 INFO : Building Path1 and Path2 listings 2024/05/06 05:05:11 INFO : Path1 checking for diffs 2024/05/06 05:05:11 INFO : - Path1 File is new - file1.txt 2024/05/06 05:05:11 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:05:11 INFO : Path2 checking for diffs 2024/05/06 05:05:11 INFO : - Path2 File is new - file1.txt 2024/05/06 05:05:11 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:05:11 INFO : Applying changes 2024/05/06 05:05:11 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:05:11 INFO : Checking potential conflicts... 2024/05/06 05:05:13 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 differences found 2024/05/06 05:05:13 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 errors while checking 2024/05/06 05:05:13 INFO : Finished checking the potential conflicts. 1 differences found 2024/05/06 05:05:13 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:05:13 INFO : file1.txt: Winner cannot be determined as times are equal. Path1: 2003-07-23 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 0s 2024/05/06 05:05:13 INFO : file1.txt: A winner could not be determined. 2024/05/06 05:05:13 NOTICE: - Path1 Renaming Path1 copy - /tmp/050349pe/resolve/path1/file1.txt.cloud1 2024/05/06 05:05:13 INFO : file1.txt: Moved (server-side) to: file1.txt.cloud1 2024/05/06 05:05:13 NOTICE: - Path1 Queue copy to Path2 - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt.cloud1 2024/05/06 05:05:13 NOTICE: - Path2 Renaming Path2 copy - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt.local1 2024/05/06 05:05:14 INFO : file1.txt: Moved (server-side) to: file1.txt.local1 2024/05/06 05:05:14 INFO : file1.txt: Moved (server-side) to: file1.txt.local1 2024/05/06 05:05:14 NOTICE: - Path2 Queue copy to Path1 - /tmp/050349pe/resolve/path1/file1.txt.local1 2024/05/06 05:05:14 INFO : - Path2 Do queued copies to - Path1 2024/05/06 05:05:15 INFO : file1.txt.local1: Copied (new) 2024/05/06 05:05:15 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:05:19 INFO : file1.txt.cloud1.rclone_chunk.001_1ybb6i: Moved (server-side) to: file1.txt.cloud1 2024/05/06 05:05:19 INFO : file1.txt.cloud1: Copied (new) 2024/05/06 05:05:19 INFO : Updating listings 2024/05/06 05:05:19 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:19 INFO : Bisync successful 2024/05/06 05:05:19 (32) : test legacy 2024/05/06 05:05:19 (33) : touch-glob 2004-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:05:19 (34) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:05:22 INFO : file1.txt.rclone_chunk.001_1ybfru: Moved (server-side) to: file1.txt 2024/05/06 05:05:22 INFO : file1R.txt: Copied (new) to: file1.txt 2024/05/06 05:05:22 (35) : touch-glob 2004-07-23 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:05:22 (36) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:05:22 INFO : file1L.txt: Copied (new) to: file1.txt 2024/05/06 05:05:22 (37) : test bisync run with --conflict-resolve=none --conflict-loser=pathname --conflict-suffix=.path 2024/05/06 05:05:22 (38) : bisync conflict-resolve=none conflict-loser=pathname conflict-suffix=.path 2024/05/06 05:05:26 INFO : modtime_write_test.rclone_chunk.001_1ybiy2: Moved (server-side) to: modtime_write_test 2024/05/06 05:05:27 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:05:27 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:05:27 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:27 INFO : Building Path1 and Path2 listings 2024/05/06 05:05:27 INFO : Path1 checking for diffs 2024/05/06 05:05:27 INFO : - Path1 File is new - file1.txt 2024/05/06 05:05:27 INFO : Path1: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:05:27 INFO : Path2 checking for diffs 2024/05/06 05:05:27 INFO : - Path2 File is new - file1.txt 2024/05/06 05:05:27 INFO : Path2: 1 changes:  1 new,  0 modified,  0 deleted 2024/05/06 05:05:27 INFO : Applying changes 2024/05/06 05:05:27 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:05:27 INFO : Checking potential conflicts... 2024/05/06 05:05:28 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 differences found 2024/05/06 05:05:28 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 1 errors while checking 2024/05/06 05:05:28 INFO : Finished checking the potential conflicts. 1 differences found 2024/05/06 05:05:28 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:05:28 NOTICE: - Path1 Renaming Path1 copy - /tmp/050349pe/resolve/path1/file1.txt..path1 2024/05/06 05:05:28 INFO : file1.txt: Moved (server-side) to: file1.txt..path1 2024/05/06 05:05:28 NOTICE: - Path1 Queue copy to Path2 - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt..path1 2024/05/06 05:05:28 NOTICE: - Path2 Renaming Path2 copy - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt..path2 2024/05/06 05:05:30 INFO : file1.txt: Moved (server-side) to: file1.txt..path2 2024/05/06 05:05:30 INFO : file1.txt: Moved (server-side) to: file1.txt..path2 2024/05/06 05:05:30 NOTICE: - Path2 Queue copy to Path1 - /tmp/050349pe/resolve/path1/file1.txt..path2 2024/05/06 05:05:30 INFO : - Path2 Do queued copies to - Path1 2024/05/06 05:05:31 INFO : file1.txt..path2: Copied (new) 2024/05/06 05:05:31 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:05:34 INFO : file1.txt..path1.rclone_chunk.001_1ybr5s: Moved (server-side) to: file1.txt..path1 2024/05/06 05:05:34 INFO : file1.txt..path1: Copied (new) 2024/05/06 05:05:34 INFO : Updating listings 2024/05/06 05:05:34 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:34 INFO : Bisync successful 2024/05/06 05:05:34 (39) : test deletes on both sides with default suffix 2024/05/06 05:05:34 (40) : touch-glob 2005-01-02 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1R.txt 2024/05/06 05:05:34 (41) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file1.txt 2024/05/06 05:05:38 INFO : file1.txt.rclone_chunk.001_1ybu55: Moved (server-side) to: file1.txt 2024/05/06 05:05:38 INFO : file1R.txt: Copied (new) to: file1.txt 2024/05/06 05:05:38 (42) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1R.txt /tmp/050349pe/resolve/path1/ file2.txt 2024/05/06 05:05:38 INFO : file1R.txt: Copied (replaced existing) to: file2.txt 2024/05/06 05:05:38 (43) : touch-glob 2006-03-04 /tmp/050349pe/datadir/test_resolve-jadoqah8/ file1L.txt 2024/05/06 05:05:38 (44) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt /tmp/050349pe/resolve/path1/ file1.txt 2024/05/06 05:05:38 INFO : file1L.txt: Copied (new) to: file1.txt 2024/05/06 05:05:38 (45) : copy-as /tmp/050349pe/datadir/test_resolve-jadoqah8/file1L.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/ file2.txt 2024/05/06 05:05:43 INFO : file2.txt: Deleted 2024/05/06 05:05:43 INFO : file2.txt.rclone_chunk.001_1ybyo5: Moved (server-side) to: file2.txt 2024/05/06 05:05:43 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2024/05/06 05:05:43 (46) : test bisync run with --conflict-resolve=newer --conflict-loser=delete 2024/05/06 05:05:43 (47) : bisync conflict-resolve=newer conflict-loser=delete 2024/05/06 05:05:46 INFO : modtime_write_test.rclone_chunk.001_1yc23k: Moved (server-side) to: modtime_write_test 2024/05/06 05:05:48 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:05:48 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:05:48 INFO : Synching Path1 "/tmp/050349pe/resolve/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:48 INFO : Building Path1 and Path2 listings 2024/05/06 05:05:48 INFO : Path1 checking for diffs 2024/05/06 05:05:48 INFO : - Path1 File changed: size (larger), time (newer) - file2.txt 2024/05/06 05:05:48 INFO : - Path1 File is new - file1.txt 2024/05/06 05:05:48 INFO : Path1: 2 changes:  1 new,  1 modified,  0 deleted 2024/05/06 05:05:48 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/05/06 05:05:48 INFO : Path2 checking for diffs 2024/05/06 05:05:48 INFO : - Path2 File changed: size (larger), time (newer) - file2.txt 2024/05/06 05:05:48 INFO : - Path2 File is new - file1.txt 2024/05/06 05:05:48 INFO : Path2: 2 changes:  1 new,  1 modified,  0 deleted 2024/05/06 05:05:48 INFO : (Modified:  1 newer,  0 older,  1 larger,  0 smaller) 2024/05/06 05:05:48 INFO : Applying changes 2024/05/06 05:05:48 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:05:48 INFO : Checking potential conflicts... 2024/05/06 05:05:49 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 2 differences found 2024/05/06 05:05:49 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2': 2 errors while checking 2024/05/06 05:05:49 INFO : Finished checking the potential conflicts. 2 differences found 2024/05/06 05:05:49 NOTICE: - WARNING New or changed in both paths - file1.txt 2024/05/06 05:05:49 INFO : file1.txt: Path1 is newer. Path1: 2006-03-04 00:00:00 +0000 UTC, Path2: 2005-01-02 00:00:00 +0000 UTC, Difference: 10224h0m0s 2024/05/06 05:05:49 INFO : file1.txt: The winner is: Path1 2024/05/06 05:05:49 NOTICE: - Path2 Deleting Path2 copy - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/file1.txt 2024/05/06 05:05:51 INFO : file1.txt: Deleted 2024/05/06 05:05:51 INFO : - Path1 Queue copy to Path2 - file1.txt 2024/05/06 05:05:51 NOTICE: - WARNING New or changed in both paths - file2.txt 2024/05/06 05:05:51 INFO : file2.txt: Path2 is newer. Path1: 2005-01-02 00:00:00 +0000 UTC, Path2: 2006-03-04 00:00:00 +0000 UTC, Difference: 10224h0m0s 2024/05/06 05:05:51 INFO : file2.txt: The winner is: Path2 2024/05/06 05:05:51 NOTICE: - Path1 Deleting Path1 copy - /tmp/050349pe/resolve/path1/file2.txt 2024/05/06 05:05:51 INFO : file2.txt: Deleted 2024/05/06 05:05:51 INFO : - Path2 Queue copy to Path1 - file2.txt 2024/05/06 05:05:51 INFO : - Path2 Do queued copies to - Path1 2024/05/06 05:05:52 INFO : file2.txt: Copied (new) 2024/05/06 05:05:52 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:05:56 INFO : file1.txt.rclone_chunk.001_1yccai: Moved (server-side) to: file1.txt 2024/05/06 05:05:56 INFO : file1.txt: Copied (new) 2024/05/06 05:05:56 INFO : Updating listings 2024/05/06 05:05:56 INFO : Can't compare hashes, so using check --download for safety. (Use --size-only or --ignore-checksum to disable) 2024/05/06 05:05:57 INFO : Validating listings for Path1 "/tmp/050349pe/resolve/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resolve/path2/" 2024/05/06 05:05:57 INFO : Bisync successful bisync_test.go:555: TEST resolve PASSED === RUN TestBisyncLocalRemote/resync 2024/05/06 05:05:59 DEBUG : Creating backend with remote "/tmp/050349pe/resync/path1" 2024/05/06 05:05:59 DEBUG : path2: Making directory 2024/05/06 05:05:59 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2" 2024/05/06 05:05:59 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2" 2024/05/06 05:06:02 DEBUG : Reset feature "ListR" 2024/05/06 05:06:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial" 2024/05/06 05:06:02 DEBUG : Creating backend with remote "/tmp/050349pe/initdir/test_resync-dolubiv2" 2024/05/06 05:06:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/initdir/test_resync-dolubiv2: Waiting for checks to finish 2024/05/06 05:06:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/initdir/test_resync-dolubiv2: Waiting for transfers to finish 2024/05/06 05:06:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:06:02 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file1.txt.darahev8.partial: renamed to: file1.txt 2024/05/06 05:06:02 INFO : file1.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : RCLONE_TEST.nulabiz8.partial: renamed to: RCLONE_TEST 2024/05/06 05:06:02 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:06:02 DEBUG : file2.txt.wapalub1.partial: renamed to: file2.txt 2024/05/06 05:06:02 INFO : file2.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file4.txt.jadivef5.partial: renamed to: file4.txt 2024/05/06 05:06:02 INFO : file4.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file5.txt.weqiral6.partial: renamed to: file5.txt 2024/05/06 05:06:02 INFO : file5.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file6.txt.jumicev6.partial: renamed to: file6.txt 2024/05/06 05:06:02 INFO : file6.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file3.txt.perebam5.partial: renamed to: file3.txt 2024/05/06 05:06:02 INFO : file3.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file7.txt.puhejac9.partial: renamed to: file7.txt 2024/05/06 05:06:02 INFO : file7.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles" 2024/05/06 05:06:02 DEBUG : Creating backend with remote "/tmp/050349pe/datadir/test_resync-vevimom7" 2024/05/06 05:06:02 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/datadir/test_resync-vevimom7: Waiting for checks to finish 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/datadir/test_resync-vevimom7: Waiting for transfers to finish 2024/05/06 05:06:02 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:06:02 DEBUG : fileA.txt.muvuqiy8.partial: renamed to: fileA.txt 2024/05/06 05:06:02 INFO : fileA.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:06:02 DEBUG : fileB.txt.nequvav4.partial: renamed to: fileB.txt 2024/05/06 05:06:02 INFO : fileB.txt: Copied (new) 2024/05/06 05:06:02 ERROR : : error listing: directory not found 2024/05/06 05:06:02 ERROR : : error listing: directory not found 2024/05/06 05:06:02 checking initFs Local file system at /tmp/050349pe/initdir/test_resync-dolubiv2 2024/05/06 05:06:02 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/resync/path1: Waiting for checks to finish 2024/05/06 05:06:02 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file1.txt.tacumet1.partial: renamed to: file1.txt 2024/05/06 05:06:02 INFO : file1.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : Local file system at /tmp/050349pe/resync/path1: Waiting for transfers to finish 2024/05/06 05:06:02 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file2.txt.fekusar5.partial: renamed to: file2.txt 2024/05/06 05:06:02 INFO : file2.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file4.txt.hicihij0.partial: renamed to: file4.txt 2024/05/06 05:06:02 INFO : file4.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:06:02 DEBUG : file3.txt.sokejuf7.partial: renamed to: file3.txt 2024/05/06 05:06:02 INFO : file3.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file5.txt.kotetur7.partial: renamed to: file5.txt 2024/05/06 05:06:02 INFO : file5.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : RCLONE_TEST.piyicut0.partial: renamed to: RCLONE_TEST 2024/05/06 05:06:02 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:06:02 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:06:02 DEBUG : file6.txt.gorepox1.partial: renamed to: file6.txt 2024/05/06 05:06:02 INFO : file6.txt: Copied (new) 2024/05/06 05:06:02 DEBUG : file7.txt.bocetiv1.partial: renamed to: file7.txt 2024/05/06 05:06:02 INFO : file7.txt: Copied (new) 2024/05/06 05:06:02 checking Path1 Local file system at /tmp/050349pe/resync/path1 2024/05/06 05:06:03 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:06:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2': Waiting for checks to finish 2024/05/06 05:06:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2': Waiting for transfers to finish 2024/05/06 05:06:03 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:04 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:04 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:04 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:10 INFO : file1.txt.rclone_chunk.001_1ycm4i: Moved (server-side) to: file1.txt 2024/05/06 05:06:10 INFO : file1.txt: Copied (new) 2024/05/06 05:06:10 INFO : file4.txt.rclone_chunk.001_1ycndp: Moved (server-side) to: file4.txt 2024/05/06 05:06:10 INFO : file4.txt: Copied (new) 2024/05/06 05:06:10 INFO : file2.txt.rclone_chunk.001_1ycnhn: Moved (server-side) to: file2.txt 2024/05/06 05:06:10 INFO : file2.txt: Copied (new) 2024/05/06 05:06:10 INFO : file3.txt.rclone_chunk.001_1ycnd9: Moved (server-side) to: file3.txt 2024/05/06 05:06:10 INFO : file3.txt: Copied (new) 2024/05/06 05:06:10 DEBUG : file5.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:11 DEBUG : file6.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:11 DEBUG : file7.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:11 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:06:13 INFO : file5.txt.rclone_chunk.001_1yctiu: Moved (server-side) to: file5.txt 2024/05/06 05:06:13 INFO : file5.txt: Copied (new) 2024/05/06 05:06:13 INFO : file6.txt.rclone_chunk.001_1ycue4: Moved (server-side) to: file6.txt 2024/05/06 05:06:13 INFO : file6.txt: Copied (new) 2024/05/06 05:06:13 INFO : file7.txt.rclone_chunk.001_1ycu6s: Moved (server-side) to: file7.txt 2024/05/06 05:06:13 INFO : file7.txt: Copied (new) 2024/05/06 05:06:16 INFO : RCLONE_TEST.rclone_chunk.001_1ycums: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:06:16 INFO : RCLONE_TEST.rclone_chunk.002_1ycums: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:06:17 INFO : RCLONE_TEST.rclone_chunk.003_1ycums: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:06:18 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:06:18 checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2' 2024/05/06 05:06:19 (01) : test resync 2024/05/06 05:06:19 (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2024/05/06 05:06:19 (03) : purge-children /tmp/050349pe/resync/path1/ 2024/05/06 05:06:19 (04) : bisync resync 2024/05/06 05:06:22 INFO : modtime_write_test.rclone_chunk.001_1yd2fq: Moved (server-side) to: modtime_write_test 2024/05/06 05:06:23 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:06:23 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:06:23 INFO : Synching Path1 "/tmp/050349pe/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:06:23 INFO : Copying Path2 files to Path1 2024/05/06 05:06:23 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:06:24 INFO : file2.txt: Copied (new) 2024/05/06 05:06:24 INFO : file1.txt: Copied (new) 2024/05/06 05:06:24 INFO : file3.txt: Copied (new) 2024/05/06 05:06:25 INFO : file5.txt: Copied (new) 2024/05/06 05:06:25 INFO : file4.txt: Copied (new) 2024/05/06 05:06:25 INFO : file6.txt: Copied (new) 2024/05/06 05:06:25 INFO : file7.txt: Copied (new) 2024/05/06 05:06:26 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:06:26 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:06:26 INFO : Resync updating listings 2024/05/06 05:06:26 INFO : Validating listings for Path1 "/tmp/050349pe/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:06:26 INFO : Bisync successful 2024/05/06 05:06:26 (05) : move-listings empty-path1 2024/05/06 05:06:26 (06) : test 2. resync with empty path2, resulting in synching all content to path2. 2024/05/06 05:06:26 (07) : purge-children TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ 2024/05/06 05:06:37 (08) : bisync resync 2024/05/06 05:06:40 INFO : modtime_write_test.rclone_chunk.001_1ydkty: Moved (server-side) to: modtime_write_test 2024/05/06 05:06:41 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:06:41 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:06:41 INFO : Synching Path1 "/tmp/050349pe/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:06:41 INFO : Copying Path2 files to Path1 2024/05/06 05:06:41 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:06:41 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:06:44 INFO : file1.txt.rclone_chunk.001_1ydpvc: Moved (server-side) to: file1.txt 2024/05/06 05:06:44 INFO : file1.txt: Copied (new) 2024/05/06 05:06:45 INFO : file3.txt.rclone_chunk.001_1ydpqk: Moved (server-side) to: file3.txt 2024/05/06 05:06:45 INFO : file3.txt: Copied (new) 2024/05/06 05:06:45 INFO : file2.txt.rclone_chunk.001_1ydp08: Moved (server-side) to: file2.txt 2024/05/06 05:06:45 INFO : file2.txt: Copied (new) 2024/05/06 05:06:45 INFO : file4.txt.rclone_chunk.001_1ydpnp: Moved (server-side) to: file4.txt 2024/05/06 05:06:45 INFO : file4.txt: Copied (new) 2024/05/06 05:06:48 INFO : file5.txt.rclone_chunk.001_1ydsax: Moved (server-side) to: file5.txt 2024/05/06 05:06:48 INFO : file5.txt: Copied (new) 2024/05/06 05:06:48 INFO : file6.txt.rclone_chunk.001_1ydsut: Moved (server-side) to: file6.txt 2024/05/06 05:06:48 INFO : file6.txt: Copied (new) 2024/05/06 05:06:48 INFO : file7.txt.rclone_chunk.001_1ydshu: Moved (server-side) to: file7.txt 2024/05/06 05:06:48 INFO : file7.txt: Copied (new) 2024/05/06 05:06:51 INFO : RCLONE_TEST.rclone_chunk.001_1ydskp: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:06:51 INFO : RCLONE_TEST.rclone_chunk.002_1ydskp: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:06:52 INFO : RCLONE_TEST.rclone_chunk.003_1ydskp: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:06:53 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:06:53 INFO : Resync updating listings 2024/05/06 05:06:53 INFO : Validating listings for Path1 "/tmp/050349pe/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:06:53 INFO : Bisync successful 2024/05/06 05:06:53 (09) : move-listings empty-path2 2024/05/06 05:06:53 (10) : test 3. exercise all of the various file difference scenarios during a resync. 2024/05/06 05:06:53 (11) : touch-glob 2002-02-02 /tmp/050349pe/datadir/test_resync-vevimom7/ fileA.txt 2024/05/06 05:06:53 (12) : touch-glob 1999-09-09 /tmp/050349pe/datadir/test_resync-vevimom7/ fileB.txt 2024/05/06 05:06:53 (13) : test = file - path1 - path2 - expected action - who wins 2024/05/06 05:06:53 (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2024/05/06 05:06:53 (15) : delete-file TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/file1.txt 2024/05/06 05:06:55 INFO : file1.txt: Deleted 2024/05/06 05:06:55 (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2024/05/06 05:06:55 (17) : delete-file /tmp/050349pe/resync/path1/file2.txt 2024/05/06 05:06:55 INFO : file2.txt: Deleted 2024/05/06 05:06:55 (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2024/05/06 05:06:55 (19) : copy-as /tmp/050349pe/datadir/test_resync-vevimom7/fileA.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ file3.txt 2024/05/06 05:07:00 INFO : file3.txt: Deleted 2024/05/06 05:07:00 INFO : file3.txt.rclone_chunk.001_1ye393: Moved (server-side) to: file3.txt 2024/05/06 05:07:00 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2024/05/06 05:07:00 (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2024/05/06 05:07:00 (21) : delete-file /tmp/050349pe/resync/path1/file4.txt 2024/05/06 05:07:00 INFO : file4.txt: Deleted 2024/05/06 05:07:00 (22) : copy-as /tmp/050349pe/datadir/test_resync-vevimom7/fileA.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ file4.txt 2024/05/06 05:07:05 INFO : file4.txt: Deleted 2024/05/06 05:07:06 INFO : file4.txt.rclone_chunk.001_1ye8qp: Moved (server-side) to: file4.txt 2024/05/06 05:07:06 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2024/05/06 05:07:06 (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2024/05/06 05:07:06 (24) : copy-as /tmp/050349pe/datadir/test_resync-vevimom7/fileB.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ file5.txt 2024/05/06 05:07:11 INFO : file5.txt: Deleted 2024/05/06 05:07:11 INFO : file5.txt.rclone_chunk.001_1yeerh: Moved (server-side) to: file5.txt 2024/05/06 05:07:11 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2024/05/06 05:07:11 (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2024/05/06 05:07:11 (26) : copy-as /tmp/050349pe/datadir/test_resync-vevimom7/fileB.txt /tmp/050349pe/resync/path1/ file6.txt 2024/05/06 05:07:11 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2024/05/06 05:07:11 (27) : copy-as /tmp/050349pe/datadir/test_resync-vevimom7/fileA.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ file6.txt 2024/05/06 05:07:16 INFO : file6.txt: Deleted 2024/05/06 05:07:17 INFO : file6.txt.rclone_chunk.001_1yejq0: Moved (server-side) to: file6.txt 2024/05/06 05:07:17 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2024/05/06 05:07:17 (28) : test - file7.txt - exists - exists (same) - none - same 2024/05/06 05:07:17 (29) : test run bisync with resync 2024/05/06 05:07:17 (30) : bisync resync 2024/05/06 05:07:20 INFO : modtime_write_test.rclone_chunk.001_1yeoiu: Moved (server-side) to: modtime_write_test 2024/05/06 05:07:21 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:07:21 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:07:21 INFO : Synching Path1 "/tmp/050349pe/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:07:21 INFO : Copying Path2 files to Path1 2024/05/06 05:07:21 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:07:22 INFO : file2.txt: Copied (new) 2024/05/06 05:07:22 INFO : file4.txt: Copied (new) 2024/05/06 05:07:22 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:07:25 INFO : file1.txt.rclone_chunk.001_1yeuoi: Moved (server-side) to: file1.txt 2024/05/06 05:07:25 INFO : file1.txt: Copied (new) 2024/05/06 05:07:26 INFO : file6.txt: Deleted 2024/05/06 05:07:26 INFO : file5.txt: Deleted 2024/05/06 05:07:26 INFO : file3.txt: Deleted 2024/05/06 05:07:27 INFO : file6.txt.rclone_chunk.001_1yetbt: Moved (server-side) to: file6.txt 2024/05/06 05:07:27 INFO : file6.txt: Copied (replaced existing) 2024/05/06 05:07:27 INFO : file3.txt.rclone_chunk.001_1yet66: Moved (server-side) to: file3.txt 2024/05/06 05:07:27 INFO : file3.txt: Copied (replaced existing) 2024/05/06 05:07:27 INFO : file5.txt.rclone_chunk.001_1yet8a: Moved (server-side) to: file5.txt 2024/05/06 05:07:27 INFO : file5.txt: Copied (replaced existing) 2024/05/06 05:07:27 INFO : Resync updating listings 2024/05/06 05:07:27 INFO : Validating listings for Path1 "/tmp/050349pe/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:07:27 INFO : Bisync successful 2024/05/06 05:07:27 (31) : copy-listings mixed-diffs 2024/05/06 05:07:27 (32) : test run normal bisync 2024/05/06 05:07:27 (33) : bisync 2024/05/06 05:07:30 INFO : modtime_write_test.rclone_chunk.001_1yeyed: Moved (server-side) to: modtime_write_test 2024/05/06 05:07:31 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:07:31 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:07:31 INFO : Synching Path1 "/tmp/050349pe/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:07:31 INFO : Building Path1 and Path2 listings 2024/05/06 05:07:32 INFO : Path1 checking for diffs 2024/05/06 05:07:32 INFO : Path2 checking for diffs 2024/05/06 05:07:32 INFO : No changes found 2024/05/06 05:07:32 INFO : Updating listings 2024/05/06 05:07:32 INFO : Validating listings for Path1 "/tmp/050349pe/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:07:32 INFO : Bisync successful 2024/05/06 05:07:32 (34) : test 4. confirm critical error on normal sync of empty path. 2024/05/06 05:07:32 (35) : purge-children TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/ 2024/05/06 05:07:41 (36) : bisync 2024/05/06 05:07:45 INFO : modtime_write_test.rclone_chunk.001_1yfd7v: Moved (server-side) to: modtime_write_test 2024/05/06 05:07:46 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:07:46 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:07:46 INFO : Synching Path1 "/tmp/050349pe/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync/path2/" 2024/05/06 05:07:46 INFO : Building Path1 and Path2 listings 2024/05/06 05:07:46 INFO : Path1 checking for diffs 2024/05/06 05:07:46 INFO : Path2 checking for diffs 2024/05/06 05:07:46 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/050349pe/workdir/tmp_050349pe_resync_path1..TestChunkerChunk50bBox_rclone-test-diyuqip7woleroz6vozinor6_050349pe_resync_path2.path2.lst-new 2024/05/06 05:07:46 ERROR : Bisync critical error: empty current Path2 listing: /tmp/050349pe/workdir/tmp_050349pe_resync_path1..TestChunkerChunk50bBox_rclone-test-diyuqip7woleroz6vozinor6_050349pe_resync_path2.path2.lst-new 2024/05/06 05:07:46 ERROR : Bisync aborted. Must run --resync to recover. 2024/05/06 05:07:46 Bisync error: bisync aborted bisync_test.go:555: TEST resync PASSED === RUN TestBisyncLocalRemote/resync_modes 2024/05/06 05:07:47 DEBUG : Creating backend with remote "/tmp/050349pe/resync_modes/path1" 2024/05/06 05:07:47 DEBUG : path2: Making directory 2024/05/06 05:07:47 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2" 2024/05/06 05:07:47 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2" 2024/05/06 05:07:49 DEBUG : Reset feature "ListR" 2024/05/06 05:07:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/initial" 2024/05/06 05:07:49 DEBUG : Creating backend with remote "/tmp/050349pe/initdir/test_resync_modes-fukehos2" 2024/05/06 05:07:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : Local file system at /tmp/050349pe/initdir/test_resync_modes-fukehos2: Waiting for checks to finish 2024/05/06 05:07:49 DEBUG : Local file system at /tmp/050349pe/initdir/test_resync_modes-fukehos2: Waiting for transfers to finish 2024/05/06 05:07:49 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:07:49 DEBUG : file2.txt.gotuvag0.partial: renamed to: file2.txt 2024/05/06 05:07:49 INFO : file2.txt: Copied (new) 2024/05/06 05:07:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:07:49 DEBUG : file1.txt.kozutaq7.partial: renamed to: file1.txt 2024/05/06 05:07:49 INFO : file1.txt: Copied (new) 2024/05/06 05:07:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:07:49 DEBUG : RCLONE_TEST.zejikux0.partial: renamed to: RCLONE_TEST 2024/05/06 05:07:49 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:07:49 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync_modes/modfiles" 2024/05/06 05:07:49 DEBUG : Creating backend with remote "/tmp/050349pe/datadir/test_resync_modes-guvegan9" 2024/05/06 05:07:49 DEBUG : file1L.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : file1R.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:49 DEBUG : Local file system at /tmp/050349pe/datadir/test_resync_modes-guvegan9: Waiting for checks to finish 2024/05/06 05:07:49 DEBUG : Local file system at /tmp/050349pe/datadir/test_resync_modes-guvegan9: Waiting for transfers to finish 2024/05/06 05:07:49 DEBUG : file1L.txt: md5 = ea683c03f780b76a62405456b08ae6fd OK 2024/05/06 05:07:49 DEBUG : file2.txt: md5 = 9fe822ddd1cb81d83aae00fa48239bd3 OK 2024/05/06 05:07:49 DEBUG : file1L.txt.wetimij6.partial: renamed to: file1L.txt 2024/05/06 05:07:49 INFO : file1L.txt: Copied (new) 2024/05/06 05:07:49 DEBUG : file2.txt.farivev7.partial: renamed to: file2.txt 2024/05/06 05:07:49 INFO : file2.txt: Copied (new) 2024/05/06 05:07:49 DEBUG : file1R.txt: md5 = 705bb3c0ef02925739e834ca98c099c3 OK 2024/05/06 05:07:49 DEBUG : file1R.txt.bufikum6.partial: renamed to: file1R.txt 2024/05/06 05:07:49 INFO : file1R.txt: Copied (new) 2024/05/06 05:07:49 ERROR : : error listing: directory not found 2024/05/06 05:07:50 ERROR : : error listing: directory not found 2024/05/06 05:07:50 checking initFs Local file system at /tmp/050349pe/initdir/test_resync_modes-fukehos2 2024/05/06 05:07:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : Local file system at /tmp/050349pe/resync_modes/path1: Waiting for checks to finish 2024/05/06 05:07:50 DEBUG : Local file system at /tmp/050349pe/resync_modes/path1: Waiting for transfers to finish 2024/05/06 05:07:50 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:07:50 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:07:50 DEBUG : file2.txt.pirahey7.partial: renamed to: file2.txt 2024/05/06 05:07:50 DEBUG : RCLONE_TEST.recofen2.partial: renamed to: RCLONE_TEST 2024/05/06 05:07:50 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:07:50 INFO : file2.txt: Copied (new) 2024/05/06 05:07:50 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:07:50 DEBUG : file1.txt.tocixor1.partial: renamed to: file1.txt 2024/05/06 05:07:50 INFO : file1.txt: Copied (new) 2024/05/06 05:07:50 checking Path1 Local file system at /tmp/050349pe/resync_modes/path1 2024/05/06 05:07:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:07:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2': Waiting for checks to finish 2024/05/06 05:07:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2': Waiting for transfers to finish 2024/05/06 05:07:50 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:07:51 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:07:51 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:07:57 INFO : file2.txt.rclone_chunk.001_1yfm0p: Moved (server-side) to: file2.txt 2024/05/06 05:07:57 INFO : file2.txt: Copied (new) 2024/05/06 05:07:57 INFO : file1.txt.rclone_chunk.001_1yfm72: Moved (server-side) to: file1.txt 2024/05/06 05:07:57 INFO : file1.txt: Copied (new) 2024/05/06 05:07:59 INFO : RCLONE_TEST.rclone_chunk.001_1yflq3: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:08:00 INFO : RCLONE_TEST.rclone_chunk.002_1yflq3: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:08:00 INFO : RCLONE_TEST.rclone_chunk.003_1yflq3: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:08:01 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:08:01 checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2' 2024/05/06 05:08:02 (01) : test resync-mode 2024/05/06 05:08:02 (02) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:08:02 (03) : touch-glob 2001-01-02 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:08:02 (04) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:08:07 INFO : file1.txt: Deleted 2024/05/06 05:08:07 INFO : file1.txt.rclone_chunk.001_1yfyno: Moved (server-side) to: file1.txt 2024/05/06 05:08:07 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:07 (05) : touch-glob 2001-03-04 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:08:07 (06) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:08:07 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:07 (07) : test bisync run with --resync-mode=newer 2024/05/06 05:08:07 (08) : bisync resync resync-mode=newer 2024/05/06 05:08:10 INFO : modtime_write_test.rclone_chunk.001_1yg29b: Moved (server-side) to: modtime_write_test 2024/05/06 05:08:11 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:08:11 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:08:11 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:11 INFO : Copying Path2 files to Path1 2024/05/06 05:08:11 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:08:12 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:08:16 INFO : file1.txt: Deleted 2024/05/06 05:08:17 INFO : file1.txt.rclone_chunk.001_1yg7q1: Moved (server-side) to: file1.txt 2024/05/06 05:08:17 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:08:17 INFO : Resync updating listings 2024/05/06 05:08:17 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:17 INFO : Bisync successful 2024/05/06 05:08:17 (09) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:08:17 (10) : touch-glob 2001-07-23 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:08:17 (11) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:08:22 INFO : file1.txt: Deleted 2024/05/06 05:08:22 INFO : file1.txt.rclone_chunk.001_1ygcg0: Moved (server-side) to: file1.txt 2024/05/06 05:08:22 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:22 (12) : touch-glob 2001-08-26 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:08:22 (13) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:08:22 INFO : file1L.txt: Updated modification time in destination 2024/05/06 05:08:22 (14) : test bisync run with --resync-mode=path2 2024/05/06 05:08:22 (15) : bisync resync resync-mode=path2 2024/05/06 05:08:25 INFO : modtime_write_test.rclone_chunk.001_1yghcj: Moved (server-side) to: modtime_write_test 2024/05/06 05:08:26 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:08:26 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:08:26 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:26 INFO : Copying Path2 files to Path1 2024/05/06 05:08:26 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:08:27 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:08:27 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:08:28 INFO : Resync updating listings 2024/05/06 05:08:28 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:28 INFO : Bisync successful 2024/05/06 05:08:28 (16) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:08:28 (17) : touch-glob 2002-07-23 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:08:28 (18) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:08:33 INFO : file1.txt: Deleted 2024/05/06 05:08:33 INFO : file1.txt.rclone_chunk.001_1ygodv: Moved (server-side) to: file1.txt 2024/05/06 05:08:33 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:33 (19) : touch-glob 2002-08-26 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:08:33 (20) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:08:33 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:33 (21) : test bisync run with --resync-mode=larger 2024/05/06 05:08:33 (22) : bisync resync resync-mode=larger 2024/05/06 05:08:36 INFO : modtime_write_test.rclone_chunk.001_1ygsoh: Moved (server-side) to: modtime_write_test 2024/05/06 05:08:37 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:08:37 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:08:37 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:37 INFO : Copying Path2 files to Path1 2024/05/06 05:08:37 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:08:38 INFO : file1.txt: Path2 is larger. Path1: 33, Path2: 42, Difference: 9 2024/05/06 05:08:38 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:08:38 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:08:39 INFO : Resync updating listings 2024/05/06 05:08:39 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:39 INFO : Bisync successful 2024/05/06 05:08:39 (23) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:08:39 (24) : touch-glob 2003-07-23 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:08:39 (25) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:08:44 INFO : file1.txt: Deleted 2024/05/06 05:08:45 INFO : file1.txt.rclone_chunk.001_1ygzuy: Moved (server-side) to: file1.txt 2024/05/06 05:08:45 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:45 (26) : touch-glob 2003-09-04 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:08:45 (27) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:08:45 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:45 (28) : test bisync run with --resync-mode=older 2024/05/06 05:08:45 (29) : bisync resync resync-mode=older 2024/05/06 05:08:48 INFO : modtime_write_test.rclone_chunk.001_1yh4qx: Moved (server-side) to: modtime_write_test 2024/05/06 05:08:49 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:08:49 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:08:49 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:49 INFO : Copying Path2 files to Path1 2024/05/06 05:08:49 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:08:49 INFO : file1.txt: Path2 is older. Path1: 2003-09-04 00:00:00 +0000 UTC, Path2: 2003-07-23 00:00:00 +0000 UTC, Difference: 1032h0m0s 2024/05/06 05:08:50 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:08:50 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:08:51 INFO : Resync updating listings 2024/05/06 05:08:51 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:08:51 INFO : Bisync successful 2024/05/06 05:08:51 (30) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:08:51 (31) : touch-glob 2004-07-23 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:08:51 (32) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:08:56 INFO : file1.txt: Deleted 2024/05/06 05:08:57 INFO : file1.txt.rclone_chunk.001_1yhbiy: Moved (server-side) to: file1.txt 2024/05/06 05:08:57 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:57 (33) : touch-glob 2004-07-23 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:08:57 (34) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:08:57 INFO : file1L.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:08:57 (35) : test bisync run with --resync-mode=smaller 2024/05/06 05:08:57 (36) : bisync resync resync-mode=smaller 2024/05/06 05:09:00 INFO : modtime_write_test.rclone_chunk.001_1yhgk1: Moved (server-side) to: modtime_write_test 2024/05/06 05:09:01 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:09:01 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:09:01 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:01 INFO : Copying Path2 files to Path1 2024/05/06 05:09:01 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:09:01 INFO : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9 2024/05/06 05:09:02 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:09:02 INFO : file1.txt: Path1 is smaller. Path1: 33, Path2: 42, Difference: 9 2024/05/06 05:09:06 INFO : file1.txt: Deleted 2024/05/06 05:09:07 INFO : file1.txt.rclone_chunk.001_1yhlzc: Moved (server-side) to: file1.txt 2024/05/06 05:09:07 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:09:07 INFO : Resync updating listings 2024/05/06 05:09:07 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:07 INFO : Bisync successful 2024/05/06 05:09:07 (37) : test changed on both paths and NOT identical - file1 (file1R, file1L) 2024/05/06 05:09:07 (38) : touch-glob 2005-01-02 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:09:07 (39) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:09:12 INFO : file1.txt: Deleted 2024/05/06 05:09:12 INFO : file1.txt.rclone_chunk.001_1yhr72: Moved (server-side) to: file1.txt 2024/05/06 05:09:12 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:09:12 (40) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt /tmp/050349pe/resync_modes/path1/ file2.txt 2024/05/06 05:09:12 INFO : file1R.txt: Copied (replaced existing) to: file2.txt 2024/05/06 05:09:12 (41) : touch-glob 2006-03-04 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:09:12 (42) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:09:12 INFO : file1L.txt: Updated modification time in destination 2024/05/06 05:09:12 (43) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file2.txt 2024/05/06 05:09:17 INFO : file2.txt: Deleted 2024/05/06 05:09:18 INFO : file2.txt.rclone_chunk.001_1yhw36: Moved (server-side) to: file2.txt 2024/05/06 05:09:18 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2024/05/06 05:09:18 (44) : test bisync run with --resync-mode=path1 2024/05/06 05:09:18 (45) : bisync resync resync-mode=path1 2024/05/06 05:09:21 INFO : modtime_write_test.rclone_chunk.001_1yi1dw: Moved (server-side) to: modtime_write_test 2024/05/06 05:09:22 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:09:22 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:09:22 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:22 INFO : Copying Path2 files to Path1 2024/05/06 05:09:22 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:09:23 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:09:27 INFO : file2.txt: Deleted 2024/05/06 05:09:27 INFO : file1.txt: Deleted 2024/05/06 05:09:27 INFO : file2.txt.rclone_chunk.001_1yi6l2: Moved (server-side) to: file2.txt 2024/05/06 05:09:27 INFO : file2.txt: Copied (replaced existing) 2024/05/06 05:09:27 INFO : file1.txt.rclone_chunk.001_1yi69l: Moved (server-side) to: file1.txt 2024/05/06 05:09:27 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:09:27 INFO : Resync updating listings 2024/05/06 05:09:27 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:27 INFO : Bisync successful 2024/05/06 05:09:27 (46) : test no winner 2024/05/06 05:09:27 (47) : touch-glob 2022-02-22 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1R.txt 2024/05/06 05:09:27 (48) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file1.txt 2024/05/06 05:09:32 INFO : file1.txt: Deleted 2024/05/06 05:09:33 INFO : file1.txt.rclone_chunk.001_1yibk0: Moved (server-side) to: file1.txt 2024/05/06 05:09:33 INFO : file1R.txt: Copied (replaced existing) to: file1.txt 2024/05/06 05:09:33 (49) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1R.txt /tmp/050349pe/resync_modes/path1/ file2.txt 2024/05/06 05:09:33 INFO : file1R.txt: Updated modification time in destination 2024/05/06 05:09:33 (50) : touch-glob 2022-02-22 /tmp/050349pe/datadir/test_resync_modes-guvegan9/ file1L.txt 2024/05/06 05:09:33 (51) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt /tmp/050349pe/resync_modes/path1/ file1.txt 2024/05/06 05:09:33 INFO : file1L.txt: Updated modification time in destination 2024/05/06 05:09:33 (52) : copy-as /tmp/050349pe/datadir/test_resync_modes-guvegan9/file1L.txt TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/ file2.txt 2024/05/06 05:09:38 INFO : file2.txt: Deleted 2024/05/06 05:09:38 INFO : file2.txt.rclone_chunk.001_1yih63: Moved (server-side) to: file2.txt 2024/05/06 05:09:38 INFO : file1L.txt: Copied (replaced existing) to: file2.txt 2024/05/06 05:09:38 (53) : test bisync run with --resync-mode=newer 2024/05/06 05:09:38 (54) : bisync resync resync-mode=newer 2024/05/06 05:09:41 INFO : modtime_write_test.rclone_chunk.001_1yilnp: Moved (server-side) to: modtime_write_test 2024/05/06 05:09:42 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:09:42 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:09:42 INFO : Synching Path1 "/tmp/050349pe/resync_modes/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:42 INFO : Copying Path2 files to Path1 2024/05/06 05:09:42 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:09:44 INFO : file1.txt: Copied (replaced existing) 2024/05/06 05:09:44 INFO : file2.txt: Copied (replaced existing) 2024/05/06 05:09:44 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:09:44 INFO : Resync updating listings 2024/05/06 05:09:44 INFO : Validating listings for Path1 "/tmp/050349pe/resync_modes/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/resync_modes/path2/" 2024/05/06 05:09:44 INFO : Bisync successful bisync_test.go:555: TEST resync_modes PASSED === RUN TestBisyncLocalRemote/rmdirs 2024/05/06 05:09:46 DEBUG : Creating backend with remote "/tmp/050349pe/rmdirs/path1" 2024/05/06 05:09:46 DEBUG : path2: Making directory 2024/05/06 05:09:46 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2" 2024/05/06 05:09:46 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2" 2024/05/06 05:09:48 DEBUG : Reset feature "ListR" 2024/05/06 05:09:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/initial" 2024/05/06 05:09:48 DEBUG : Creating backend with remote "/tmp/050349pe/initdir/test_rmdirs-genuyak8" 2024/05/06 05:09:48 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : subdir: Making directory with metadata 2024/05/06 05:09:48 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/05/06 05:09:48 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/05/06 05:09:48 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.copy1.txt.qofabaw7.partial: renamed to: file1.copy1.txt 2024/05/06 05:09:48 INFO : file1.copy1.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.copy2.txt.huwilat0.partial: renamed to: file1.copy2.txt 2024/05/06 05:09:48 INFO : file1.copy2.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:09:48 DEBUG : RCLONE_TEST.tegosuc5.partial: renamed to: RCLONE_TEST 2024/05/06 05:09:48 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:09:48 DEBUG : Local file system at /tmp/050349pe/initdir/test_rmdirs-genuyak8: Waiting for checks to finish 2024/05/06 05:09:48 DEBUG : Local file system at /tmp/050349pe/initdir/test_rmdirs-genuyak8: Waiting for transfers to finish 2024/05/06 05:09:48 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.copy3.txt.ceduwaw5.partial: renamed to: file1.copy3.txt 2024/05/06 05:09:48 INFO : file1.copy3.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.copy5.txt.jutoquh2.partial: renamed to: file1.copy5.txt 2024/05/06 05:09:48 INFO : file1.copy5.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.copy4.txt.taquhes8.partial: renamed to: file1.copy4.txt 2024/05/06 05:09:48 INFO : file1.copy4.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : file1.txt.xipufos4.partial: renamed to: file1.txt 2024/05/06 05:09:48 INFO : file1.txt: Copied (new) 2024/05/06 05:09:48 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:48 DEBUG : subdir/file20.txt.marusel7.partial: renamed to: subdir/file20.txt 2024/05/06 05:09:48 INFO : subdir/file20.txt: Copied (new) 2024/05/06 05:09:48 INFO : subdir: Updated directory metadata 2024/05/06 05:09:48 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_rmdirs/modfiles" 2024/05/06 05:09:48 DEBUG : Creating backend with remote "/tmp/050349pe/datadir/test_rmdirs-cujisud3" 2024/05/06 05:09:48 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:48 DEBUG : Local file system at /tmp/050349pe/datadir/test_rmdirs-cujisud3: Waiting for checks to finish 2024/05/06 05:09:48 DEBUG : Local file system at /tmp/050349pe/datadir/test_rmdirs-cujisud3: Waiting for transfers to finish 2024/05/06 05:09:48 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:09:48 DEBUG : file1.txt.nukovex7.partial: renamed to: file1.txt 2024/05/06 05:09:48 INFO : file1.txt: Copied (new) 2024/05/06 05:09:48 ERROR : : error listing: directory not found 2024/05/06 05:09:49 ERROR : : error listing: directory not found 2024/05/06 05:09:49 checking initFs Local file system at /tmp/050349pe/initdir/test_rmdirs-genuyak8 2024/05/06 05:09:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : subdir: Making directory with metadata 2024/05/06 05:09:49 INFO : subdir: Made directory with metadata (mtime=2021-11-02T05:00:07Z) 2024/05/06 05:09:49 DEBUG : Added delayed dir = "subdir", newDst=subdir 2024/05/06 05:09:49 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.copy1.txt.xugivij2.partial: renamed to: file1.copy1.txt 2024/05/06 05:09:49 INFO : file1.copy1.txt: Copied (new) 2024/05/06 05:09:49 DEBUG : file1.copy3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.copy3.txt.radotud9.partial: renamed to: file1.copy3.txt 2024/05/06 05:09:49 INFO : file1.copy3.txt: Copied (new) 2024/05/06 05:09:49 DEBUG : file1.copy4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.copy2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.copy4.txt.sefazet4.partial: renamed to: file1.copy4.txt 2024/05/06 05:09:49 INFO : file1.copy4.txt: Copied (new) 2024/05/06 05:09:49 DEBUG : file1.copy2.txt.taluqop6.partial: renamed to: file1.copy2.txt 2024/05/06 05:09:49 INFO : file1.copy2.txt: Copied (new) 2024/05/06 05:09:49 DEBUG : Local file system at /tmp/050349pe/rmdirs/path1: Waiting for checks to finish 2024/05/06 05:09:49 DEBUG : file1.copy5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : file1.copy5.txt.wowajov5.partial: renamed to: file1.copy5.txt 2024/05/06 05:09:49 DEBUG : file1.txt.siqoxac1.partial: renamed to: file1.txt 2024/05/06 05:09:49 INFO : file1.copy5.txt: Copied (new) 2024/05/06 05:09:49 INFO : file1.txt: Copied (new) 2024/05/06 05:09:49 DEBUG : Local file system at /tmp/050349pe/rmdirs/path1: Waiting for transfers to finish 2024/05/06 05:09:49 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:09:49 DEBUG : RCLONE_TEST.risewiq4.partial: renamed to: RCLONE_TEST 2024/05/06 05:09:49 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:09:49 DEBUG : subdir/file20.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:09:49 DEBUG : subdir/file20.txt.pajajil4.partial: renamed to: subdir/file20.txt 2024/05/06 05:09:49 INFO : subdir/file20.txt: Copied (new) 2024/05/06 05:09:49 INFO : subdir: Updated directory metadata 2024/05/06 05:09:49 checking Path1 Local file system at /tmp/050349pe/rmdirs/path1 2024/05/06 05:09:49 DEBUG : file1.copy1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy2.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy3.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy4.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.copy5.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:09:49 DEBUG : subdir: Making directory 2024/05/06 05:09:52 DEBUG : subdir/file20.txt: Need to transfer - File not found at Destination 2024/05/06 05:09:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2': Waiting for checks to finish 2024/05/06 05:09:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2': Waiting for transfers to finish 2024/05/06 05:09:52 DEBUG : file1.copy1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:52 DEBUG : file1.copy2.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:52 DEBUG : file1.copy3.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:52 DEBUG : file1.copy4.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:55 INFO : file1.copy2.txt.rclone_chunk.001_1yizg3: Moved (server-side) to: file1.copy2.txt 2024/05/06 05:09:55 INFO : file1.copy2.txt: Copied (new) 2024/05/06 05:09:55 INFO : file1.copy1.txt.rclone_chunk.001_1yiz8c: Moved (server-side) to: file1.copy1.txt 2024/05/06 05:09:55 INFO : file1.copy1.txt: Copied (new) 2024/05/06 05:09:55 INFO : file1.copy3.txt.rclone_chunk.001_1yizt5: Moved (server-side) to: file1.copy3.txt 2024/05/06 05:09:55 INFO : file1.copy3.txt: Copied (new) 2024/05/06 05:09:55 DEBUG : file1.copy5.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:55 INFO : file1.copy4.txt.rclone_chunk.001_1yizgg: Moved (server-side) to: file1.copy4.txt 2024/05/06 05:09:55 INFO : file1.copy4.txt: Copied (new) 2024/05/06 05:09:55 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:55 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:56 DEBUG : subdir/file20.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:09:58 INFO : file1.copy5.txt.rclone_chunk.001_1yj22r: Moved (server-side) to: file1.copy5.txt 2024/05/06 05:09:58 INFO : file1.copy5.txt: Copied (new) 2024/05/06 05:09:58 INFO : file1.txt.rclone_chunk.001_1yj2xo: Moved (server-side) to: file1.txt 2024/05/06 05:09:58 INFO : file1.txt: Copied (new) 2024/05/06 05:09:58 INFO : subdir/file20.txt.rclone_chunk.001_1yj3rx: Moved (server-side) to: subdir/file20.txt 2024/05/06 05:09:58 INFO : subdir/file20.txt: Copied (new) 2024/05/06 05:10:01 INFO : RCLONE_TEST.rclone_chunk.001_1yj2pi: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:10:01 INFO : RCLONE_TEST.rclone_chunk.002_1yj2pi: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:10:02 INFO : RCLONE_TEST.rclone_chunk.003_1yj2pi: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:10:03 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:10:03 checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2' 2024/05/06 05:10:04 (01) : test rmdirs 2024/05/06 05:10:04 (02) : test initial bisync 2024/05/06 05:10:04 (03) : bisync resync 2024/05/06 05:10:07 INFO : modtime_write_test.rclone_chunk.001_1yjbc7: Moved (server-side) to: modtime_write_test 2024/05/06 05:10:08 INFO : subdir: Set directory modification time (using SetModTime) 2024/05/06 05:10:08 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:10:08 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:10:08 INFO : Synching Path1 "/tmp/050349pe/rmdirs/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:08 INFO : Copying Path2 files to Path1 2024/05/06 05:10:08 INFO : - Path2 Resync is copying files to - Path1 2024/05/06 05:10:09 INFO : - Path1 Resync is copying files to - Path2 2024/05/06 05:10:10 INFO : Resync updating listings 2024/05/06 05:10:10 INFO : Validating listings for Path1 "/tmp/050349pe/rmdirs/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:10 INFO : Bisync successful 2024/05/06 05:10:10 (04) : test 1. delete path1 subdir file 2024/05/06 05:10:10 (05) : delete-file /tmp/050349pe/rmdirs/path1/subdir/file20.txt 2024/05/06 05:10:10 INFO : file20.txt: Deleted 2024/05/06 05:10:10 (06) : test 2. run bisync without remove-empty-dirs 2024/05/06 05:10:10 (07) : bisync 2024/05/06 05:10:13 INFO : modtime_write_test.rclone_chunk.001_1yjhdv: Moved (server-side) to: modtime_write_test 2024/05/06 05:10:14 INFO : subdir: Set directory modification time (using SetModTime) 2024/05/06 05:10:14 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:10:14 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:10:14 INFO : Synching Path1 "/tmp/050349pe/rmdirs/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:14 INFO : Building Path1 and Path2 listings 2024/05/06 05:10:14 INFO : Path1 checking for diffs 2024/05/06 05:10:14 INFO : - Path1 File was deleted - subdir/file20.txt 2024/05/06 05:10:14 INFO : Path1: 1 changes:  0 new,  0 modified,  1 deleted 2024/05/06 05:10:14 INFO : Path2 checking for diffs 2024/05/06 05:10:14 INFO : Applying changes 2024/05/06 05:10:14 INFO : - Path2 Queue delete - TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/subdir/file20.txt 2024/05/06 05:10:14 INFO : - Path1 Do queued copies to - Path2 2024/05/06 05:10:16 INFO : subdir/file20.txt: Deleted 2024/05/06 05:10:16 INFO : Updating listings 2024/05/06 05:10:17 INFO : Validating listings for Path1 "/tmp/050349pe/rmdirs/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:17 INFO : Bisync successful 2024/05/06 05:10:17 (08) : test 3. confirm the subdir still exists on both paths 2024/05/06 05:10:17 (09) : list-dirs /tmp/050349pe/rmdirs/path1/ 2024/05/06 05:10:17 subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2 2024/05/06 05:10:17 (10) : list-dirs TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/ 2024/05/06 05:10:17 subdir/ - filename hash: 86ae37b338459868804e9697025ba4c2 2024/05/06 05:10:17 (11) : test 4. run bisync with remove-empty-dirs 2024/05/06 05:10:17 (12) : bisync remove-empty-dirs 2024/05/06 05:10:21 INFO : modtime_write_test.rclone_chunk.001_1yjpbu: Moved (server-side) to: modtime_write_test 2024/05/06 05:10:22 INFO : subdir: Set directory modification time (using SetModTime) 2024/05/06 05:10:22 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/05/06 05:10:22 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/05/06 05:10:22 INFO : Synching Path1 "/tmp/050349pe/rmdirs/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:22 INFO : Building Path1 and Path2 listings 2024/05/06 05:10:22 INFO : Path1 checking for diffs 2024/05/06 05:10:22 INFO : Path2 checking for diffs 2024/05/06 05:10:22 INFO : No changes found 2024/05/06 05:10:22 INFO : Updating listings 2024/05/06 05:10:22 INFO : Validating listings for Path1 "/tmp/050349pe/rmdirs/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/" 2024/05/06 05:10:22 INFO : Removing empty directories 2024/05/06 05:10:22 INFO : subdir: Removing directory 2024/05/06 05:10:23 INFO : subdir: Removing directory 2024/05/06 05:10:24 INFO : Bisync successful 2024/05/06 05:10:24 (13) : test 5. confirm the subdir has been removed on both paths 2024/05/06 05:10:24 (14) : list-dirs /tmp/050349pe/rmdirs/path1/ 2024/05/06 05:10:24 (15) : list-dirs TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/rmdirs/path2/ bisync_test.go:555: TEST rmdirs PASSED === RUN TestBisyncLocalRemote/volatile 2024/05/06 05:10:27 DEBUG : Creating backend with remote "/tmp/050349pe/volatile/path1" 2024/05/06 05:10:27 DEBUG : path2: Making directory 2024/05/06 05:10:27 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/volatile/path2" 2024/05/06 05:10:27 DEBUG : Creating backend with remote "TestBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/volatile/path2" 2024/05/06 05:10:29 DEBUG : Reset feature "ListR" 2024/05/06 05:10:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/initial" 2024/05/06 05:10:29 DEBUG : Creating backend with remote "/tmp/050349pe/initdir/test_volatile-woqulim4" 2024/05/06 05:10:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file8.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/initdir/test_volatile-woqulim4: Waiting for checks to finish 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/initdir/test_volatile-woqulim4: Waiting for transfers to finish 2024/05/06 05:10:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file1.txt.qahozuh4.partial: renamed to: file1.txt 2024/05/06 05:10:29 INFO : file1.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file3.txt.falucip8.partial: renamed to: file3.txt 2024/05/06 05:10:29 INFO : file3.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file4.txt.lagonih9.partial: renamed to: file4.txt 2024/05/06 05:10:29 INFO : file4.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:10:29 DEBUG : file2.txt.sulonoy0.partial: renamed to: file2.txt 2024/05/06 05:10:29 INFO : file2.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : RCLONE_TEST.zenemez9.partial: renamed to: RCLONE_TEST 2024/05/06 05:10:29 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:10:29 DEBUG : file5.txt.rihejiq3.partial: renamed to: file5.txt 2024/05/06 05:10:29 INFO : file5.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file6.txt.faragay6.partial: renamed to: file6.txt 2024/05/06 05:10:29 INFO : file6.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file8.txt.vicizok1.partial: renamed to: file8.txt 2024/05/06 05:10:29 INFO : file8.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file7.txt.xirekix0.partial: renamed to: file7.txt 2024/05/06 05:10:29 INFO : file7.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_volatile/modfiles" 2024/05/06 05:10:29 DEBUG : Creating backend with remote "/tmp/050349pe/datadir/test_volatile-medeqip2" 2024/05/06 05:10:29 DEBUG : dummy.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file10.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file11.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file5L.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file5R.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/datadir/test_volatile-medeqip2: Waiting for checks to finish 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/datadir/test_volatile-medeqip2: Waiting for transfers to finish 2024/05/06 05:10:29 DEBUG : dummy.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : dummy.txt.kesiviv5.partial: renamed to: dummy.txt 2024/05/06 05:10:29 INFO : dummy.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file1.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:10:29 DEBUG : file1.txt.cazofix2.partial: renamed to: file1.txt 2024/05/06 05:10:29 INFO : file1.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file2.txt: md5 = fb3ecfb2800400fb01b0bfd39903e9fb OK 2024/05/06 05:10:29 DEBUG : file10.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:10:29 DEBUG : file2.txt.gidokay5.partial: renamed to: file2.txt 2024/05/06 05:10:29 INFO : file2.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file11.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:10:29 DEBUG : file10.txt.hisoxaj6.partial: renamed to: file10.txt 2024/05/06 05:10:29 DEBUG : file11.txt.dusiwuc2.partial: renamed to: file11.txt 2024/05/06 05:10:29 INFO : file10.txt: Copied (new) 2024/05/06 05:10:29 INFO : file11.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file5L.txt: md5 = 0860a03592626642f8fd6c8bfb447d2a OK 2024/05/06 05:10:29 DEBUG : file5L.txt.memukep8.partial: renamed to: file5L.txt 2024/05/06 05:10:29 INFO : file5L.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file6.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:10:29 DEBUG : file6.txt.guqugub8.partial: renamed to: file6.txt 2024/05/06 05:10:29 INFO : file6.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file7.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/05/06 05:10:29 DEBUG : file7.txt.xineloc9.partial: renamed to: file7.txt 2024/05/06 05:10:29 INFO : file7.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file5R.txt: md5 = 979a803b15d27df0c31ad7d29006d10b OK 2024/05/06 05:10:29 DEBUG : file5R.txt.qiwipib7.partial: renamed to: file5R.txt 2024/05/06 05:10:29 INFO : file5R.txt: Copied (new) 2024/05/06 05:10:29 ERROR : : error listing: directory not found 2024/05/06 05:10:29 ERROR : : error listing: directory not found 2024/05/06 05:10:29 checking initFs Local file system at /tmp/050349pe/initdir/test_volatile-woqulim4 2024/05/06 05:10:29 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : file8.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/volatile/path1: Waiting for checks to finish 2024/05/06 05:10:29 DEBUG : Local file system at /tmp/050349pe/volatile/path1: Waiting for transfers to finish 2024/05/06 05:10:29 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/05/06 05:10:29 DEBUG : RCLONE_TEST.qasibob9.partial: renamed to: RCLONE_TEST 2024/05/06 05:10:29 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:10:29 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file1.txt.tedikoq8.partial: renamed to: file1.txt 2024/05/06 05:10:29 INFO : file1.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file3.txt.vojedaf8.partial: renamed to: file3.txt 2024/05/06 05:10:29 INFO : file3.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file5.txt.huzodev0.partial: renamed to: file5.txt 2024/05/06 05:10:29 INFO : file5.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file2.txt.cofaciw6.partial: renamed to: file2.txt 2024/05/06 05:10:29 INFO : file2.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file7.txt.tamimoz3.partial: renamed to: file7.txt 2024/05/06 05:10:29 INFO : file7.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file8.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file6.txt.bilutob3.partial: renamed to: file6.txt 2024/05/06 05:10:29 INFO : file6.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file8.txt.juwiqem7.partial: renamed to: file8.txt 2024/05/06 05:10:29 INFO : file8.txt: Copied (new) 2024/05/06 05:10:29 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/05/06 05:10:29 DEBUG : file4.txt.sexudab8.partial: renamed to: file4.txt 2024/05/06 05:10:29 INFO : file4.txt: Copied (new) 2024/05/06 05:10:29 checking Path1 Local file system at /tmp/050349pe/volatile/path1 2024/05/06 05:10:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : file8.txt: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/05/06 05:10:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/volatile/path2': Waiting for checks to finish 2024/05/06 05:10:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/volatile/path2': Waiting for transfers to finish 2024/05/06 05:10:30 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:31 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:31 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:31 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:37 INFO : file1.txt.rclone_chunk.001_1yk1vw: Moved (server-side) to: file1.txt 2024/05/06 05:10:37 INFO : file1.txt: Copied (new) 2024/05/06 05:10:37 INFO : file3.txt.rclone_chunk.001_1yk2rx: Moved (server-side) to: file3.txt 2024/05/06 05:10:37 INFO : file3.txt: Copied (new) 2024/05/06 05:10:37 INFO : file2.txt.rclone_chunk.001_1yk2e0: Moved (server-side) to: file2.txt 2024/05/06 05:10:37 INFO : file2.txt: Copied (new) 2024/05/06 05:10:37 DEBUG : file5.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:37 INFO : file4.txt.rclone_chunk.001_1yk242: Moved (server-side) to: file4.txt 2024/05/06 05:10:37 INFO : file4.txt: Copied (new) 2024/05/06 05:10:37 DEBUG : file6.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:37 DEBUG : file7.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:38 DEBUG : file8.txt: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:40 INFO : file5.txt.rclone_chunk.001_1yk8vg: Moved (server-side) to: file5.txt 2024/05/06 05:10:40 INFO : file5.txt: Copied (new) 2024/05/06 05:10:40 INFO : file6.txt.rclone_chunk.001_1yk84i: Moved (server-side) to: file6.txt 2024/05/06 05:10:40 INFO : file6.txt: Copied (new) 2024/05/06 05:10:40 INFO : file7.txt.rclone_chunk.001_1yk8f4: Moved (server-side) to: file7.txt 2024/05/06 05:10:40 INFO : file7.txt: Copied (new) 2024/05/06 05:10:40 INFO : file8.txt.rclone_chunk.001_1yk975: Moved (server-side) to: file8.txt 2024/05/06 05:10:40 INFO : file8.txt: Copied (new) 2024/05/06 05:10:40 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/05/06 05:10:45 INFO : RCLONE_TEST.rclone_chunk.001_1ykb9r: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/05/06 05:10:46 INFO : RCLONE_TEST.rclone_chunk.002_1ykb9r: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/05/06 05:10:46 INFO : RCLONE_TEST.rclone_chunk.003_1ykb9r: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/05/06 05:10:48 INFO : RCLONE_TEST: Copied (new) 2024/05/06 05:10:48 checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6/050349pe/volatile/path2' 2024/05/06 05:10:48 (01) : test volatile 2024/05/06 05:10:48 (02) : test initial bisync 2024/05/06 05:10:48 (03) : bisync resync bisync_test.go:923: skipping 'volatile' test on non-local as it requires uploading 100 files 2024/05/06 05:10:48 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-diyuqip7woleroz6vozinor6': Purge remote --- PASS: TestBisyncLocalRemote (423.30s) --- PASS: TestBisyncLocalRemote/resolve (130.22s) --- PASS: TestBisyncLocalRemote/resync (107.36s) --- PASS: TestBisyncLocalRemote/resync_modes (118.82s) --- PASS: TestBisyncLocalRemote/rmdirs (40.90s) --- SKIP: TestBisyncLocalRemote/volatile (21.66s) PASS 2024/05/06 05:10:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-luwadiq7cebuneh8lufamog8': Purge remote 2024/05/06 05:10:52 DEBUG : box root 'rclone-test-luwadiq7cebuneh8lufamog8': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/05/06 05:10:52 purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestBisyncLocalRemote$/^(resolve|resync|resync_modes|rmdirs|volatile)$'" - Finished OK in 7m4.640580899s (try 2/5)