"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Starting (try 1/5) 2021/06/01 05:49:15 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7" 2021/06/01 05:49:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2021/06/01 05:49:15 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7" 2021/06/01 05:49:15 DEBUG : box root 'rclone-test-noyibol9noqucoh2yeyoxax7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:49:16 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:49:16 DEBUG : Reset feature "ListR" 2021/06/01 05:49:16 DEBUG : Creating backend with remote "/tmp/rclone047265206" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.06s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:16 DEBUG : box root 'rclone-test-noyibol9noqucoh2yeyoxax7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:49:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:17 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) --- PASS: TestCopyWithDryRun (2.51s) === RUN TestCopy run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:19 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:24 INFO : sub dir/hello world.rclone_chunk.001_0iq15f: Moved (server-side) to: sub dir/hello world 2021/06/01 05:49:24 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (9.22s) === RUN TestCopyMissingDirectory run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:28 DEBUG : Creating backend with remote "/non-existing" 2021/06/01 05:49:28 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2021/06/01 05:49:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:28 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.95s) === RUN TestCopyNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:29 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:29 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:34 INFO : sub dir/hello world.rclone_chunk.001_0iqbfc: Moved (server-side) to: sub dir/hello world 2021/06/01 05:49:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (9.04s) === RUN TestCopyCheckFirst run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:38 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Running all checks before starting transfers 2021/06/01 05:49:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:38 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Checks finished, now starting transfers 2021/06/01 05:49:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:38 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:43 INFO : sub dir/hello world.rclone_chunk.001_0iqk6s: Moved (server-side) to: sub dir/hello world 2021/06/01 05:49:43 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (9.24s) === RUN TestSyncNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:47 ERROR : Ignoring --no-traverse with sync 2021/06/01 05:49:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:48 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:53 INFO : sub dir/hello world.rclone_chunk.001_0iqud0: Moved (server-side) to: sub dir/hello world 2021/06/01 05:49:53 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:49:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (9.73s) === RUN TestCopyWithDepth run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:49:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:49:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:49:57 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value 2021/06/01 05:49:58 DEBUG : pacer: low level retry 1/1 (error Error "502 Bad Gateway" (502)) 2021/06/01 05:49:58 DEBUG : pacer: Rate limited, increasing sleep to 20ms 2021/06/01 05:49:58 DEBUG : hello world2: Received error: Error "502 Bad Gateway" (502) - low level retry 1/10 2021/06/01 05:49:59 DEBUG : pacer: Reducing sleep to 15ms 2021/06/01 05:49:59 DEBUG : hello world2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:49:59 DEBUG : pacer: Reducing sleep to 11.25ms 2021/06/01 05:50:00 DEBUG : pacer: Reducing sleep to 10ms 2021/06/01 05:50:03 INFO : hello world2.rclone_chunk.001_0ir5gh: Moved (server-side) to: hello world2 2021/06/01 05:50:03 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (7.97s) === RUN TestCopyWithFilesFrom run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:05 DEBUG : hello world2: Excluded 2021/06/01 05:50:05 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:50:05 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:50:05 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:50:09 INFO : potato2.rclone_chunk.001_0irbgr: Moved (server-side) to: potato2 2021/06/01 05:50:09 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (6.62s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:50:12 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:50:12 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:50:15 INFO : potato2.rclone_chunk.001_0iri98: Moved (server-side) to: potato2 2021/06/01 05:50:15 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (6.20s) === RUN TestCopyEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:17 DEBUG : sub dir2: Making directory 2021/06/01 05:50:18 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:50:18 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:50:18 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:50:23 INFO : sub dir/hello world.rclone_chunk.001_0iroxy: Moved (server-side) to: sub dir/hello world 2021/06/01 05:50:23 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:50:23 DEBUG : sub dir2: Making directory 2021/06/01 05:50:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': copied 1 directories --- PASS: TestCopyEmptyDirectories (11.85s) === RUN TestMoveEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:29 DEBUG : sub dir2: Making directory 2021/06/01 05:50:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:50:30 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:50:30 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:50:35 INFO : sub dir/hello world.rclone_chunk.001_0is0bw: Moved (server-side) to: sub dir/hello world 2021/06/01 05:50:35 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:50:35 INFO : sub dir/hello world: Deleted 2021/06/01 05:50:35 DEBUG : sub dir: Making directory 2021/06/01 05:50:35 DEBUG : sub dir2: Making directory 2021/06/01 05:50:36 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': copied 2 directories --- PASS: TestMoveEmptyDirectories (11.04s) === RUN TestSyncEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:40 DEBUG : sub dir2: Making directory 2021/06/01 05:50:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:50:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:50:41 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:50:46 INFO : sub dir/hello world.rclone_chunk.001_0isbi2: Moved (server-side) to: sub dir/hello world 2021/06/01 05:50:46 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:50:46 DEBUG : sub dir2: Making directory 2021/06/01 05:50:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': copied 1 directories 2021/06/01 05:50:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (11.29s) === RUN TestServerSideCopy run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:50:57 INFO : sub dir/hello world.rclone_chunk.001_0ismuq: Moved (server-side) to: sub dir/hello world 2021/06/01 05:50:58 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-lakibib7rejocux5taqafow5" 2021/06/01 05:50:58 DEBUG : Creating backend with remote "TestBox:rclone-test-lakibib7rejocux5taqafow5" 2021/06/01 05:50:58 DEBUG : box root 'rclone-test-lakibib7rejocux5taqafow5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:50:58 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:50:58 DEBUG : Reset feature "ListR" sync_test.go:281: Server side copy (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7' -> Chunked 'TestChunkerChunk50bBox:rclone-test-lakibib7rejocux5taqafow5' 2021/06/01 05:50:59 DEBUG : box root 'rclone-test-lakibib7rejocux5taqafow5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:50:59 DEBUG : sub dir/hello world: copy non-chunked object... 2021/06/01 05:50:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lakibib7rejocux5taqafow5': Waiting for checks to finish 2021/06/01 05:50:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lakibib7rejocux5taqafow5': Waiting for transfers to finish 2021/06/01 05:51:00 DEBUG : box root 'rclone-test-lakibib7rejocux5taqafow5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:51:02 INFO : sub dir/hello world: Copied (server-side copy) 2021/06/01 05:51:03 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-lakibib7rejocux5taqafow5': Purge remote --- PASS: TestServerSideCopy (15.53s) === RUN TestCopyAfterDelete run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:12 INFO : sub dir/hello world.rclone_chunk.001_0it1nf: Moved (server-side) to: sub dir/hello world 2021/06/01 05:51:12 ERROR : : error listing: directory not found 2021/06/01 05:51:13 DEBUG : Local file system at /tmp/rclone047265206: Making directory 2021/06/01 05:51:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (9.41s) === RUN TestCopyRedownload run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:22 INFO : sub dir/hello world.rclone_chunk.001_0itbr6: Moved (server-side) to: sub dir/hello world 2021/06/01 05:51:23 DEBUG : Local file system at /tmp/rclone047265206: Waiting for checks to finish 2021/06/01 05:51:23 DEBUG : Local file system at /tmp/rclone047265206: Waiting for transfers to finish 2021/06/01 05:51:24 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (10.09s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:27 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:27 DEBUG : check sum: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:51:31 INFO : check sum.rclone_chunk.001_0itlmw: Moved (server-side) to: check sum 2021/06/01 05:51:31 INFO : check sum: Copied (new) 2021/06/01 05:51:31 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:32 NOTICE: Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2021/06/01 05:51:32 DEBUG : check sum: Size of src and dst objects identical 2021/06/01 05:51:32 DEBUG : check sum: Unchanged skipping 2021/06/01 05:51:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:32 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:32 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (6.99s) === RUN TestSyncSizeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:34 DEBUG : sizeonly: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:51:38 INFO : sizeonly.rclone_chunk.001_0itsph: Moved (server-side) to: sizeonly 2021/06/01 05:51:38 INFO : sizeonly: Copied (new) 2021/06/01 05:51:38 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:38 DEBUG : sizeonly: Sizes identical 2021/06/01 05:51:38 DEBUG : sizeonly: Unchanged skipping 2021/06/01 05:51:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:38 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:38 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (6.80s) === RUN TestSyncIgnoreSize run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:41 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:41 DEBUG : ignore-size: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:51:45 INFO : ignore-size.rclone_chunk.001_0itzw7: Moved (server-side) to: ignore-size 2021/06/01 05:51:45 INFO : ignore-size: Copied (new) 2021/06/01 05:51:45 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:45 DEBUG : ignore-size: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 05:51:45 DEBUG : ignore-size: Unchanged skipping 2021/06/01 05:51:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:45 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:45 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (7.06s) === RUN TestSyncIgnoreTimes run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:51:52 INFO : existing.rclone_chunk.001_0iu6q2: Moved (server-side) to: existing 2021/06/01 05:51:52 DEBUG : existing: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 05:51:52 DEBUG : existing: Unchanged skipping 2021/06/01 05:51:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:52 DEBUG : Waiting for deletions to finish 2021/06/01 05:51:52 INFO : There was nothing to transfer 2021/06/01 05:51:53 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/06/01 05:51:53 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:51:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:51:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:51:57 INFO : existing: Deleted 2021/06/01 05:51:58 INFO : existing.rclone_chunk.001_0iubw2: Moved (server-side) to: existing 2021/06/01 05:51:58 INFO : existing: Copied (replaced existing) 2021/06/01 05:51:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (12.06s) === RUN TestSyncIgnoreExisting run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:52:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:00 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:52:04 INFO : existing.rclone_chunk.001_0iui3a: Moved (server-side) to: existing 2021/06/01 05:52:04 INFO : existing: Copied (new) 2021/06/01 05:52:04 DEBUG : Waiting for deletions to finish 2021/06/01 05:52:04 DEBUG : existing: Destination exists, skipping 2021/06/01 05:52:04 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:04 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:04 DEBUG : Waiting for deletions to finish 2021/06/01 05:52:04 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (6.81s) === RUN TestSyncIgnoreErrors run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:52:12 INFO : b/potato.rclone_chunk.001_0iup48: Moved (server-side) to: b/potato 2021/06/01 05:52:17 INFO : c/non empty space.rclone_chunk.001_0iuus1: Moved (server-side) to: c/non empty space 2021/06/01 05:52:17 DEBUG : d: Making directory 2021/06/01 05:52:19 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:52:19 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:52:19 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:52:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:26 INFO : a/potato2.rclone_chunk.001_0iv1pl: Moved (server-side) to: a/potato2.rclone_chunk.001 2021/06/01 05:52:26 INFO : a/potato2.rclone_chunk.002_0iv1pl: Moved (server-side) to: a/potato2.rclone_chunk.002 2021/06/01 05:52:28 INFO : a/potato2: Copied (new) 2021/06/01 05:52:28 DEBUG : Waiting for deletions to finish 2021/06/01 05:52:29 INFO : b/potato: Deleted 2021/06/01 05:52:29 DEBUG : d: Removing directory 2021/06/01 05:52:30 DEBUG : b: Removing directory 2021/06/01 05:52:31 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': deleted 2 directories --- PASS: TestSyncIgnoreErrors (33.17s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:52:43 INFO : empty space.rclone_chunk.001_0ivmgn: Moved (server-side) to: empty space 2021/06/01 05:52:44 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2021/06/01 05:52:44 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2021/06/01 05:52:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:44 DEBUG : Waiting for deletions to finish 2021/06/01 05:52:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:45 DEBUG : empty space: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2021/06/01 05:52:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:45 DEBUG : empty space: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:52:49 INFO : empty space: Deleted 2021/06/01 05:52:49 INFO : empty space.rclone_chunk.001_0ivrrl: Moved (server-side) to: empty space 2021/06/01 05:52:49 INFO : empty space: Copied (replaced existing) 2021/06/01 05:52:49 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (11.81s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:591: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.69s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:52:56 INFO : foo.rclone_chunk.001_0ivywx: Moved (server-side) to: foo 2021/06/01 05:52:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:52:57 DEBUG : foo: Modification times differ by -95456h54m53.123456789s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-02 20:05:06 -0800 -0800 2021/06/01 05:52:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:52:57 DEBUG : foo: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:53:02 INFO : foo: Deleted 2021/06/01 05:53:02 INFO : foo.rclone_chunk.001_0iw338: Moved (server-side) to: foo 2021/06/01 05:53:02 INFO : foo: Copied (replaced existing) 2021/06/01 05:53:02 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (12.17s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:53:08 INFO : empty space.rclone_chunk.001_0iwbx3: Moved (server-side) to: empty space 2021/06/01 05:53:09 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:53:09 DEBUG : empty space: Unchanged skipping 2021/06/01 05:53:09 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:53:09 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:53:09 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:53:14 INFO : potato.rclone_chunk.001_0iwff7: Moved (server-side) to: potato.rclone_chunk.001 2021/06/01 05:53:15 INFO : potato.rclone_chunk.002_0iwff7: Moved (server-side) to: potato.rclone_chunk.002 2021/06/01 05:53:17 INFO : potato: Copied (new) 2021/06/01 05:53:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (18.84s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:53:29 INFO : potato.rclone_chunk.001_0iwt10: Moved (server-side) to: potato.rclone_chunk.001 2021/06/01 05:53:29 INFO : potato.rclone_chunk.002_0iwt10: Moved (server-side) to: potato.rclone_chunk.002 2021/06/01 05:53:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:53:32 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/06/01 05:53:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:53:33 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:53:39 INFO : potato: Deleted 2021/06/01 05:53:39 INFO : potato.rclone_chunk.001_0ix3qe: Moved (server-side) to: potato 2021/06/01 05:53:39 INFO : potato: Copied (replaced existing) 2021/06/01 05:53:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (18.43s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:53:46 INFO : potato.rclone_chunk.001_0ixc00: Moved (server-side) to: potato 2021/06/01 05:53:46 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 04:59:59 -0800 -0800 2021/06/01 05:53:46 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:53:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:53:46 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:53:51 INFO : potato: Deleted 2021/06/01 05:53:51 INFO : potato.rclone_chunk.001_0ixg8n: Moved (server-side) to: potato 2021/06/01 05:53:51 INFO : potato: Copied (replaced existing) 2021/06/01 05:53:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (11.79s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:53:57 INFO : potato.rclone_chunk.001_0ixor3: Moved (server-side) to: potato 2021/06/01 05:54:01 INFO : empty space.rclone_chunk.001_0ixrxh: Moved (server-side) to: empty space 2021/06/01 05:54:01 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:54:01 DEBUG : empty space: Unchanged skipping 2021/06/01 05:54:01 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/06/01 05:54:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:54:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:54:01 DEBUG : Waiting for deletions to finish 2021/06/01 05:54:01 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (11.24s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:54:09 INFO : potato.rclone_chunk.001_0ixz5v: Moved (server-side) to: potato 2021/06/01 05:54:19 INFO : empty space.rclone_chunk.001_0iy3pz: Moved (server-side) to: empty space 2021/06/01 05:54:19 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:54:19 DEBUG : empty space: Unchanged skipping 2021/06/01 05:54:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:54:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:54:20 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:54:25 INFO : potato2.rclone_chunk.001_0iyebo: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:54:25 INFO : potato2.rclone_chunk.002_0iyebo: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:54:27 INFO : potato2: Copied (new) 2021/06/01 05:54:27 DEBUG : Waiting for deletions to finish 2021/06/01 05:54:28 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (29.03s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:54:39 INFO : b/potato.rclone_chunk.001_0iysdh: Moved (server-side) to: b/potato 2021/06/01 05:54:44 INFO : c/non empty space.rclone_chunk.001_0iyx4b: Moved (server-side) to: c/non empty space 2021/06/01 05:54:44 DEBUG : d: Making directory 2021/06/01 05:54:45 DEBUG : d/e: Making directory 2021/06/01 05:54:48 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:54:48 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:54:48 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:54:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:54:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:54:54 INFO : a/potato2.rclone_chunk.001_0iz6fx: Moved (server-side) to: a/potato2.rclone_chunk.001 2021/06/01 05:54:55 INFO : a/potato2.rclone_chunk.002_0iz6fx: Moved (server-side) to: a/potato2.rclone_chunk.002 2021/06/01 05:54:57 INFO : a/potato2: Copied (new) 2021/06/01 05:54:57 DEBUG : Waiting for deletions to finish 2021/06/01 05:54:58 INFO : b/potato: Deleted 2021/06/01 05:54:58 DEBUG : d/e: Removing directory 2021/06/01 05:54:59 DEBUG : d: Removing directory 2021/06/01 05:55:00 DEBUG : b: Removing directory 2021/06/01 05:55:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (35.86s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:55:14 INFO : b/potato.rclone_chunk.001_0izsms: Moved (server-side) to: b/potato 2021/06/01 05:55:20 INFO : c/non empty space.rclone_chunk.001_0izxzw: Moved (server-side) to: c/non empty space 2021/06/01 05:55:20 DEBUG : d: Making directory 2021/06/01 05:55:22 DEBUG : c/non empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:55:22 DEBUG : c/non empty space: Unchanged skipping 2021/06/01 05:55:22 DEBUG : a/potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:55:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:55:23 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:55:29 INFO : a/potato2.rclone_chunk.001_0j049g: Moved (server-side) to: a/potato2.rclone_chunk.001 2021/06/01 05:55:30 INFO : a/potato2.rclone_chunk.002_0j049g: Moved (server-side) to: a/potato2.rclone_chunk.002 2021/06/01 05:55:31 INFO : a/potato2: Copied (new) 2021/06/01 05:55:31 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': not deleting files as there were IO errors 2021/06/01 05:55:31 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (32.87s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:55:46 INFO : potato.rclone_chunk.001_0j0pqm: Moved (server-side) to: potato 2021/06/01 05:55:50 INFO : empty space.rclone_chunk.001_0j0tqp: Moved (server-side) to: empty space 2021/06/01 05:55:51 DEBUG : Waiting for deletions to finish 2021/06/01 05:55:51 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:55:51 DEBUG : empty space: Unchanged skipping 2021/06/01 05:55:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:55:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:55:51 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:55:52 INFO : potato: Deleted 2021/06/01 05:55:57 INFO : potato2.rclone_chunk.001_0j0xx3: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:55:57 INFO : potato2.rclone_chunk.002_0j0xx3: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:55:59 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (22.53s) === RUN TestSyncDeleteBefore run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:56:09 INFO : potato.rclone_chunk.001_0j1b1e: Moved (server-side) to: potato 2021/06/01 05:56:12 INFO : empty space.rclone_chunk.001_0j1fp8: Moved (server-side) to: empty space 2021/06/01 05:56:13 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:56:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:56:14 INFO : potato: Deleted 2021/06/01 05:56:14 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:56:14 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:56:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:56:15 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:56:20 INFO : potato2.rclone_chunk.001_0j1ls9: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:56:20 INFO : potato2.rclone_chunk.002_0j1ls9: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:56:22 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (22.89s) === RUN TestCopyDeleteBefore run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:56:31 INFO : potato.rclone_chunk.001_0j1yqs: Moved (server-side) to: potato 2021/06/01 05:56:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:56:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:56:32 DEBUG : potato2: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:56:36 INFO : potato2.rclone_chunk.001_0j22mm: Moved (server-side) to: potato2 2021/06/01 05:56:36 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (11.28s) === RUN TestSyncWithExclude run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:56:44 INFO : potato2.rclone_chunk.001_0j2961: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:56:45 INFO : potato2.rclone_chunk.002_0j2961: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:56:51 INFO : empty space.rclone_chunk.001_0j2hfk: Moved (server-side) to: empty space 2021/06/01 05:56:51 DEBUG : enormous: Excluded 2021/06/01 05:56:51 DEBUG : potato2: Excluded 2021/06/01 05:56:52 DEBUG : potato2: Excluded 2021/06/01 05:56:52 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:56:52 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:56:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:56:52 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:52 INFO : There was nothing to transfer 2021/06/01 05:56:52 DEBUG : enormous: Excluded 2021/06/01 05:56:52 DEBUG : potato2: Excluded 2021/06/01 05:56:53 DEBUG : potato2: Excluded 2021/06/01 05:56:53 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2021/06/01 05:56:53 DEBUG : empty space: Unchanged skipping 2021/06/01 05:56:53 DEBUG : Local file system at /tmp/rclone047265206: Waiting for checks to finish 2021/06/01 05:56:53 DEBUG : Local file system at /tmp/rclone047265206: Waiting for transfers to finish 2021/06/01 05:56:53 DEBUG : Waiting for deletions to finish 2021/06/01 05:56:53 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (18.68s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:57:03 INFO : potato2.rclone_chunk.001_0j2su9: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:57:04 INFO : potato2.rclone_chunk.002_0j2su9: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:57:10 INFO : empty space.rclone_chunk.001_0j30lh: Moved (server-side) to: empty space 2021/06/01 05:57:15 INFO : enormous.rclone_chunk.001_0j34ki: Moved (server-side) to: enormous.rclone_chunk.001 2021/06/01 05:57:16 INFO : enormous.rclone_chunk.002_0j34ki: Moved (server-side) to: enormous.rclone_chunk.002 2021/06/01 05:57:18 DEBUG : enormous: Excluded 2021/06/01 05:57:18 DEBUG : potato2: Excluded 2021/06/01 05:57:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:57:19 DEBUG : empty space: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 05:57:19 DEBUG : empty space: Unchanged skipping 2021/06/01 05:57:19 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:57:19 DEBUG : Waiting for deletions to finish 2021/06/01 05:57:22 INFO : potato2: Deleted 2021/06/01 05:57:22 INFO : enormous: Deleted 2021/06/01 05:57:22 INFO : There was nothing to transfer 2021/06/01 05:57:23 DEBUG : empty space: Size and modification time the same (differ by 123.456789ms, within tolerance 1s) 2021/06/01 05:57:23 DEBUG : empty space: Unchanged skipping 2021/06/01 05:57:23 DEBUG : Local file system at /tmp/rclone047265206: Waiting for checks to finish 2021/06/01 05:57:23 DEBUG : Local file system at /tmp/rclone047265206: Waiting for transfers to finish 2021/06/01 05:57:23 DEBUG : Waiting for deletions to finish 2021/06/01 05:57:23 INFO : potato2: Deleted 2021/06/01 05:57:23 INFO : enormous: Deleted 2021/06/01 05:57:23 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (26.90s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:57:29 INFO : one.rclone_chunk.001_0j3jrw: Moved (server-side) to: one 2021/06/01 05:57:33 INFO : two.rclone_chunk.001_0j3n6k: Moved (server-side) to: two 2021/06/01 05:57:37 INFO : three.rclone_chunk.001_0j3s6y: Moved (server-side) to: three 2021/06/01 05:57:41 INFO : four.rclone_chunk.001_0j3wz1: Moved (server-side) to: four 2021/06/01 05:57:42 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/06/01 05:57:42 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/06/01 05:57:42 DEBUG : four: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:57:42 DEBUG : one: Destination is newer than source, skipping 2021/06/01 05:57:42 DEBUG : three: Sizes identical 2021/06/01 05:57:42 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/06/01 05:57:42 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:57:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:57:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:57:43 DEBUG : five: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:57:46 INFO : five.rclone_chunk.001_0j41ud: Moved (server-side) to: five 2021/06/01 05:57:46 INFO : five: Copied (new) 2021/06/01 05:57:47 INFO : four: Deleted 2021/06/01 05:57:47 INFO : two: Deleted 2021/06/01 05:57:47 INFO : four.rclone_chunk.001_0j408a: Moved (server-side) to: four 2021/06/01 05:57:47 INFO : four: Copied (replaced existing) 2021/06/01 05:57:48 INFO : two.rclone_chunk.001_0j40ol: Moved (server-side) to: two 2021/06/01 05:57:48 INFO : two: Copied (replaced existing) 2021/06/01 05:57:48 DEBUG : Waiting for deletions to finish sync_test.go:1022: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (29.02s) === RUN TestSyncWithMaxDuration sync_test.go:1039: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1088: Can track renames: false 2021/06/01 05:57:54 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Ignoring --track-renames as the source and destination do not have a common hash 2021/06/01 05:57:54 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:57:54 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:57:54 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:57:54 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:57:58 INFO : yam.rclone_chunk.001_0j4c17: Moved (server-side) to: yam 2021/06/01 05:57:58 INFO : yam: Copied (new) 2021/06/01 05:57:58 INFO : potato.rclone_chunk.001_0j4cbm: Moved (server-side) to: potato 2021/06/01 05:57:58 INFO : potato: Copied (new) 2021/06/01 05:57:58 DEBUG : Waiting for deletions to finish 2021/06/01 05:57:58 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Ignoring --track-renames as the source and destination do not have a common hash 2021/06/01 05:57:59 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 05:57:59 DEBUG : potato: Unchanged skipping 2021/06/01 05:57:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:57:59 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:57:59 DEBUG : yaml: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:04 INFO : yaml.rclone_chunk.001_0j4hup: Moved (server-side) to: yaml 2021/06/01 05:58:04 INFO : yaml: Copied (new) 2021/06/01 05:58:04 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:05 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (14.17s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1157: Can track renames: true 2021/06/01 05:58:08 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Making map for --track-renames 2021/06/01 05:58:08 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Finished making map for --track-renames 2021/06/01 05:58:08 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:08 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for renames to finish 2021/06/01 05:58:08 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:08 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:08 DEBUG : yam: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:12 INFO : potato.rclone_chunk.001_0j4qu0: Moved (server-side) to: potato 2021/06/01 05:58:12 INFO : potato: Copied (new) 2021/06/01 05:58:12 INFO : yam.rclone_chunk.001_0j4qlb: Moved (server-side) to: yam 2021/06/01 05:58:12 INFO : yam: Copied (new) 2021/06/01 05:58:12 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:13 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 05:58:13 DEBUG : potato: Unchanged skipping 2021/06/01 05:58:13 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Making map for --track-renames 2021/06/01 05:58:13 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Finished making map for --track-renames 2021/06/01 05:58:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for renames to finish 2021/06/01 05:58:13 DEBUG : yam: move non-chunked object... 2021/06/01 05:58:14 INFO : yam: Moved (server-side) to: yaml 2021/06/01 05:58:14 INFO : yam: Moved (server-side) to: yaml 2021/06/01 05:58:14 INFO : yaml: Renamed from "yam" 2021/06/01 05:58:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:14 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:14 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (9.04s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1193: Can track renames: true 2021/06/01 05:58:17 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Making map for --track-renames 2021/06/01 05:58:17 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Finished making map for --track-renames 2021/06/01 05:58:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for renames to finish 2021/06/01 05:58:17 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:17 DEBUG : sub/yam: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:17 DEBUG : potato: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:22 INFO : sub/yam.rclone_chunk.001_0j4zi7: Moved (server-side) to: sub/yam 2021/06/01 05:58:22 INFO : sub/yam: Copied (new) 2021/06/01 05:58:22 INFO : potato.rclone_chunk.001_0j4zee: Moved (server-side) to: potato 2021/06/01 05:58:22 INFO : potato: Copied (new) 2021/06/01 05:58:22 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:24 DEBUG : potato: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 05:58:24 DEBUG : potato: Unchanged skipping 2021/06/01 05:58:24 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Making map for --track-renames 2021/06/01 05:58:24 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Finished making map for --track-renames 2021/06/01 05:58:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for renames to finish 2021/06/01 05:58:24 DEBUG : sub/yam: move non-chunked object... 2021/06/01 05:58:26 INFO : sub/yam: Moved (server-side) to: yam 2021/06/01 05:58:26 INFO : sub/yam: Moved (server-side) to: yam 2021/06/01 05:58:26 INFO : yam: Renamed from "sub/yam" 2021/06/01 05:58:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:26 DEBUG : Waiting for deletions to finish 2021/06/01 05:58:26 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (13.69s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:58:31 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:31 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:31 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:32 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:40 INFO : nested/sub dir/file.rclone_chunk.001_0j5eli: Moved (server-side) to: nested/sub dir/file 2021/06/01 05:58:40 INFO : nested/sub dir/file: Copied (new) 2021/06/01 05:58:40 INFO : nested/sub dir/file: Deleted 2021/06/01 05:58:40 INFO : sub dir/hello world.rclone_chunk.001_0j5ddr: Moved (server-side) to: sub dir/hello world 2021/06/01 05:58:40 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:58:40 INFO : sub dir/hello world: Deleted 2021/06/01 05:58:40 DEBUG : sub dir: Removing directory 2021/06/01 05:58:40 DEBUG : nested/sub dir: Removing directory 2021/06/01 05:58:40 DEBUG : nested: Removing directory 2021/06/01 05:58:40 DEBUG : Local file system at /tmp/rclone047265206: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (16.20s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:58:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 05:58:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 05:58:47 DEBUG : sub dir/hello world: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:48 DEBUG : nested/sub dir/file: skip slow MD5 on source file, hashing in-transit 2021/06/01 05:58:55 INFO : nested/sub dir/file.rclone_chunk.001_0j5uvm: Moved (server-side) to: nested/sub dir/file 2021/06/01 05:58:55 INFO : nested/sub dir/file: Copied (new) 2021/06/01 05:58:55 INFO : nested/sub dir/file: Deleted 2021/06/01 05:58:55 INFO : sub dir/hello world.rclone_chunk.001_0j5t1x: Moved (server-side) to: sub dir/hello world 2021/06/01 05:58:55 INFO : sub dir/hello world: Copied (new) 2021/06/01 05:58:55 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (15.78s) === RUN TestServerSideMove run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:59:02 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9" 2021/06/01 05:59:02 DEBUG : Config file has changed externaly - reloading 2021/06/01 05:59:02 DEBUG : Creating backend with remote "TestBox:rclone-test-bosewuq8qubayuz7meqojuh9" 2021/06/01 05:59:03 DEBUG : box root 'rclone-test-bosewuq8qubayuz7meqojuh9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:03 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:03 DEBUG : Reset feature "ListR" 2021/06/01 05:59:09 INFO : potato2.rclone_chunk.001_0j6aqe: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 05:59:09 INFO : potato2.rclone_chunk.002_0j6aqe: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 05:59:16 INFO : empty space.rclone_chunk.001_0j6i8y: Moved (server-side) to: empty space 2021/06/01 05:59:22 INFO : potato3.rclone_chunk.001_0j6msm: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 05:59:22 INFO : potato3.rclone_chunk.002_0j6msm: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7' -> Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9' 2021/06/01 05:59:25 DEBUG : box root 'rclone-test-bosewuq8qubayuz7meqojuh9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:29 INFO : empty space.rclone_chunk.001_0j6wop: Moved (server-side) to: empty space 2021/06/01 05:59:35 INFO : potato3.rclone_chunk.001_0j708r: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 05:59:36 INFO : potato3.rclone_chunk.002_0j708r: Moved (server-side) to: potato3.rclone_chunk.002 2021/06/01 05:59:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9': Using server-side directory move 2021/06/01 05:59:38 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/06/01 05:59:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2021/06/01 05:59:39 DEBUG : empty space: Unchanged skipping 2021/06/01 05:59:39 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 05:59:39 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9': Waiting for checks to finish 2021/06/01 05:59:40 DEBUG : potato2: move 2 data chunks... 2021/06/01 05:59:40 INFO : empty space: Deleted 2021/06/01 05:59:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9': Waiting for transfers to finish 2021/06/01 05:59:41 INFO : potato2.rclone_chunk.001: Moved (server-side) 2021/06/01 05:59:42 INFO : potato2.rclone_chunk.002: Moved (server-side) 2021/06/01 05:59:42 INFO : potato3: Deleted 2021/06/01 05:59:43 INFO : potato2: Moved (server-side) 2021/06/01 05:59:43 DEBUG : potato3: move 2 data chunks... 2021/06/01 05:59:44 INFO : potato3.rclone_chunk.001: Moved (server-side) 2021/06/01 05:59:44 INFO : potato2: Moved (server-side) 2021/06/01 05:59:45 INFO : potato3.rclone_chunk.002: Moved (server-side) 2021/06/01 05:59:46 INFO : potato3: Moved (server-side) 2021/06/01 05:59:48 INFO : potato3: Moved (server-side) 2021/06/01 05:59:48 INFO : There was nothing to transfer 2021/06/01 05:59:49 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-nubuqav9vepifip0bolovem2" 2021/06/01 05:59:49 DEBUG : Creating backend with remote "TestBox:rclone-test-nubuqav9vepifip0bolovem2" 2021/06/01 05:59:49 DEBUG : box root 'rclone-test-nubuqav9vepifip0bolovem2': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:50 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:50 DEBUG : Reset feature "ListR" 2021/06/01 05:59:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-nubuqav9vepifip0bolovem2': Using server-side directory move 2021/06/01 05:59:50 DEBUG : box root 'rclone-test-nubuqav9vepifip0bolovem2': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:51 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-nubuqav9vepifip0bolovem2': Server side directory move succeeded 2021/06/01 05:59:52 DEBUG : box root 'rclone-test-bosewuq8qubayuz7meqojuh9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:52 ERROR : : error listing: directory not found 2021/06/01 05:59:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-nubuqav9vepifip0bolovem2': Purge remote 2021/06/01 05:59:54 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-bosewuq8qubayuz7meqojuh9': Purge remote 2021/06/01 05:59:55 DEBUG : box root 'rclone-test-bosewuq8qubayuz7meqojuh9': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:55 purge failed: directory not found --- PASS: TestServerSideMove (53.30s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 05:59:56 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-batocob1gerumig6yegahih6" 2021/06/01 05:59:56 DEBUG : Creating backend with remote "TestBox:rclone-test-batocob1gerumig6yegahih6" 2021/06/01 05:59:56 DEBUG : box root 'rclone-test-batocob1gerumig6yegahih6': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:57 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 05:59:57 DEBUG : Reset feature "ListR" 2021/06/01 06:00:03 INFO : potato2.rclone_chunk.001_0j7rqt: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 06:00:03 INFO : potato2.rclone_chunk.002_0j7rqt: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 06:00:09 INFO : empty space.rclone_chunk.001_0j7zhk: Moved (server-side) to: empty space 2021/06/01 06:00:14 INFO : potato3.rclone_chunk.001_0j83r3: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 06:00:15 INFO : potato3.rclone_chunk.002_0j83r3: Moved (server-side) to: potato3.rclone_chunk.002 sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7' -> Chunked 'TestChunkerChunk50bBox:rclone-test-batocob1gerumig6yegahih6' 2021/06/01 06:00:18 DEBUG : box root 'rclone-test-batocob1gerumig6yegahih6': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:23 INFO : empty space.rclone_chunk.001_0j8d1h: Moved (server-side) to: empty space 2021/06/01 06:00:29 INFO : potato3.rclone_chunk.001_0j8huf: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 06:00:30 INFO : potato3.rclone_chunk.002_0j8huf: Moved (server-side) to: potato3.rclone_chunk.002 2021/06/01 06:00:33 DEBUG : empty space: Excluded 2021/06/01 06:00:33 DEBUG : empty space: Excluded 2021/06/01 06:00:33 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 06:00:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-batocob1gerumig6yegahih6': Waiting for checks to finish 2021/06/01 06:00:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-batocob1gerumig6yegahih6': Waiting for transfers to finish 2021/06/01 06:00:33 DEBUG : potato2: move 2 data chunks... 2021/06/01 06:00:35 INFO : potato2.rclone_chunk.001: Moved (server-side) 2021/06/01 06:00:36 INFO : potato2.rclone_chunk.002: Moved (server-side) 2021/06/01 06:00:36 INFO : potato3: Deleted 2021/06/01 06:00:37 INFO : potato2: Moved (server-side) 2021/06/01 06:00:37 DEBUG : potato3: move 2 data chunks... 2021/06/01 06:00:38 INFO : potato3.rclone_chunk.001: Moved (server-side) 2021/06/01 06:00:38 INFO : potato2: Moved (server-side) 2021/06/01 06:00:39 INFO : potato3.rclone_chunk.002: Moved (server-side) 2021/06/01 06:00:40 INFO : potato3: Moved (server-side) 2021/06/01 06:00:41 INFO : potato3: Moved (server-side) 2021/06/01 06:00:41 INFO : There was nothing to transfer 2021/06/01 06:00:42 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-sibahoz4gigiluj9yahagep3" 2021/06/01 06:00:42 DEBUG : Config file has changed externaly - reloading 2021/06/01 06:00:42 DEBUG : Creating backend with remote "TestBox:rclone-test-sibahoz4gigiluj9yahagep3" 2021/06/01 06:00:42 DEBUG : box root 'rclone-test-sibahoz4gigiluj9yahagep3': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:43 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:43 DEBUG : Reset feature "ListR" 2021/06/01 06:00:43 DEBUG : empty space: Excluded 2021/06/01 06:00:43 DEBUG : box root 'rclone-test-sibahoz4gigiluj9yahagep3': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:43 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-sibahoz4gigiluj9yahagep3': Waiting for checks to finish 2021/06/01 06:00:43 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-sibahoz4gigiluj9yahagep3': Waiting for transfers to finish 2021/06/01 06:00:44 DEBUG : potato2: move 2 data chunks... 2021/06/01 06:00:44 DEBUG : potato3: move 2 data chunks... 2021/06/01 06:00:44 DEBUG : box root 'rclone-test-sibahoz4gigiluj9yahagep3': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:46 INFO : potato3.rclone_chunk.001: Moved (server-side) 2021/06/01 06:00:46 INFO : potato2.rclone_chunk.001: Moved (server-side) 2021/06/01 06:00:47 INFO : potato3.rclone_chunk.002: Moved (server-side) 2021/06/01 06:00:48 INFO : potato2.rclone_chunk.002: Moved (server-side) 2021/06/01 06:00:48 INFO : potato3: Moved (server-side) 2021/06/01 06:00:49 INFO : potato2: Moved (server-side) 2021/06/01 06:00:50 INFO : potato3: Moved (server-side) 2021/06/01 06:00:51 INFO : potato2: Moved (server-side) 2021/06/01 06:00:51 INFO : There was nothing to transfer 2021/06/01 06:00:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-sibahoz4gigiluj9yahagep3': Purge remote 2021/06/01 06:00:54 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-batocob1gerumig6yegahih6': Purge remote --- PASS: TestServerSideMoveWithFilter (60.67s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:00:56 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5" 2021/06/01 06:00:56 DEBUG : Creating backend with remote "TestBox:rclone-test-genifoc8rewiris1vasijob5" 2021/06/01 06:00:57 DEBUG : box root 'rclone-test-genifoc8rewiris1vasijob5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:58 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:00:58 DEBUG : Reset feature "ListR" 2021/06/01 06:01:03 INFO : potato2.rclone_chunk.001_0j9gzw: Moved (server-side) to: potato2.rclone_chunk.001 2021/06/01 06:01:04 INFO : potato2.rclone_chunk.002_0j9gzw: Moved (server-side) to: potato2.rclone_chunk.002 2021/06/01 06:01:10 INFO : empty space.rclone_chunk.001_0j9oz4: Moved (server-side) to: empty space 2021/06/01 06:01:16 INFO : potato3.rclone_chunk.001_0j9sum: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 06:01:16 INFO : potato3.rclone_chunk.002_0j9sum: Moved (server-side) to: potato3.rclone_chunk.002 2021/06/01 06:01:18 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7' -> Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5' 2021/06/01 06:01:20 DEBUG : box root 'rclone-test-genifoc8rewiris1vasijob5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:25 INFO : empty space.rclone_chunk.001_0ja3eu: Moved (server-side) to: empty space 2021/06/01 06:01:30 INFO : potato3.rclone_chunk.001_0ja7q6: Moved (server-side) to: potato3.rclone_chunk.001 2021/06/01 06:01:31 INFO : potato3.rclone_chunk.002_0ja7q6: Moved (server-side) to: potato3.rclone_chunk.002 2021/06/01 06:01:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5': Using server-side directory move 2021/06/01 06:01:33 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/06/01 06:01:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1s) 2021/06/01 06:01:34 DEBUG : empty space: Unchanged skipping 2021/06/01 06:01:34 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/06/01 06:01:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5': Waiting for checks to finish 2021/06/01 06:01:35 DEBUG : potato2: move 2 data chunks... 2021/06/01 06:01:35 INFO : empty space: Deleted 2021/06/01 06:01:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5': Waiting for transfers to finish 2021/06/01 06:01:36 INFO : potato2.rclone_chunk.001: Moved (server-side) 2021/06/01 06:01:37 INFO : potato2.rclone_chunk.002: Moved (server-side) 2021/06/01 06:01:37 INFO : potato3: Deleted 2021/06/01 06:01:38 INFO : potato2: Moved (server-side) 2021/06/01 06:01:38 DEBUG : potato3: move 2 data chunks... 2021/06/01 06:01:39 INFO : potato2: Moved (server-side) 2021/06/01 06:01:39 INFO : potato3.rclone_chunk.001: Moved (server-side) 2021/06/01 06:01:40 INFO : potato3.rclone_chunk.002: Moved (server-side) 2021/06/01 06:01:42 INFO : potato3: Moved (server-side) 2021/06/01 06:01:43 INFO : potato3: Moved (server-side) 2021/06/01 06:01:43 DEBUG : tomatoDir: Removing directory 2021/06/01 06:01:44 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': deleted 1 directories 2021/06/01 06:01:44 INFO : There was nothing to transfer 2021/06/01 06:01:45 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-midonat2gurexom2yoxamum7" 2021/06/01 06:01:45 DEBUG : Creating backend with remote "TestBox:rclone-test-midonat2gurexom2yoxamum7" 2021/06/01 06:01:46 DEBUG : box root 'rclone-test-midonat2gurexom2yoxamum7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:46 DEBUG : box root '': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:46 DEBUG : Reset feature "ListR" 2021/06/01 06:01:46 DEBUG : tomatoDir: Making directory 2021/06/01 06:01:47 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-midonat2gurexom2yoxamum7': Using server-side directory move 2021/06/01 06:01:48 DEBUG : box root 'rclone-test-midonat2gurexom2yoxamum7': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:48 INFO : Chunked 'TestChunkerChunk50bBox:rclone-test-midonat2gurexom2yoxamum7': Server side directory move succeeded 2021/06/01 06:01:50 DEBUG : box root 'rclone-test-genifoc8rewiris1vasijob5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:50 ERROR : : error listing: directory not found 2021/06/01 06:01:50 DEBUG : box root 'rclone-test-genifoc8rewiris1vasijob5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:50 ERROR : : error listing: directory not found 2021/06/01 06:01:50 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-midonat2gurexom2yoxamum7': Purge remote 2021/06/01 06:01:52 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-genifoc8rewiris1vasijob5': Purge remote 2021/06/01 06:01:53 DEBUG : box root 'rclone-test-genifoc8rewiris1vasijob5': Ignoring "Shared folder — ForNick" - unknown type "web_link" 2021/06/01 06:01:53 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (57.39s) === RUN TestServerSideMoveOverlap run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1382: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.67s) === RUN TestSyncOverlap run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:01:54 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/rclone-sync-test" 2021/06/01 06:01:54 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/rclone-sync-test" 2021/06/01 06:01:56 DEBUG : Reset feature "ListR" --- PASS: TestSyncOverlap (2.23s) === RUN TestSyncCompareDest run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:01:57 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:01:57 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:01:58 DEBUG : Reset feature "ListR" 2021/06/01 06:01:58 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/CompareDest" 2021/06/01 06:01:58 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/CompareDest" 2021/06/01 06:02:00 DEBUG : Reset feature "ListR" 2021/06/01 06:02:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:01 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:01 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:02:05 INFO : one.rclone_chunk.001_0jb7sa: Moved (server-side) to: one 2021/06/01 06:02:05 INFO : one: Copied (new) 2021/06/01 06:02:05 DEBUG : Waiting for deletions to finish 2021/06/01 06:02:07 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:08 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:02:08 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:08 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:02:12 INFO : one: Deleted 2021/06/01 06:02:13 INFO : one.rclone_chunk.001_0jbexx: Moved (server-side) to: one 2021/06/01 06:02:13 INFO : one: Copied (replaced existing) 2021/06/01 06:02:13 DEBUG : Waiting for deletions to finish 2021/06/01 06:02:18 INFO : dst/one: Deleted 2021/06/01 06:02:19 INFO : dst/one.rclone_chunk.001_0jbkjo: Moved (server-side) to: dst/one 2021/06/01 06:02:23 INFO : CompareDest/one.rclone_chunk.001_0jbpy8: Moved (server-side) to: CompareDest/one 2021/06/01 06:02:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:26 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:26 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:02:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:26 DEBUG : Waiting for deletions to finish 2021/06/01 06:02:26 INFO : There was nothing to transfer 2021/06/01 06:02:30 INFO : CompareDest/two.rclone_chunk.001_0jbx2h: Moved (server-side) to: CompareDest/two 2021/06/01 06:02:32 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:32 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 06:02:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:33 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:33 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:02:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:33 DEBUG : Waiting for deletions to finish 2021/06/01 06:02:33 INFO : There was nothing to transfer 2021/06/01 06:02:35 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:35 DEBUG : two: Destination found in --compare-dest, skipping 2021/06/01 06:02:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:35 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:35 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:02:35 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:35 DEBUG : Waiting for deletions to finish 2021/06/01 06:02:35 INFO : There was nothing to transfer 2021/06/01 06:02:38 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/06/01 06:02:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:02:38 DEBUG : two: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:02:39 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:02:39 DEBUG : one: Destination found in --compare-dest, skipping 2021/06/01 06:02:39 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:02:42 INFO : two.rclone_chunk.001_0jc8if: Moved (server-side) to: two 2021/06/01 06:02:42 INFO : two: Copied (new) 2021/06/01 06:02:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (53.03s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:02:55 INFO : pre-dest1/1.rclone_chunk.001_0jck93: Moved (server-side) to: pre-dest1/1 2021/06/01 06:03:02 INFO : pre-dest2/2.rclone_chunk.001_0jcpng: Moved (server-side) to: pre-dest2/2 2021/06/01 06:03:03 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dest" 2021/06/01 06:03:03 DEBUG : Config file has changed externaly - reloading 2021/06/01 06:03:03 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dest" 2021/06/01 06:03:05 DEBUG : Reset feature "ListR" 2021/06/01 06:03:05 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/pre-dest1" 2021/06/01 06:03:05 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/pre-dest1" 2021/06/01 06:03:05 DEBUG : Reset feature "ListR" 2021/06/01 06:03:05 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/pre-dest2" 2021/06/01 06:03:05 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/pre-dest2" 2021/06/01 06:03:06 DEBUG : Reset feature "ListR" 2021/06/01 06:03:07 DEBUG : 1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:03:07 DEBUG : 1: Destination found in --compare-dest, skipping 2021/06/01 06:03:08 DEBUG : 2: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:03:08 DEBUG : 2: Destination found in --compare-dest, skipping 2021/06/01 06:03:09 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dest': Waiting for checks to finish 2021/06/01 06:03:09 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dest': Waiting for transfers to finish 2021/06/01 06:03:09 DEBUG : 3: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:03:14 INFO : 3.rclone_chunk.001_0jd3ts: Moved (server-side) to: 3 2021/06/01 06:03:14 INFO : 3: Copied (new) 2021/06/01 06:03:14 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (32.66s) === RUN TestSyncCopyDest run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:03:22 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:03:22 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:03:24 DEBUG : Reset feature "ListR" 2021/06/01 06:03:24 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/CopyDest" 2021/06/01 06:03:24 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/CopyDest" 2021/06/01 06:03:26 DEBUG : Reset feature "ListR" 2021/06/01 06:03:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:03:26 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:03:27 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:03:31 INFO : one.rclone_chunk.001_0jdl4f: Moved (server-side) to: one 2021/06/01 06:03:31 INFO : one: Copied (new) 2021/06/01 06:03:31 DEBUG : Waiting for deletions to finish 2021/06/01 06:03:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:03:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:03:33 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:03:33 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:03:38 INFO : one: Deleted 2021/06/01 06:03:39 INFO : one.rclone_chunk.001_0jdr56: Moved (server-side) to: one 2021/06/01 06:03:39 INFO : one: Copied (replaced existing) 2021/06/01 06:03:39 DEBUG : Waiting for deletions to finish 2021/06/01 06:03:44 INFO : dst/one: Deleted 2021/06/01 06:03:45 INFO : dst/one.rclone_chunk.001_0jdyy3: Moved (server-side) to: dst/one 2021/06/01 06:03:49 INFO : CopyDest/one.rclone_chunk.001_0je346: Moved (server-side) to: CopyDest/one 2021/06/01 06:03:50 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/BackupDir" 2021/06/01 06:03:50 DEBUG : Config file has changed externaly - reloading 2021/06/01 06:03:50 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/BackupDir" 2021/06/01 06:03:52 DEBUG : Reset feature "ListR" 2021/06/01 06:03:53 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:03:54 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:03:54 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/06/01 06:03:54 DEBUG : one: move non-chunked object... 2021/06/01 06:03:56 INFO : one: Moved (server-side) 2021/06/01 06:03:56 INFO : one: Moved (server-side) 2021/06/01 06:03:56 DEBUG : one: copy non-chunked object... 2021/06/01 06:03:57 INFO : one: Copied (server-side copy) 2021/06/01 06:03:57 DEBUG : one: Destination found in --copy-dest, using server-side copy 2021/06/01 06:03:57 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:03:57 DEBUG : Waiting for deletions to finish 2021/06/01 06:04:02 INFO : CopyDest/two.rclone_chunk.001_0jeg58: Moved (server-side) to: CopyDest/two 2021/06/01 06:04:04 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:04 DEBUG : two: copy non-chunked object... 2021/06/01 06:04:04 INFO : two: Copied (server-side copy) 2021/06/01 06:04:04 DEBUG : two: Destination found in --copy-dest, using server-side copy 2021/06/01 06:04:04 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:04:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:05 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:05 DEBUG : one: Unchanged skipping 2021/06/01 06:04:05 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:04:05 DEBUG : Waiting for deletions to finish 2021/06/01 06:04:06 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:04:07 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:07 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:07 DEBUG : two: Unchanged skipping 2021/06/01 06:04:07 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:07 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:07 DEBUG : one: Unchanged skipping 2021/06/01 06:04:07 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:04:07 DEBUG : Waiting for deletions to finish 2021/06/01 06:04:07 INFO : There was nothing to transfer 2021/06/01 06:04:11 INFO : CopyDest/three.rclone_chunk.001_0jeqvh: Moved (server-side) to: CopyDest/three 2021/06/01 06:04:13 DEBUG : three: Sizes differ (src 7 vs dst 5) 2021/06/01 06:04:13 DEBUG : three: Destination not found in --copy-dest 2021/06/01 06:04:13 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:04:14 DEBUG : three: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:04:14 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:14 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:14 DEBUG : one: Unchanged skipping 2021/06/01 06:04:14 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:14 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s) 2021/06/01 06:04:14 DEBUG : two: Unchanged skipping 2021/06/01 06:04:14 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:04:17 INFO : three.rclone_chunk.001_0jewkr: Moved (server-side) to: three 2021/06/01 06:04:17 INFO : three: Copied (new) 2021/06/01 06:04:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (66.43s) === RUN TestSyncBackupDir run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:04:34 INFO : dst/one.rclone_chunk.001_0jfb2r: Moved (server-side) to: dst/one 2021/06/01 06:04:38 INFO : dst/two.rclone_chunk.001_0jfg9a: Moved (server-side) to: dst/two 2021/06/01 06:04:42 INFO : dst/three.txt.rclone_chunk.001_0jfk5f: Moved (server-side) to: dst/three.txt 2021/06/01 06:04:42 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:04:42 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:04:43 DEBUG : Reset feature "ListR" 2021/06/01 06:04:43 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:04:43 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:04:45 DEBUG : Reset feature "ListR" 2021/06/01 06:04:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:04:45 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:04:45 DEBUG : two: Unchanged skipping 2021/06/01 06:04:45 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:04:46 DEBUG : one: move non-chunked object... 2021/06/01 06:04:48 INFO : one: Moved (server-side) 2021/06/01 06:04:48 INFO : one: Moved (server-side) 2021/06/01 06:04:48 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:04:48 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:04:52 INFO : one.rclone_chunk.001_0jfuk8: Moved (server-side) to: one 2021/06/01 06:04:52 INFO : one: Copied (new) 2021/06/01 06:04:52 DEBUG : Waiting for deletions to finish 2021/06/01 06:04:52 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:04:53 INFO : three.txt: Moved (server-side) 2021/06/01 06:04:53 INFO : three.txt: Moved (server-side) 2021/06/01 06:04:53 INFO : three.txt: Moved into backup dir 2021/06/01 06:04:58 INFO : dst/three.txt.rclone_chunk.001_0jg0d3: Moved (server-side) to: dst/three.txt 2021/06/01 06:05:00 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:05:00 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:05:00 DEBUG : two: Unchanged skipping 2021/06/01 06:05:00 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:05:01 INFO : one: Deleted 2021/06/01 06:05:01 DEBUG : one: move non-chunked object... 2021/06/01 06:05:02 INFO : one: Moved (server-side) 2021/06/01 06:05:02 INFO : one: Moved (server-side) 2021/06/01 06:05:02 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:05:03 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:05:06 INFO : one.rclone_chunk.001_0jg9pv: Moved (server-side) to: one 2021/06/01 06:05:06 INFO : one: Copied (new) 2021/06/01 06:05:06 DEBUG : Waiting for deletions to finish 2021/06/01 06:05:08 INFO : three.txt: Deleted 2021/06/01 06:05:08 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:05:09 INFO : three.txt: Moved (server-side) 2021/06/01 06:05:09 INFO : three.txt: Moved (server-side) 2021/06/01 06:05:09 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (47.95s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:05:22 INFO : dst/one.rclone_chunk.001_0jgnab: Moved (server-side) to: dst/one 2021/06/01 06:05:27 INFO : dst/two.rclone_chunk.001_0jgsv4: Moved (server-side) to: dst/two 2021/06/01 06:05:31 INFO : dst/three.txt.rclone_chunk.001_0jgxe3: Moved (server-side) to: dst/three.txt 2021/06/01 06:05:31 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:05:31 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:05:32 DEBUG : Reset feature "ListR" 2021/06/01 06:05:32 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:05:32 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:05:34 DEBUG : Reset feature "ListR" 2021/06/01 06:05:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:05:34 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:05:34 DEBUG : two: Unchanged skipping 2021/06/01 06:05:34 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:05:34 DEBUG : one: move non-chunked object... 2021/06/01 06:05:37 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:05:37 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:05:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:05:37 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:05:41 INFO : one.rclone_chunk.001_0jh70m: Moved (server-side) to: one 2021/06/01 06:05:41 INFO : one: Copied (new) 2021/06/01 06:05:41 DEBUG : Waiting for deletions to finish 2021/06/01 06:05:41 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:05:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:05:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:05:43 INFO : three.txt: Moved into backup dir 2021/06/01 06:05:47 INFO : dst/three.txt.rclone_chunk.001_0jhe6p: Moved (server-side) to: dst/three.txt 2021/06/01 06:05:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:05:48 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:05:48 DEBUG : two: Unchanged skipping 2021/06/01 06:05:48 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:05:50 INFO : one.bak: Deleted 2021/06/01 06:05:50 DEBUG : one: move non-chunked object... 2021/06/01 06:05:51 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:05:51 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:05:51 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:05:52 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:05:55 INFO : one.rclone_chunk.001_0jhmcq: Moved (server-side) to: one 2021/06/01 06:05:55 INFO : one: Copied (new) 2021/06/01 06:05:55 DEBUG : Waiting for deletions to finish 2021/06/01 06:05:57 INFO : three.txt.bak: Deleted 2021/06/01 06:05:57 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:05:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:05:58 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:05:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (48.93s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:06:11 INFO : dst/one.rclone_chunk.001_0ji0zo: Moved (server-side) to: dst/one 2021/06/01 06:06:15 INFO : dst/two.rclone_chunk.001_0ji5y1: Moved (server-side) to: dst/two 2021/06/01 06:06:19 INFO : dst/three.txt.rclone_chunk.001_0ji9tq: Moved (server-side) to: dst/three.txt 2021/06/01 06:06:19 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:06:19 DEBUG : Config file has changed externaly - reloading 2021/06/01 06:06:19 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:06:20 DEBUG : Reset feature "ListR" 2021/06/01 06:06:20 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:06:20 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/backup" 2021/06/01 06:06:22 DEBUG : Reset feature "ListR" 2021/06/01 06:06:22 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:06:22 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:06:22 DEBUG : two: Unchanged skipping 2021/06/01 06:06:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:06:23 DEBUG : one: move non-chunked object... 2021/06/01 06:06:25 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:06:25 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:06:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:06:25 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:06:29 INFO : one.rclone_chunk.001_0jijal: Moved (server-side) to: one 2021/06/01 06:06:29 INFO : one: Copied (new) 2021/06/01 06:06:29 DEBUG : Waiting for deletions to finish 2021/06/01 06:06:29 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:06:31 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:06:31 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:06:31 INFO : three.txt: Moved into backup dir 2021/06/01 06:06:35 INFO : dst/three.txt.rclone_chunk.001_0jiqon: Moved (server-side) to: dst/three.txt 2021/06/01 06:06:38 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:06:38 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:06:38 DEBUG : two: Unchanged skipping 2021/06/01 06:06:38 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:06:39 INFO : one-2019-01-01: Deleted 2021/06/01 06:06:39 DEBUG : one: move non-chunked object... 2021/06/01 06:06:40 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:06:40 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:06:40 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:06:40 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:06:45 INFO : one.rclone_chunk.001_0jiy7o: Moved (server-side) to: one 2021/06/01 06:06:45 INFO : one: Copied (new) 2021/06/01 06:06:45 DEBUG : Waiting for deletions to finish 2021/06/01 06:06:46 INFO : three-2019-01-01.txt: Deleted 2021/06/01 06:06:46 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:06:47 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:06:47 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:06:47 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (48.95s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:07:00 INFO : dst/one.rclone_chunk.001_0jjdx2: Moved (server-side) to: dst/one 2021/06/01 06:07:04 INFO : dst/two.rclone_chunk.001_0jjio8: Moved (server-side) to: dst/two 2021/06/01 06:07:08 INFO : dst/three.txt.rclone_chunk.001_0jjmgl: Moved (server-side) to: dst/three.txt 2021/06/01 06:07:09 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:07:09 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:07:10 DEBUG : Reset feature "ListR" 2021/06/01 06:07:10 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:07:10 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:07:10 DEBUG : two: Unchanged skipping 2021/06/01 06:07:10 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:07:10 DEBUG : one: move non-chunked object... 2021/06/01 06:07:11 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:11 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:11 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:07:11 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:07:15 INFO : one.rclone_chunk.001_0jjthy: Moved (server-side) to: one 2021/06/01 06:07:15 INFO : one: Copied (new) 2021/06/01 06:07:15 DEBUG : Waiting for deletions to finish 2021/06/01 06:07:15 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:07:16 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:07:16 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:07:16 INFO : three.txt: Moved into backup dir 2021/06/01 06:07:21 INFO : dst/three.txt.rclone_chunk.001_0jjzye: Moved (server-side) to: dst/three.txt 2021/06/01 06:07:22 DEBUG : one.bak: Excluded 2021/06/01 06:07:22 DEBUG : three.txt.bak: Excluded 2021/06/01 06:07:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:07:22 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for checks to finish 2021/06/01 06:07:22 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:07:22 DEBUG : two: Unchanged skipping 2021/06/01 06:07:24 INFO : one.bak: Deleted 2021/06/01 06:07:24 DEBUG : one: move non-chunked object... 2021/06/01 06:07:24 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:24 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:24 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst': Waiting for transfers to finish 2021/06/01 06:07:24 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:07:28 INFO : one.rclone_chunk.001_0jk6sp: Moved (server-side) to: one 2021/06/01 06:07:28 INFO : one: Copied (new) 2021/06/01 06:07:28 DEBUG : Waiting for deletions to finish 2021/06/01 06:07:30 INFO : three.txt.bak: Deleted 2021/06/01 06:07:30 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:07:31 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:07:31 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:07:31 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (42.56s) === RUN TestSyncSuffix run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:07:42 INFO : dst/one.rclone_chunk.001_0jkky1: Moved (server-side) to: dst/one 2021/06/01 06:07:47 INFO : dst/two.rclone_chunk.001_0jkpbp: Moved (server-side) to: dst/two 2021/06/01 06:07:51 INFO : dst/three.txt.rclone_chunk.001_0jktrv: Moved (server-side) to: dst/three.txt 2021/06/01 06:07:51 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:07:51 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:07:52 DEBUG : Reset feature "ListR" 2021/06/01 06:07:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:07:53 DEBUG : one: move non-chunked object... 2021/06/01 06:07:54 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:54 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:07:54 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:07:58 INFO : one.rclone_chunk.001_0jl046: Moved (server-side) to: one 2021/06/01 06:07:58 INFO : one: Copied (new) 2021/06/01 06:07:59 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:07:59 DEBUG : two: Unchanged skipping 2021/06/01 06:08:00 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:08:00 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:08:01 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:08:01 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:08:01 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:08:04 INFO : three.txt.rclone_chunk.001_0jl7mz: Moved (server-side) to: three.txt 2021/06/01 06:08:04 INFO : three.txt: Copied (new) 2021/06/01 06:08:08 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:08:09 INFO : one.bak: Deleted 2021/06/01 06:08:09 DEBUG : one: move non-chunked object... 2021/06/01 06:08:10 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:08:10 INFO : one: Moved (server-side) to: one.bak 2021/06/01 06:08:10 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:08:14 INFO : one.rclone_chunk.001_0jlgwq: Moved (server-side) to: one 2021/06/01 06:08:14 INFO : one: Copied (new) 2021/06/01 06:08:15 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:08:15 DEBUG : two: Unchanged skipping 2021/06/01 06:08:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:08:17 INFO : three.txt.bak: Deleted 2021/06/01 06:08:17 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:08:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:08:18 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/06/01 06:08:18 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:08:22 INFO : three.txt.rclone_chunk.001_0jlo6y: Moved (server-side) to: three.txt 2021/06/01 06:08:22 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (51.97s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:08:34 INFO : dst/one.rclone_chunk.001_0jlzud: Moved (server-side) to: dst/one 2021/06/01 06:08:38 INFO : dst/two.rclone_chunk.001_0jm4sh: Moved (server-side) to: dst/two 2021/06/01 06:08:42 INFO : dst/three.txt.rclone_chunk.001_0jm8zx: Moved (server-side) to: dst/three.txt 2021/06/01 06:08:43 DEBUG : Creating backend with remote "TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:08:43 DEBUG : Creating backend with remote "TestBox:rclone-test-noyibol9noqucoh2yeyoxax7/dst" 2021/06/01 06:08:43 DEBUG : Reset feature "ListR" 2021/06/01 06:08:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/06/01 06:08:44 DEBUG : one: move non-chunked object... 2021/06/01 06:08:45 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:08:45 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:08:45 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:08:49 INFO : one.rclone_chunk.001_0jmfhn: Moved (server-side) to: one 2021/06/01 06:08:49 INFO : one: Copied (new) 2021/06/01 06:08:50 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:08:50 DEBUG : two: Unchanged skipping 2021/06/01 06:08:51 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/06/01 06:08:51 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:08:52 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:08:52 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:08:52 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:08:56 INFO : three.txt.rclone_chunk.001_0jmman: Moved (server-side) to: three.txt 2021/06/01 06:08:56 INFO : three.txt: Copied (new) 2021/06/01 06:08:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/06/01 06:09:00 INFO : one-2019-01-01: Deleted 2021/06/01 06:09:00 DEBUG : one: move non-chunked object... 2021/06/01 06:09:00 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:09:00 INFO : one: Moved (server-side) to: one-2019-01-01 2021/06/01 06:09:01 DEBUG : one: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:09:04 INFO : one.rclone_chunk.001_0jmvhj: Moved (server-side) to: one 2021/06/01 06:09:04 INFO : one: Copied (new) 2021/06/01 06:09:05 DEBUG : two: Size and modification time the same (differ by -499.999999ms, within tolerance 1s) 2021/06/01 06:09:05 DEBUG : two: Unchanged skipping 2021/06/01 06:09:06 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/06/01 06:09:08 INFO : three-2019-01-01.txt: Deleted 2021/06/01 06:09:08 DEBUG : three.txt: move non-chunked object... 2021/06/01 06:09:08 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:09:08 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/06/01 06:09:08 DEBUG : three.txt: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:09:12 INFO : three.txt.rclone_chunk.001_0jn26b: Moved (server-side) to: three.txt 2021/06/01 06:09:12 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (50.86s) === RUN TestSyncUTFNorm run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:09:24 INFO : Testêé.rclone_chunk.001_0jneyo: Moved (server-side) to: Testêé 2021/06/01 06:09:25 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/06/01 06:09:25 DEBUG : Testêé: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:09:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 06:09:25 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 06:09:29 INFO : Testêé: Deleted 2021/06/01 06:09:30 INFO : Testêé.rclone_chunk.001_0jnj73: Moved (server-side) to: Testêé 2021/06/01 06:09:30 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/06/01 06:09:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (11.50s) === RUN TestSyncImmutable run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" 2021/06/01 06:09:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 06:09:32 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 06:09:33 DEBUG : existing: skip slow MD5 on source file, hashing in-transit 2021/06/01 06:09:36 INFO : existing.rclone_chunk.001_0jnrl8: Moved (server-side) to: existing 2021/06/01 06:09:36 INFO : existing: Copied (new) 2021/06/01 06:09:36 DEBUG : Waiting for deletions to finish 2021/06/01 06:09:37 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/06/01 06:09:37 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/06/01 06:09:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for checks to finish 2021/06/01 06:09:37 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Waiting for transfers to finish 2021/06/01 06:09:37 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': not deleting files as there were IO errors 2021/06/01 06:09:37 ERROR : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (7.46s) === RUN TestSyncIgnoreCase run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1907: Skipping test as local or remote are case-insensitive --- SKIP: TestSyncIgnoreCase (0.66s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7'", Local "Local file system at /tmp/rclone047265206", Modify Window "1s" sync_test.go:1941: This test only runs on local --- PASS: TestMaxTransfer (1.95s) --- SKIP: TestMaxTransfer/Hard (0.65s) --- SKIP: TestMaxTransfer/Soft (0.66s) --- SKIP: TestMaxTransfer/Cautious (0.63s) PASS 2021/06/01 06:09:42 DEBUG : Chunked 'TestChunkerChunk50bBox:rclone-test-noyibol9noqucoh2yeyoxax7': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerChunk50bBox: -verbose -size-limit 1024" - Finished OK in 20m27.613159345s (try 1/5)