"./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestBisyncLocalRemote$/^resync$'" - Starting (try 3/5) 2024/11/26 05:05:38 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-sayedak9nugi" 2024/11/26 05:05:38 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/26 05:05:38 DEBUG : Creating backend with remote "TestBox:rclone-test-sayedak9nugi" 2024/11/26 05:05:39 DEBUG : box root 'rclone-test-sayedak9nugi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:05:39 DEBUG : Reset feature "ListR" 2024/11/26 05:05:39 DEBUG : Creating backend with remote "/tmp/rclone4107210615" === RUN TestBisyncLocalRemote 2024/11/26 05:05:39 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-wocosew1bida" 2024/11/26 05:05:39 DEBUG : Creating backend with remote "TestBox:rclone-test-wocosew1bida" 2024/11/26 05:05:40 DEBUG : box root 'rclone-test-wocosew1bida': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:05:40 DEBUG : Reset feature "ListR" 2024/11/26 05:05:40 NOTICE: remote: TestChunkerChunk50bBox:rclone-test-wocosew1bida === RUN TestBisyncLocalRemote/resync 2024/11/26 05:05:40 DEBUG : Creating backend with remote "/tmp/050540pa" 2024/11/26 05:05:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/11/26 05:05:40 DEBUG : Creating backend with remote "/tmp/050540pa/resync/path1" 2024/11/26 05:05:40 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa" 2024/11/26 05:05:40 DEBUG : Creating backend with remote "TestBox:rclone-test-wocosew1bida/050540pa" 2024/11/26 05:05:41 DEBUG : box root 'rclone-test-wocosew1bida/050540pa': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:05:41 DEBUG : box root 'rclone-test-wocosew1bida': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:05:41 DEBUG : Reset feature "ListR" 2024/11/26 05:05:41 DEBUG : path2: Making directory 2024/11/26 05:05:42 DEBUG : box root 'rclone-test-wocosew1bida/050540pa': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:05:46 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2" 2024/11/26 05:05:46 DEBUG : Creating backend with remote "TestBox:rclone-test-wocosew1bida/050540pa/resync/path2" 2024/11/26 05:05:50 DEBUG : Reset feature "ListR" 2024/11/26 05:05:50 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/initial" 2024/11/26 05:05:50 DEBUG : Creating backend with remote "/tmp/050540pa/initdir/test_resync-xohiruf2" 2024/11/26 05:05:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2024/11/26 05:05:50 INFO : file1.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt 2024/11/26 05:05:50 INFO : file2.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt 2024/11/26 05:05:50 INFO : file4.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/initdir/test_resync-xohiruf2: Waiting for checks to finish 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/initdir/test_resync-xohiruf2: Waiting for transfers to finish 2024/11/26 05:05:50 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/11/26 05:05:50 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt 2024/11/26 05:05:50 INFO : file3.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST 2024/11/26 05:05:50 INFO : RCLONE_TEST: Copied (new) 2024/11/26 05:05:50 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt 2024/11/26 05:05:50 INFO : file5.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt 2024/11/26 05:05:50 INFO : file6.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt 2024/11/26 05:05:50 INFO : file7.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : Creating backend with remote "/home/rclone/go/src/github.com/rclone/rclone/cmd/bisync/testdata/test_resync/modfiles" 2024/11/26 05:05:50 DEBUG : Creating backend with remote "/tmp/050540pa/datadir/test_resync-yalajuj1" 2024/11/26 05:05:50 DEBUG : fileA.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : fileB.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/datadir/test_resync-yalajuj1: Waiting for checks to finish 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/datadir/test_resync-yalajuj1: Waiting for transfers to finish 2024/11/26 05:05:50 DEBUG : fileA.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/11/26 05:05:50 DEBUG : fileB.txt: md5 = 7fe98ed88552b828777d8630900346b8 OK 2024/11/26 05:05:50 DEBUG : fileA.txt.16ee54da.partial: renamed to: fileA.txt 2024/11/26 05:05:50 INFO : fileA.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : fileB.txt.f0a2c6af.partial: renamed to: fileB.txt 2024/11/26 05:05:50 INFO : fileB.txt: Copied (new) 2024/11/26 05:05:50 ERROR : : error listing: directory not found 2024/11/26 05:05:50 ERROR : : error listing: directory not found 2024/11/26 05:05:50 NOTICE: checking initFs Local file system at /tmp/050540pa/initdir/test_resync-xohiruf2 2024/11/26 05:05:50 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : file1.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file2.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file1.txt.5028a97.partial: renamed to: file1.txt 2024/11/26 05:05:50 DEBUG : file2.txt.5028a97.partial: renamed to: file2.txt 2024/11/26 05:05:50 INFO : file1.txt: Copied (new) 2024/11/26 05:05:50 INFO : file2.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/resync/path1: Waiting for checks to finish 2024/11/26 05:05:50 DEBUG : Local file system at /tmp/050540pa/resync/path1: Waiting for transfers to finish 2024/11/26 05:05:50 DEBUG : file4.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file5.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file5.txt.5028a97.partial: renamed to: file5.txt 2024/11/26 05:05:50 INFO : file5.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file4.txt.5028a97.partial: renamed to: file4.txt 2024/11/26 05:05:50 INFO : file4.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : RCLONE_TEST: md5 = 294d25b294ff26a5243dba914ac3fbf7 OK 2024/11/26 05:05:50 DEBUG : file6.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : RCLONE_TEST.cf9a93ac.partial: renamed to: RCLONE_TEST 2024/11/26 05:05:50 DEBUG : file6.txt.5028a97.partial: renamed to: file6.txt 2024/11/26 05:05:50 INFO : RCLONE_TEST: Copied (new) 2024/11/26 05:05:50 INFO : file6.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file3.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file3.txt.5028a97.partial: renamed to: file3.txt 2024/11/26 05:05:50 INFO : file3.txt: Copied (new) 2024/11/26 05:05:50 DEBUG : file7.txt: md5 = d41d8cd98f00b204e9800998ecf8427e OK 2024/11/26 05:05:50 DEBUG : file7.txt.5028a97.partial: renamed to: file7.txt 2024/11/26 05:05:50 INFO : file7.txt: Copied (new) 2024/11/26 05:05:50 NOTICE: checking Path1 Local file system at /tmp/050540pa/resync/path1 2024/11/26 05:05:51 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file2.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file3.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file4.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file5.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file6.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : file7.txt: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : RCLONE_TEST: Need to transfer - File not found at Destination 2024/11/26 05:05:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2': Waiting for checks to finish 2024/11/26 05:05:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2': Waiting for transfers to finish 2024/11/26 05:05:51 DEBUG : file1.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:05:52 DEBUG : file2.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:05:53 DEBUG : file3.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:05:53 DEBUG : file4.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:06:01 INFO : file2.txt.rclone_chunk.001_jqe9vi: Moved (server-side) to: file2.txt 2024/11/26 05:06:01 INFO : file2.txt: Copied (new) 2024/11/26 05:06:01 DEBUG : Couldn't decode error response: invalid character 'u' looking for beginning of value 2024/11/26 05:06:01 DEBUG : pacer: low level retry 1/10 (error Error "503 Service Unavailable" (503)) 2024/11/26 05:06:01 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2024/11/26 05:06:01 DEBUG : pacer: Reducing sleep to 15ms 2024/11/26 05:06:01 INFO : file1.txt.rclone_chunk.001_jqe8is: Moved (server-side) to: file1.txt 2024/11/26 05:06:01 INFO : file1.txt: Copied (new) 2024/11/26 05:06:01 DEBUG : pacer: Reducing sleep to 11.25ms 2024/11/26 05:06:01 INFO : file3.txt.rclone_chunk.001_jqea7d: Moved (server-side) to: file3.txt 2024/11/26 05:06:01 INFO : file3.txt: Copied (new) 2024/11/26 05:06:01 DEBUG : pacer: Reducing sleep to 10ms 2024/11/26 05:06:01 DEBUG : file5.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:06:01 DEBUG : file6.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:06:01 DEBUG : file7.txt: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:06:02 INFO : file4.txt.rclone_chunk.001_jqea9j: Moved (server-side) to: file4.txt 2024/11/26 05:06:02 INFO : file4.txt: Copied (new) 2024/11/26 05:06:02 DEBUG : RCLONE_TEST: skip slow MD5 on source file, hashing in-transit 2024/11/26 05:06:05 INFO : file5.txt.rclone_chunk.001_jqei3h: Moved (server-side) to: file5.txt 2024/11/26 05:06:05 INFO : file5.txt: Copied (new) 2024/11/26 05:06:05 INFO : file7.txt.rclone_chunk.001_jqeisj: Moved (server-side) to: file7.txt 2024/11/26 05:06:05 INFO : file7.txt: Copied (new) 2024/11/26 05:06:05 INFO : file6.txt.rclone_chunk.001_jqeitf: Moved (server-side) to: file6.txt 2024/11/26 05:06:05 INFO : file6.txt: Copied (new) 2024/11/26 05:06:09 INFO : RCLONE_TEST.rclone_chunk.001_jqejqj: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/11/26 05:06:09 INFO : RCLONE_TEST.rclone_chunk.002_jqejqj: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/11/26 05:06:10 INFO : RCLONE_TEST.rclone_chunk.003_jqejqj: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/11/26 05:06:12 INFO : RCLONE_TEST: Copied (new) 2024/11/26 05:06:12 NOTICE: checking path2 Chunked 'TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2' 2024/11/26 05:06:12 NOTICE: (01) : test resync 2024/11/26 05:06:12 NOTICE: (02) : test 1. resync with empty path1, resulting in copying all content from path2. 2024/11/26 05:06:12 NOTICE: (03) : purge-children /tmp/050540pa/resync/path1/ 2024/11/26 05:06:12 NOTICE: (04) : bisync resync 2024/11/26 05:06:16 INFO : modtime_write_test.rclone_chunk.001_jqeuyk: Moved (server-side) to: modtime_write_test 2024/11/26 05:06:17 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/11/26 05:06:17 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/11/26 05:06:17 INFO : Synching Path1 "/tmp/050540pa/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:06:17 INFO : Copying Path2 files to Path1 2024/11/26 05:06:17 INFO : - Path2 Resync is copying files to - Path1 2024/11/26 05:06:19 INFO : file3.txt: Copied (new) 2024/11/26 05:06:19 INFO : file1.txt: Copied (new) 2024/11/26 05:06:19 INFO : file2.txt: Copied (new) 2024/11/26 05:06:20 INFO : file5.txt: Copied (new) 2024/11/26 05:06:20 INFO : file4.txt: Copied (new) 2024/11/26 05:06:20 INFO : file6.txt: Copied (new) 2024/11/26 05:06:21 INFO : file7.txt: Copied (new) 2024/11/26 05:06:21 INFO : RCLONE_TEST: Copied (new) 2024/11/26 05:06:21 INFO : - Path1 Resync is copying files to - Path2 2024/11/26 05:06:22 INFO : Resync updating listings 2024/11/26 05:06:22 INFO : Validating listings for Path1 "/tmp/050540pa/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:06:22 INFO : Bisync successful 2024/11/26 05:06:22 NOTICE: (05) : move-listings empty-path1 2024/11/26 05:06:22 NOTICE: (06) : test 2. resync with empty path2, resulting in synching all content to path2. 2024/11/26 05:06:22 NOTICE: (07) : purge-children TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ 2024/11/26 05:06:35 NOTICE: (08) : bisync resync 2024/11/26 05:06:38 INFO : modtime_write_test.rclone_chunk.001_jqfga7: Moved (server-side) to: modtime_write_test 2024/11/26 05:06:40 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/11/26 05:06: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/11/26 05:06:40 INFO : Synching Path1 "/tmp/050540pa/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:06:40 INFO : Copying Path2 files to Path1 2024/11/26 05:06:40 INFO : - Path2 Resync is copying files to - Path1 2024/11/26 05:06:40 INFO : - Path1 Resync is copying files to - Path2 2024/11/26 05:06:44 INFO : file1.txt.rclone_chunk.001_jqfmmr: Moved (server-side) to: file1.txt 2024/11/26 05:06:44 INFO : file1.txt: Copied (new) 2024/11/26 05:06:44 INFO : file3.txt.rclone_chunk.001_jqfmmr: Moved (server-side) to: file3.txt 2024/11/26 05:06:44 INFO : file3.txt: Copied (new) 2024/11/26 05:06:45 INFO : file4.txt.rclone_chunk.001_jqfmv5: Moved (server-side) to: file4.txt 2024/11/26 05:06:45 INFO : file4.txt: Copied (new) 2024/11/26 05:06:47 INFO : file2.txt.rclone_chunk.001_jqfmec: Moved (server-side) to: file2.txt 2024/11/26 05:06:47 INFO : file2.txt: Copied (new) 2024/11/26 05:06:48 INFO : file5.txt.rclone_chunk.001_jqfq1x: Moved (server-side) to: file5.txt 2024/11/26 05:06:48 INFO : file5.txt: Copied (new) 2024/11/26 05:06:48 INFO : file7.txt.rclone_chunk.001_jqfqzv: Moved (server-side) to: file7.txt 2024/11/26 05:06:48 INFO : file7.txt: Copied (new) 2024/11/26 05:06:48 INFO : file6.txt.rclone_chunk.001_jqfq4s: Moved (server-side) to: file6.txt 2024/11/26 05:06:48 INFO : file6.txt: Copied (new) 2024/11/26 05:06:54 INFO : RCLONE_TEST.rclone_chunk.001_jqfsah: Moved (server-side) to: RCLONE_TEST.rclone_chunk.001 2024/11/26 05:06:55 INFO : RCLONE_TEST.rclone_chunk.002_jqfsah: Moved (server-side) to: RCLONE_TEST.rclone_chunk.002 2024/11/26 05:06:55 INFO : RCLONE_TEST.rclone_chunk.003_jqfsah: Moved (server-side) to: RCLONE_TEST.rclone_chunk.003 2024/11/26 05:06:57 INFO : RCLONE_TEST: Copied (new) 2024/11/26 05:06:57 INFO : Resync updating listings 2024/11/26 05:06:57 INFO : Validating listings for Path1 "/tmp/050540pa/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:06:57 INFO : Bisync successful 2024/11/26 05:06:57 NOTICE: (09) : move-listings empty-path2 2024/11/26 05:06:57 NOTICE: (10) : test 3. exercise all of the various file difference scenarios during a resync. 2024/11/26 05:06:57 NOTICE: (11) : touch-glob 2002-02-02 /tmp/050540pa/datadir/test_resync-yalajuj1/ fileA.txt 2024/11/26 05:06:57 NOTICE: (12) : touch-glob 1999-09-09 /tmp/050540pa/datadir/test_resync-yalajuj1/ fileB.txt 2024/11/26 05:06:57 NOTICE: (13) : test = file - path1 - path2 - expected action - who wins 2024/11/26 05:06:57 NOTICE: (14) : test - file1.txt - exists - missing - sync path1 > path2 - path1 2024/11/26 05:06:57 NOTICE: (15) : delete-file TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/file1.txt 2024/11/26 05:07:00 INFO : file1.txt: Deleted 2024/11/26 05:07:00 NOTICE: (16) : test - file2.txt - missing - exists - copy path2 > path1 - path2 2024/11/26 05:07:00 NOTICE: (17) : delete-file /tmp/050540pa/resync/path1/file2.txt 2024/11/26 05:07:00 INFO : file2.txt: Deleted 2024/11/26 05:07:00 NOTICE: (18) : test - file3.txt - exists - newer date - sync path1 > path2 - path1 2024/11/26 05:07:00 NOTICE: (19) : copy-as /tmp/050540pa/datadir/test_resync-yalajuj1/fileA.txt TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ file3.txt 2024/11/26 05:07:06 INFO : file3.txt: Deleted 2024/11/26 05:07:06 INFO : file3.txt.rclone_chunk.001_jqg6eh: Moved (server-side) to: file3.txt 2024/11/26 05:07:06 INFO : fileA.txt: Copied (replaced existing) to: file3.txt 2024/11/26 05:07:06 NOTICE: (20) : test - file4.txt - missing - newer date - copy path2 > path1 - path2 2024/11/26 05:07:06 NOTICE: (21) : delete-file /tmp/050540pa/resync/path1/file4.txt 2024/11/26 05:07:06 INFO : file4.txt: Deleted 2024/11/26 05:07:06 NOTICE: (22) : copy-as /tmp/050540pa/datadir/test_resync-yalajuj1/fileA.txt TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ file4.txt 2024/11/26 05:07:13 INFO : file4.txt: Deleted 2024/11/26 05:07:13 INFO : file4.txt.rclone_chunk.001_jqgdg6: Moved (server-side) to: file4.txt 2024/11/26 05:07:13 INFO : fileA.txt: Copied (replaced existing) to: file4.txt 2024/11/26 05:07:13 NOTICE: (23) : test - file5.txt - exists - older date - sync path1 > path2 - path1 2024/11/26 05:07:13 NOTICE: (24) : copy-as /tmp/050540pa/datadir/test_resync-yalajuj1/fileB.txt TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ file5.txt 2024/11/26 05:07:20 INFO : file5.txt: Deleted 2024/11/26 05:07:20 INFO : file5.txt.rclone_chunk.001_jqgkvm: Moved (server-side) to: file5.txt 2024/11/26 05:07:20 INFO : fileB.txt: Copied (replaced existing) to: file5.txt 2024/11/26 05:07:20 NOTICE: (25) : test - file6.txt - older date - newer date - sync path1 > path2 - path1 2024/11/26 05:07:20 NOTICE: (26) : copy-as /tmp/050540pa/datadir/test_resync-yalajuj1/fileB.txt /tmp/050540pa/resync/path1/ file6.txt 2024/11/26 05:07:20 INFO : fileB.txt: Copied (replaced existing) to: file6.txt 2024/11/26 05:07:20 NOTICE: (27) : copy-as /tmp/050540pa/datadir/test_resync-yalajuj1/fileA.txt TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ file6.txt 2024/11/26 05:07:27 INFO : file6.txt: Deleted 2024/11/26 05:07:27 INFO : file6.txt.rclone_chunk.001_jqgrxh: Moved (server-side) to: file6.txt 2024/11/26 05:07:27 INFO : fileA.txt: Copied (replaced existing) to: file6.txt 2024/11/26 05:07:27 NOTICE: (28) : test - file7.txt - exists - exists (same) - none - same 2024/11/26 05:07:27 NOTICE: (29) : test run bisync with resync 2024/11/26 05:07:27 NOTICE: (30) : bisync resync 2024/11/26 05:07:31 INFO : modtime_write_test.rclone_chunk.001_jqgxt9: Moved (server-side) to: modtime_write_test 2024/11/26 05:07:33 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/11/26 05:07:33 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/11/26 05:07:33 INFO : Synching Path1 "/tmp/050540pa/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:07:33 INFO : Copying Path2 files to Path1 2024/11/26 05:07:33 INFO : - Path2 Resync is copying files to - Path1 2024/11/26 05:07:34 INFO : file4.txt: Copied (new) 2024/11/26 05:07:34 INFO : file2.txt: Copied (new) 2024/11/26 05:07:34 INFO : - Path1 Resync is copying files to - Path2 2024/11/26 05:07:39 INFO : file1.txt.rclone_chunk.001_jqh4e3: Moved (server-side) to: file1.txt 2024/11/26 05:07:39 INFO : file1.txt: Copied (new) 2024/11/26 05:07:40 INFO : file6.txt: Deleted 2024/11/26 05:07:40 INFO : file5.txt: Deleted 2024/11/26 05:07:40 INFO : file6.txt.rclone_chunk.001_jqh4qc: Moved (server-side) to: file6.txt 2024/11/26 05:07:40 INFO : file6.txt: Copied (replaced existing) 2024/11/26 05:07:41 INFO : file5.txt.rclone_chunk.001_jqh44e: Moved (server-side) to: file5.txt 2024/11/26 05:07:41 INFO : file5.txt: Copied (replaced existing) 2024/11/26 05:07:41 INFO : file3.txt: Deleted 2024/11/26 05:07:41 INFO : file3.txt.rclone_chunk.001_jqh4qn: Moved (server-side) to: file3.txt 2024/11/26 05:07:41 INFO : file3.txt: Copied (replaced existing) 2024/11/26 05:07:41 INFO : Resync updating listings 2024/11/26 05:07:41 INFO : Validating listings for Path1 "/tmp/050540pa/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:07:41 INFO : Bisync successful 2024/11/26 05:07:41 NOTICE: (31) : copy-listings mixed-diffs 2024/11/26 05:07:41 NOTICE: (32) : test run normal bisync 2024/11/26 05:07:41 NOTICE: (33) : bisync 2024/11/26 05:07:45 INFO : modtime_write_test.rclone_chunk.001_jqhbpb: Moved (server-side) to: modtime_write_test 2024/11/26 05:07:47 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/11/26 05:07:47 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/11/26 05:07:47 INFO : Synching Path1 "/tmp/050540pa/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:07:47 INFO : Building Path1 and Path2 listings 2024/11/26 05:07:47 INFO : Path1 checking for diffs 2024/11/26 05:07:47 INFO : Path2 checking for diffs 2024/11/26 05:07:47 INFO : No changes found 2024/11/26 05:07:47 INFO : Updating listings 2024/11/26 05:07:47 INFO : Validating listings for Path1 "/tmp/050540pa/resync/path1/" vs Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:07:47 INFO : Bisync successful 2024/11/26 05:07:47 NOTICE: (34) : test 4. confirm critical error on normal sync of empty path. 2024/11/26 05:07:47 NOTICE: (35) : purge-children TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/ 2024/11/26 05:08:09 NOTICE: (36) : bisync 2024/11/26 05:08:14 INFO : modtime_write_test.rclone_chunk.001_jqi2jz: Moved (server-side) to: modtime_write_test 2024/11/26 05:08:15 INFO : Setting --ignore-listing-checksum as neither --checksum nor --compare checksum are set. 2024/11/26 05:08:15 INFO : Bisyncing with Comparison Settings: { "Modtime": true, "Size": true, "Checksum": false, "HashType1": 0, "HashType2": 0, "NoSlowHash": false, "SlowHashSyncOnly": false, "SlowHashDetected": true, "DownloadHash": false } 2024/11/26 05:08:15 INFO : Synching Path1 "/tmp/050540pa/resync/path1/" with Path2 "TestChunkerChunk50bBox:rclone-test-wocosew1bida/050540pa/resync/path2/" 2024/11/26 05:08:15 INFO : Building Path1 and Path2 listings 2024/11/26 05:08:16 INFO : Path1 checking for diffs 2024/11/26 05:08:16 INFO : Path2 checking for diffs 2024/11/26 05:08:16 ERROR : Empty current Path2 listing. Cannot sync to an empty directory: /tmp/050540pa/workdir/tmp_050540pa_resync_path1..TestChunkerChunk50bBox_rclone-test-wocosew1bida_050540pa_resync_path2.path2.lst-new 2024/11/26 05:08:16 ERROR : Bisync critical error: empty current Path2 listing: /tmp/050540pa/workdir/tmp_050540pa_resync_path1..TestChunkerChunk50bBox_rclone-test-wocosew1bida_050540pa_resync_path2.path2.lst-new 2024/11/26 05:08:16 ERROR : Bisync aborted. Must run --resync to recover. 2024/11/26 05:08:16 NOTICE: Bisync error: bisync aborted bisync_test.go:558: TEST resync PASSED 2024/11/26 05:08:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-wocosew1bida': Purge remote --- PASS: TestBisyncLocalRemote (161.12s) --- PASS: TestBisyncLocalRemote/resync (156.75s) PASS 2024/11/26 05:08:20 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-sayedak9nugi': Purge remote 2024/11/26 05:08:21 DEBUG : box root 'rclone-test-sayedak9nugi': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2024/11/26 05:08:21 NOTICE: purge failed: directory not found "./bisync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024 -test.run '^TestBisyncLocalRemote$/^resync$'" - Finished OK in 2m43.368249741s (try 3/5)