"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerOverCrypt: -verbose -size-limit 6144" - Starting (try 1/5) 2021/03/04 05:13:38 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0" 2021/03/04 05:13:38 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0" 2021/03/04 05:13:38 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta" 2021/03/04 05:13:38 DEBUG : Reset feature "ListR" 2021/03/04 05:13:38 DEBUG : Creating backend with remote "/tmp/rclone486996259" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.10s) === 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 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.01s) === RUN TestCopy run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:38 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.001_fnpl0k: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.002_fnpl0k: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.003_fnpl0k: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.004_fnpl0k: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (0.02s) === RUN TestCopyMissingDirectory run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 DEBUG : Creating backend with remote "/non-existing" 2021/03/04 05:13:38 ERROR : : error reading source directory: directory not found 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.00s) === RUN TestCopyNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.001_fnpliz: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.002_fnpliz: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.003_fnpliz: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.004_fnpliz: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.01s) === RUN TestCopyCheckFirst run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Running all checks before starting transfers 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Checks finished, now starting transfers 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:38 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.001_fnpldo: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.002_fnpldo: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.003_fnpldo: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.004_fnpldo: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:38 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.02s) === RUN TestSyncNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 ERROR : Ignoring --no-traverse with sync 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:38 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.001_fnplyj: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.002_fnplyj: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.003_fnplyj: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:38 INFO : sub dir/hello world.rcc.004_fnplyj: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:38 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.02s) === RUN TestCopyWithDepth run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:38 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:38 DEBUG : hello world2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : hello world2.rcc.001_fnploa: Moved (server-side) to: hello world2.rcc.001 2021/03/04 05:13:39 INFO : hello world2.rcc.002_fnploa: Moved (server-side) to: hello world2.rcc.002 2021/03/04 05:13:39 INFO : hello world2.rcc.003_fnploa: Moved (server-side) to: hello world2.rcc.003 2021/03/04 05:13:39 INFO : hello world2.rcc.004_fnploa: Moved (server-side) to: hello world2.rcc.004 2021/03/04 05:13:39 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.04s) === RUN TestCopyWithFilesFrom run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : hello world2: Excluded 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmoc: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmoc: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmoc: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmoc: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.01s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmei: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmei: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmei: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmei: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.01s) === RUN TestCopyEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmwh: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmwh: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmwh: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmwh: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': copied 1 directories --- PASS: TestCopyEmptyDirectories (0.00s) === RUN TestMoveEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmn0: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmn0: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmn0: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmn0: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:39 INFO : sub dir/hello world: Deleted 2021/03/04 05:13:39 DEBUG : sub dir: Making directory 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': copied 2 directories --- PASS: TestMoveEmptyDirectories (0.01s) === RUN TestSyncEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmzn: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmzn: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmzn: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmzn: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:39 DEBUG : sub dir2: Making directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': copied 1 directories 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (0.01s) === RUN TestServerSideCopy run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpm7w: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpm7w: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpm7w: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpm7w: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0" 2021/03/04 05:13:39 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0" 2021/03/04 05:13:39 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/ro4dln6psgdub8f1v5pfuilpui9ib1heseokrbk8fqtdl085agfh0bohu0qaeh3sph4ei955b0mee" 2021/03/04 05:13:39 DEBUG : Reset feature "ListR" sync_test.go:292: Server side copy (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0' -> Chunked 'TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0' 2021/03/04 05:13:39 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmax: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmax: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmax: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmax: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-cuhenin4damaqar3buzorah0': Purge remote --- PASS: TestServerSideCopy (0.14s) === RUN TestCopyAfterDelete run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmfg: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmfg: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmfg: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmfg: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 ERROR : : error listing: directory not found 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Making directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (0.01s) === RUN TestCopyRedownload run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.001_fnpmg0: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.002_fnpmg0: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.003_fnpmg0: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:39 INFO : sub dir/hello world.rcc.004_fnpmg0: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:39 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for transfers to finish --- PASS: TestCopyRedownload (0.01s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : check sum: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : check sum.rcc.001_fnpm2x: Moved (server-side) to: check sum 2021/03/04 05:13:39 INFO : check sum: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 NOTICE: Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2021/03/04 05:13:39 DEBUG : check sum: Size of src and dst objects identical 2021/03/04 05:13:39 DEBUG : check sum: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (0.01s) === RUN TestSyncSizeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : sizeonly: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : sizeonly.rcc.001_fnpmo4: Moved (server-side) to: sizeonly.rcc.001 2021/03/04 05:13:39 INFO : sizeonly.rcc.002_fnpmo4: Moved (server-side) to: sizeonly.rcc.002 2021/03/04 05:13:39 INFO : sizeonly: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : sizeonly: Sizes identical 2021/03/04 05:13:39 DEBUG : sizeonly: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (0.01s) === RUN TestSyncIgnoreSize run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : ignore-size: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : ignore-size.rcc.001_fnpmib: Moved (server-side) to: ignore-size.rcc.001 2021/03/04 05:13:39 INFO : ignore-size.rcc.002_fnpmib: Moved (server-side) to: ignore-size.rcc.002 2021/03/04 05:13:39 INFO : ignore-size.rcc.003_fnpmib: Moved (server-side) to: ignore-size.rcc.003 2021/03/04 05:13:39 INFO : ignore-size: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : ignore-size: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (0.01s) === RUN TestSyncIgnoreTimes run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : existing.rcc.001_fnpm50: Moved (server-side) to: existing.rcc.001 2021/03/04 05:13:39 INFO : existing.rcc.002_fnpm50: Moved (server-side) to: existing.rcc.002 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : existing: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer 2021/03/04 05:13:39 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/03/04 05:13:39 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : existing.rcc.001_fnpmmw: Moved (server-side) to: existing.rcc.001 2021/03/04 05:13:39 INFO : existing.rcc.002_fnpmmw: Moved (server-side) to: existing.rcc.002 2021/03/04 05:13:39 INFO : existing: Copied (replaced existing) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (0.01s) === RUN TestSyncIgnoreExisting run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : existing.rcc.001_fnpmkm: Moved (server-side) to: existing.rcc.001 2021/03/04 05:13:39 INFO : existing.rcc.002_fnpmkm: Moved (server-side) to: existing.rcc.002 2021/03/04 05:13:39 INFO : existing: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : existing: Destination exists, skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.01s) === RUN TestSyncIgnoreErrors run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : b/potato.rcc.001_fnpmd9: Moved (server-side) to: b/potato.rcc.001 2021/03/04 05:13:39 INFO : b/potato.rcc.002_fnpmd9: Moved (server-side) to: b/potato.rcc.002 2021/03/04 05:13:39 INFO : b/potato.rcc.003_fnpmd9: Moved (server-side) to: b/potato.rcc.003 2021/03/04 05:13:39 INFO : b/potato.rcc.004_fnpmd9: Moved (server-side) to: b/potato.rcc.004 2021/03/04 05:13:39 INFO : b/potato.rcc.005_fnpmd9: Moved (server-side) to: b/potato.rcc.005 2021/03/04 05:13:39 INFO : b/potato.rcc.006_fnpmd9: Moved (server-side) to: b/potato.rcc.006 2021/03/04 05:13:39 INFO : b/potato.rcc.007_fnpmd9: Moved (server-side) to: b/potato.rcc.007 2021/03/04 05:13:39 INFO : c/non empty space.rcc.001_fnpm19: Moved (server-side) to: c/non empty space.rcc.001 2021/03/04 05:13:39 INFO : c/non empty space.rcc.002_fnpm19: Moved (server-side) to: c/non empty space.rcc.002 2021/03/04 05:13:39 DEBUG : d: Making directory 2021/03/04 05:13:39 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : c/non empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : a/potato2.rcc.001_fnpmjf: Moved (server-side) to: a/potato2.rcc.001 2021/03/04 05:13:39 INFO : a/potato2.rcc.002_fnpmjf: Moved (server-side) to: a/potato2.rcc.002 2021/03/04 05:13:39 INFO : a/potato2.rcc.003_fnpmjf: Moved (server-side) to: a/potato2.rcc.003 2021/03/04 05:13:39 INFO : a/potato2.rcc.004_fnpmjf: Moved (server-side) to: a/potato2.rcc.004 2021/03/04 05:13:39 INFO : a/potato2.rcc.005_fnpmjf: Moved (server-side) to: a/potato2.rcc.005 2021/03/04 05:13:39 INFO : a/potato2.rcc.006_fnpmjf: Moved (server-side) to: a/potato2.rcc.006 2021/03/04 05:13:39 INFO : a/potato2.rcc.007_fnpmjf: Moved (server-side) to: a/potato2.rcc.007 2021/03/04 05:13:39 INFO : a/potato2.rcc.008_fnpmjf: Moved (server-side) to: a/potato2.rcc.008 2021/03/04 05:13:39 INFO : a/potato2.rcc.009_fnpmjf: Moved (server-side) to: a/potato2.rcc.009 2021/03/04 05:13:39 INFO : a/potato2.rcc.010_fnpmjf: Moved (server-side) to: a/potato2.rcc.010 2021/03/04 05:13:39 INFO : a/potato2.rcc.011_fnpmjf: Moved (server-side) to: a/potato2.rcc.011 2021/03/04 05:13:39 INFO : a/potato2.rcc.012_fnpmjf: Moved (server-side) to: a/potato2.rcc.012 2021/03/04 05:13:39 INFO : a/potato2.rcc.013_fnpmjf: Moved (server-side) to: a/potato2.rcc.013 2021/03/04 05:13:39 INFO : a/potato2.rcc.014_fnpmjf: Moved (server-side) to: a/potato2.rcc.014 2021/03/04 05:13:39 INFO : a/potato2.rcc.015_fnpmjf: Moved (server-side) to: a/potato2.rcc.015 2021/03/04 05:13:39 INFO : a/potato2.rcc.016_fnpmjf: Moved (server-side) to: a/potato2.rcc.016 2021/03/04 05:13:39 INFO : a/potato2.rcc.017_fnpmjf: Moved (server-side) to: a/potato2.rcc.017 2021/03/04 05:13:39 INFO : a/potato2.rcc.018_fnpmjf: Moved (server-side) to: a/potato2.rcc.018 2021/03/04 05:13:39 INFO : a/potato2.rcc.019_fnpmjf: Moved (server-side) to: a/potato2.rcc.019 2021/03/04 05:13:39 INFO : a/potato2.rcc.020_fnpmjf: Moved (server-side) to: a/potato2.rcc.020 2021/03/04 05:13:39 INFO : a/potato2: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : b/potato: Deleted 2021/03/04 05:13:39 DEBUG : d: Removing directory 2021/03/04 05:13:39 DEBUG : b: Removing directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': deleted 2 directories --- PASS: TestSyncIgnoreErrors (0.05s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpm8b: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/03/04 05:13:39 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : empty space: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : empty space: Deleted 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpmel: Moved (server-side) to: empty space 2021/03/04 05:13:39 INFO : empty space: Copied (replaced existing) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (0.02s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:611: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.00s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : foo.rcc.001_fnpm9d: Moved (server-side) to: foo 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : foo: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : foo: Deleted 2021/03/04 05:13:39 INFO : foo.rcc.001_fnpmrc: Moved (server-side) to: foo 2021/03/04 05:13:39 INFO : foo: Copied (replaced existing) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.01s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpmz8: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmro: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmro: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmro: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmro: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmro: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmro: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmro: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : potato.rcc.008_fnpmro: Moved (server-side) to: potato.rcc.008 2021/03/04 05:13:39 INFO : potato.rcc.009_fnpmro: Moved (server-side) to: potato.rcc.009 2021/03/04 05:13:39 INFO : potato.rcc.010_fnpmro: Moved (server-side) to: potato.rcc.010 2021/03/04 05:13:39 INFO : potato.rcc.011_fnpmro: Moved (server-side) to: potato.rcc.011 2021/03/04 05:13:39 INFO : potato.rcc.012_fnpmro: Moved (server-side) to: potato.rcc.012 2021/03/04 05:13:39 INFO : potato.rcc.013_fnpmro: Moved (server-side) to: potato.rcc.013 2021/03/04 05:13:39 INFO : potato.rcc.014_fnpmro: Moved (server-side) to: potato.rcc.014 2021/03/04 05:13:39 INFO : potato.rcc.015_fnpmro: Moved (server-side) to: potato.rcc.015 2021/03/04 05:13:39 INFO : potato.rcc.016_fnpmro: Moved (server-side) to: potato.rcc.016 2021/03/04 05:13:39 INFO : potato.rcc.017_fnpmro: Moved (server-side) to: potato.rcc.017 2021/03/04 05:13:39 INFO : potato.rcc.018_fnpmro: Moved (server-side) to: potato.rcc.018 2021/03/04 05:13:39 INFO : potato.rcc.019_fnpmro: Moved (server-side) to: potato.rcc.019 2021/03/04 05:13:39 INFO : potato.rcc.020_fnpmro: Moved (server-side) to: potato.rcc.020 2021/03/04 05:13:39 INFO : potato: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (0.04s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpm1m: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpm1m: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpm1m: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpm1m: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpm1m: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpm1m: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpm1m: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : potato.rcc.008_fnpm1m: Moved (server-side) to: potato.rcc.008 2021/03/04 05:13:39 INFO : potato.rcc.009_fnpm1m: Moved (server-side) to: potato.rcc.009 2021/03/04 05:13:39 INFO : potato.rcc.010_fnpm1m: Moved (server-side) to: potato.rcc.010 2021/03/04 05:13:39 INFO : potato.rcc.011_fnpm1m: Moved (server-side) to: potato.rcc.011 2021/03/04 05:13:39 INFO : potato.rcc.012_fnpm1m: Moved (server-side) to: potato.rcc.012 2021/03/04 05:13:39 INFO : potato.rcc.013_fnpm1m: Moved (server-side) to: potato.rcc.013 2021/03/04 05:13:39 INFO : potato.rcc.014_fnpm1m: Moved (server-side) to: potato.rcc.014 2021/03/04 05:13:39 INFO : potato.rcc.015_fnpm1m: Moved (server-side) to: potato.rcc.015 2021/03/04 05:13:39 INFO : potato.rcc.016_fnpm1m: Moved (server-side) to: potato.rcc.016 2021/03/04 05:13:39 INFO : potato.rcc.017_fnpm1m: Moved (server-side) to: potato.rcc.017 2021/03/04 05:13:39 INFO : potato.rcc.018_fnpm1m: Moved (server-side) to: potato.rcc.018 2021/03/04 05:13:39 INFO : potato.rcc.019_fnpm1m: Moved (server-side) to: potato.rcc.019 2021/03/04 05:13:39 INFO : potato.rcc.020_fnpm1m: Moved (server-side) to: potato.rcc.020 2021/03/04 05:13:39 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpml8: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpml8: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpml8: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpml8: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpml8: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpml8: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpml8: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : potato: Copied (replaced existing) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.06s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmkq: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmkq: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmkq: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmkq: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmkq: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmkq: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmkq: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpm0r: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpm0r: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpm0r: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpm0r: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpm0r: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpm0r: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpm0r: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : potato: Copied (replaced existing) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.03s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmun: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmun: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmun: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmun: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmun: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmun: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmun: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpmg3: Moved (server-side) to: empty space 2021/03/04 05:13:39 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (0.02s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmhd: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmhd: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmhd: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmhd: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmhd: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmhd: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmhd: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpm6u: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmx9: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmx9: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmx9: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmx9: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpmx9: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpmx9: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpmx9: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2.rcc.008_fnpmx9: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:39 INFO : potato2.rcc.009_fnpmx9: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:39 INFO : potato2.rcc.010_fnpmx9: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:39 INFO : potato2.rcc.011_fnpmx9: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:39 INFO : potato2.rcc.012_fnpmx9: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:39 INFO : potato2.rcc.013_fnpmx9: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:39 INFO : potato2.rcc.014_fnpmx9: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:39 INFO : potato2.rcc.015_fnpmx9: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:39 INFO : potato2.rcc.016_fnpmx9: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:39 INFO : potato2.rcc.017_fnpmx9: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:39 INFO : potato2.rcc.018_fnpmx9: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:39 INFO : potato2.rcc.019_fnpmx9: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:39 INFO : potato2.rcc.020_fnpmx9: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:39 INFO : potato2: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (0.06s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : b/potato.rcc.001_fnpmbh: Moved (server-side) to: b/potato.rcc.001 2021/03/04 05:13:39 INFO : b/potato.rcc.002_fnpmbh: Moved (server-side) to: b/potato.rcc.002 2021/03/04 05:13:39 INFO : b/potato.rcc.003_fnpmbh: Moved (server-side) to: b/potato.rcc.003 2021/03/04 05:13:39 INFO : b/potato.rcc.004_fnpmbh: Moved (server-side) to: b/potato.rcc.004 2021/03/04 05:13:39 INFO : b/potato.rcc.005_fnpmbh: Moved (server-side) to: b/potato.rcc.005 2021/03/04 05:13:39 INFO : b/potato.rcc.006_fnpmbh: Moved (server-side) to: b/potato.rcc.006 2021/03/04 05:13:39 INFO : b/potato.rcc.007_fnpmbh: Moved (server-side) to: b/potato.rcc.007 2021/03/04 05:13:39 INFO : c/non empty space.rcc.001_fnpme0: Moved (server-side) to: c/non empty space.rcc.001 2021/03/04 05:13:39 INFO : c/non empty space.rcc.002_fnpme0: Moved (server-side) to: c/non empty space.rcc.002 2021/03/04 05:13:39 DEBUG : d: Making directory 2021/03/04 05:13:39 DEBUG : d/e: Making directory 2021/03/04 05:13:39 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : c/non empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : a/potato2.rcc.001_fnpmnd: Moved (server-side) to: a/potato2.rcc.001 2021/03/04 05:13:39 INFO : a/potato2.rcc.002_fnpmnd: Moved (server-side) to: a/potato2.rcc.002 2021/03/04 05:13:39 INFO : a/potato2.rcc.003_fnpmnd: Moved (server-side) to: a/potato2.rcc.003 2021/03/04 05:13:39 INFO : a/potato2.rcc.004_fnpmnd: Moved (server-side) to: a/potato2.rcc.004 2021/03/04 05:13:39 INFO : a/potato2.rcc.005_fnpmnd: Moved (server-side) to: a/potato2.rcc.005 2021/03/04 05:13:39 INFO : a/potato2.rcc.006_fnpmnd: Moved (server-side) to: a/potato2.rcc.006 2021/03/04 05:13:39 INFO : a/potato2.rcc.007_fnpmnd: Moved (server-side) to: a/potato2.rcc.007 2021/03/04 05:13:39 INFO : a/potato2.rcc.008_fnpmnd: Moved (server-side) to: a/potato2.rcc.008 2021/03/04 05:13:39 INFO : a/potato2.rcc.009_fnpmnd: Moved (server-side) to: a/potato2.rcc.009 2021/03/04 05:13:39 INFO : a/potato2.rcc.010_fnpmnd: Moved (server-side) to: a/potato2.rcc.010 2021/03/04 05:13:39 INFO : a/potato2.rcc.011_fnpmnd: Moved (server-side) to: a/potato2.rcc.011 2021/03/04 05:13:39 INFO : a/potato2.rcc.012_fnpmnd: Moved (server-side) to: a/potato2.rcc.012 2021/03/04 05:13:39 INFO : a/potato2.rcc.013_fnpmnd: Moved (server-side) to: a/potato2.rcc.013 2021/03/04 05:13:39 INFO : a/potato2.rcc.014_fnpmnd: Moved (server-side) to: a/potato2.rcc.014 2021/03/04 05:13:39 INFO : a/potato2.rcc.015_fnpmnd: Moved (server-side) to: a/potato2.rcc.015 2021/03/04 05:13:39 INFO : a/potato2.rcc.016_fnpmnd: Moved (server-side) to: a/potato2.rcc.016 2021/03/04 05:13:39 INFO : a/potato2.rcc.017_fnpmnd: Moved (server-side) to: a/potato2.rcc.017 2021/03/04 05:13:39 INFO : a/potato2.rcc.018_fnpmnd: Moved (server-side) to: a/potato2.rcc.018 2021/03/04 05:13:39 INFO : a/potato2.rcc.019_fnpmnd: Moved (server-side) to: a/potato2.rcc.019 2021/03/04 05:13:39 INFO : a/potato2.rcc.020_fnpmnd: Moved (server-side) to: a/potato2.rcc.020 2021/03/04 05:13:39 INFO : a/potato2: Copied (new) 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : b/potato: Deleted 2021/03/04 05:13:39 DEBUG : d/e: Removing directory 2021/03/04 05:13:39 DEBUG : d: Removing directory 2021/03/04 05:13:39 DEBUG : b: Removing directory 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (0.06s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : b/potato.rcc.001_fnpm71: Moved (server-side) to: b/potato.rcc.001 2021/03/04 05:13:39 INFO : b/potato.rcc.002_fnpm71: Moved (server-side) to: b/potato.rcc.002 2021/03/04 05:13:39 INFO : b/potato.rcc.003_fnpm71: Moved (server-side) to: b/potato.rcc.003 2021/03/04 05:13:39 INFO : b/potato.rcc.004_fnpm71: Moved (server-side) to: b/potato.rcc.004 2021/03/04 05:13:39 INFO : b/potato.rcc.005_fnpm71: Moved (server-side) to: b/potato.rcc.005 2021/03/04 05:13:39 INFO : b/potato.rcc.006_fnpm71: Moved (server-side) to: b/potato.rcc.006 2021/03/04 05:13:39 INFO : b/potato.rcc.007_fnpm71: Moved (server-side) to: b/potato.rcc.007 2021/03/04 05:13:39 INFO : c/non empty space.rcc.001_fnpmnz: Moved (server-side) to: c/non empty space.rcc.001 2021/03/04 05:13:39 INFO : c/non empty space.rcc.002_fnpmnz: Moved (server-side) to: c/non empty space.rcc.002 2021/03/04 05:13:39 DEBUG : d: Making directory 2021/03/04 05:13:39 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : c/non empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : a/potato2.rcc.001_fnpmbe: Moved (server-side) to: a/potato2.rcc.001 2021/03/04 05:13:39 INFO : a/potato2.rcc.002_fnpmbe: Moved (server-side) to: a/potato2.rcc.002 2021/03/04 05:13:39 INFO : a/potato2.rcc.003_fnpmbe: Moved (server-side) to: a/potato2.rcc.003 2021/03/04 05:13:39 INFO : a/potato2.rcc.004_fnpmbe: Moved (server-side) to: a/potato2.rcc.004 2021/03/04 05:13:39 INFO : a/potato2.rcc.005_fnpmbe: Moved (server-side) to: a/potato2.rcc.005 2021/03/04 05:13:39 INFO : a/potato2.rcc.006_fnpmbe: Moved (server-side) to: a/potato2.rcc.006 2021/03/04 05:13:39 INFO : a/potato2.rcc.007_fnpmbe: Moved (server-side) to: a/potato2.rcc.007 2021/03/04 05:13:39 INFO : a/potato2.rcc.008_fnpmbe: Moved (server-side) to: a/potato2.rcc.008 2021/03/04 05:13:39 INFO : a/potato2.rcc.009_fnpmbe: Moved (server-side) to: a/potato2.rcc.009 2021/03/04 05:13:39 INFO : a/potato2.rcc.010_fnpmbe: Moved (server-side) to: a/potato2.rcc.010 2021/03/04 05:13:39 INFO : a/potato2.rcc.011_fnpmbe: Moved (server-side) to: a/potato2.rcc.011 2021/03/04 05:13:39 INFO : a/potato2.rcc.012_fnpmbe: Moved (server-side) to: a/potato2.rcc.012 2021/03/04 05:13:39 INFO : a/potato2.rcc.013_fnpmbe: Moved (server-side) to: a/potato2.rcc.013 2021/03/04 05:13:39 INFO : a/potato2.rcc.014_fnpmbe: Moved (server-side) to: a/potato2.rcc.014 2021/03/04 05:13:39 INFO : a/potato2.rcc.015_fnpmbe: Moved (server-side) to: a/potato2.rcc.015 2021/03/04 05:13:39 INFO : a/potato2.rcc.016_fnpmbe: Moved (server-side) to: a/potato2.rcc.016 2021/03/04 05:13:39 INFO : a/potato2.rcc.017_fnpmbe: Moved (server-side) to: a/potato2.rcc.017 2021/03/04 05:13:39 INFO : a/potato2.rcc.018_fnpmbe: Moved (server-side) to: a/potato2.rcc.018 2021/03/04 05:13:39 INFO : a/potato2.rcc.019_fnpmbe: Moved (server-side) to: a/potato2.rcc.019 2021/03/04 05:13:39 INFO : a/potato2.rcc.020_fnpmbe: Moved (server-side) to: a/potato2.rcc.020 2021/03/04 05:13:39 INFO : a/potato2: Copied (new) 2021/03/04 05:13:39 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': not deleting files as there were IO errors 2021/03/04 05:13:39 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (0.06s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmue: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmue: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmue: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmue: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmue: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmue: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmue: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpmqq: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : potato: Deleted 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmnm: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmnm: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmnm: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmnm: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpmnm: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpmnm: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpmnm: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2.rcc.008_fnpmnm: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:39 INFO : potato2.rcc.009_fnpmnm: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:39 INFO : potato2.rcc.010_fnpmnm: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:39 INFO : potato2.rcc.011_fnpmnm: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:39 INFO : potato2.rcc.012_fnpmnm: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:39 INFO : potato2.rcc.013_fnpmnm: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:39 INFO : potato2.rcc.014_fnpmnm: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:39 INFO : potato2.rcc.015_fnpmnm: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:39 INFO : potato2.rcc.016_fnpmnm: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:39 INFO : potato2.rcc.017_fnpmnm: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:39 INFO : potato2.rcc.018_fnpmnm: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:39 INFO : potato2.rcc.019_fnpmnm: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:39 INFO : potato2.rcc.020_fnpmnm: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:39 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (0.06s) === RUN TestSyncDeleteBefore run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmwo: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmwo: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmwo: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmwo: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmwo: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmwo: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmwo: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpm3b: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : potato: Deleted 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmgb: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmgb: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmgb: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmgb: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpmgb: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpmgb: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpmgb: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2.rcc.008_fnpmgb: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:39 INFO : potato2.rcc.009_fnpmgb: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:39 INFO : potato2.rcc.010_fnpmgb: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:39 INFO : potato2.rcc.011_fnpmgb: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:39 INFO : potato2.rcc.012_fnpmgb: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:39 INFO : potato2.rcc.013_fnpmgb: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:39 INFO : potato2.rcc.014_fnpmgb: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:39 INFO : potato2.rcc.015_fnpmgb: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:39 INFO : potato2.rcc.016_fnpmgb: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:39 INFO : potato2.rcc.017_fnpmgb: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:39 INFO : potato2.rcc.018_fnpmgb: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:39 INFO : potato2.rcc.019_fnpmgb: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:39 INFO : potato2.rcc.020_fnpmgb: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:39 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (0.07s) === RUN TestCopyDeleteBefore run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato.rcc.001_fnpmto: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:39 INFO : potato.rcc.002_fnpmto: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:39 INFO : potato.rcc.003_fnpmto: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:39 INFO : potato.rcc.004_fnpmto: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:39 INFO : potato.rcc.005_fnpmto: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:39 INFO : potato.rcc.006_fnpmto: Moved (server-side) to: potato.rcc.006 2021/03/04 05:13:39 INFO : potato.rcc.007_fnpmto: Moved (server-side) to: potato.rcc.007 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmnp: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmnp: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmnp: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmnp: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpmnp: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpmnp: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpmnp: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (0.03s) === RUN TestSyncWithExclude run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpmwh: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpmwh: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpmwh: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpmwh: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpmwh: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpmwh: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpmwh: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2.rcc.008_fnpmwh: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:39 INFO : potato2.rcc.009_fnpmwh: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:39 INFO : potato2.rcc.010_fnpmwh: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:39 INFO : potato2.rcc.011_fnpmwh: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:39 INFO : potato2.rcc.012_fnpmwh: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:39 INFO : potato2.rcc.013_fnpmwh: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:39 INFO : potato2.rcc.014_fnpmwh: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:39 INFO : potato2.rcc.015_fnpmwh: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:39 INFO : potato2.rcc.016_fnpmwh: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:39 INFO : potato2.rcc.017_fnpmwh: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:39 INFO : potato2.rcc.018_fnpmwh: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:39 INFO : potato2.rcc.019_fnpmwh: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:39 INFO : potato2.rcc.020_fnpmwh: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpma6: Moved (server-side) to: empty space 2021/03/04 05:13:39 DEBUG : enormous: Excluded 2021/03/04 05:13:39 DEBUG : potato2: Excluded 2021/03/04 05:13:39 DEBUG : potato2: Excluded 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer 2021/03/04 05:13:39 DEBUG : enormous: Excluded 2021/03/04 05:13:39 DEBUG : potato2: Excluded 2021/03/04 05:13:39 DEBUG : potato2: Excluded 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (0.06s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:39 INFO : potato2.rcc.001_fnpm99: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:39 INFO : potato2.rcc.002_fnpm99: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:39 INFO : potato2.rcc.003_fnpm99: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:39 INFO : potato2.rcc.004_fnpm99: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:39 INFO : potato2.rcc.005_fnpm99: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:39 INFO : potato2.rcc.006_fnpm99: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:39 INFO : potato2.rcc.007_fnpm99: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:39 INFO : potato2.rcc.008_fnpm99: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:39 INFO : potato2.rcc.009_fnpm99: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:39 INFO : potato2.rcc.010_fnpm99: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:39 INFO : potato2.rcc.011_fnpm99: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:39 INFO : potato2.rcc.012_fnpm99: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:39 INFO : potato2.rcc.013_fnpm99: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:39 INFO : potato2.rcc.014_fnpm99: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:39 INFO : potato2.rcc.015_fnpm99: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:39 INFO : potato2.rcc.016_fnpm99: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:39 INFO : potato2.rcc.017_fnpm99: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:39 INFO : potato2.rcc.018_fnpm99: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:39 INFO : potato2.rcc.019_fnpm99: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:39 INFO : potato2.rcc.020_fnpm99: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:39 INFO : empty space.rcc.001_fnpmpr: Moved (server-side) to: empty space 2021/03/04 05:13:39 INFO : enormous.rcc.001_fnpmqm: Moved (server-side) to: enormous.rcc.001 2021/03/04 05:13:39 INFO : enormous.rcc.002_fnpmqm: Moved (server-side) to: enormous.rcc.002 2021/03/04 05:13:39 INFO : enormous.rcc.003_fnpmqm: Moved (server-side) to: enormous.rcc.003 2021/03/04 05:13:39 INFO : enormous.rcc.004_fnpmqm: Moved (server-side) to: enormous.rcc.004 2021/03/04 05:13:39 INFO : enormous.rcc.005_fnpmqm: Moved (server-side) to: enormous.rcc.005 2021/03/04 05:13:39 INFO : enormous.rcc.006_fnpmqm: Moved (server-side) to: enormous.rcc.006 2021/03/04 05:13:39 INFO : enormous.rcc.007_fnpmqm: Moved (server-side) to: enormous.rcc.007 2021/03/04 05:13:39 INFO : enormous.rcc.008_fnpmqm: Moved (server-side) to: enormous.rcc.008 2021/03/04 05:13:39 INFO : enormous.rcc.009_fnpmqm: Moved (server-side) to: enormous.rcc.009 2021/03/04 05:13:39 INFO : enormous.rcc.010_fnpmqm: Moved (server-side) to: enormous.rcc.010 2021/03/04 05:13:39 INFO : enormous.rcc.011_fnpmqm: Moved (server-side) to: enormous.rcc.011 2021/03/04 05:13:39 INFO : enormous.rcc.012_fnpmqm: Moved (server-side) to: enormous.rcc.012 2021/03/04 05:13:39 INFO : enormous.rcc.013_fnpmqm: Moved (server-side) to: enormous.rcc.013 2021/03/04 05:13:39 INFO : enormous.rcc.014_fnpmqm: Moved (server-side) to: enormous.rcc.014 2021/03/04 05:13:39 INFO : enormous.rcc.015_fnpmqm: Moved (server-side) to: enormous.rcc.015 2021/03/04 05:13:39 INFO : enormous.rcc.016_fnpmqm: Moved (server-side) to: enormous.rcc.016 2021/03/04 05:13:39 INFO : enormous.rcc.017_fnpmqm: Moved (server-side) to: enormous.rcc.017 2021/03/04 05:13:39 INFO : enormous.rcc.018_fnpmqm: Moved (server-side) to: enormous.rcc.018 2021/03/04 05:13:39 INFO : enormous.rcc.019_fnpmqm: Moved (server-side) to: enormous.rcc.019 2021/03/04 05:13:39 INFO : enormous.rcc.020_fnpmqm: Moved (server-side) to: enormous.rcc.020 2021/03/04 05:13:39 INFO : enormous.rcc.021_fnpmqm: Moved (server-side) to: enormous.rcc.021 2021/03/04 05:13:39 INFO : enormous.rcc.022_fnpmqm: Moved (server-side) to: enormous.rcc.022 2021/03/04 05:13:39 INFO : enormous.rcc.023_fnpmqm: Moved (server-side) to: enormous.rcc.023 2021/03/04 05:13:39 INFO : enormous.rcc.024_fnpmqm: Moved (server-side) to: enormous.rcc.024 2021/03/04 05:13:39 INFO : enormous.rcc.025_fnpmqm: Moved (server-side) to: enormous.rcc.025 2021/03/04 05:13:39 INFO : enormous.rcc.026_fnpmqm: Moved (server-side) to: enormous.rcc.026 2021/03/04 05:13:39 INFO : enormous.rcc.027_fnpmqm: Moved (server-side) to: enormous.rcc.027 2021/03/04 05:13:39 INFO : enormous.rcc.028_fnpmqm: Moved (server-side) to: enormous.rcc.028 2021/03/04 05:13:39 INFO : enormous.rcc.029_fnpmqm: Moved (server-side) to: enormous.rcc.029 2021/03/04 05:13:39 INFO : enormous.rcc.030_fnpmqm: Moved (server-side) to: enormous.rcc.030 2021/03/04 05:13:39 INFO : enormous.rcc.031_fnpmqm: Moved (server-side) to: enormous.rcc.031 2021/03/04 05:13:39 INFO : enormous.rcc.032_fnpmqm: Moved (server-side) to: enormous.rcc.032 2021/03/04 05:13:39 INFO : enormous.rcc.033_fnpmqm: Moved (server-side) to: enormous.rcc.033 2021/03/04 05:13:39 INFO : enormous.rcc.034_fnpmqm: Moved (server-side) to: enormous.rcc.034 2021/03/04 05:13:39 DEBUG : enormous: Excluded 2021/03/04 05:13:39 DEBUG : potato2: Excluded 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : potato2: Deleted 2021/03/04 05:13:39 INFO : enormous: Deleted 2021/03/04 05:13:39 INFO : There was nothing to transfer 2021/03/04 05:13:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:39 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for checks to finish 2021/03/04 05:13:39 DEBUG : Local file system at /tmp/rclone486996259: Waiting for transfers to finish 2021/03/04 05:13:39 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:39 INFO : enormous: Deleted 2021/03/04 05:13:39 INFO : potato2: Deleted 2021/03/04 05:13:39 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (0.09s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:40 INFO : one.rcc.001_fnpn0u: Moved (server-side) to: one 2021/03/04 05:13:40 INFO : two.rcc.001_fnpnfl: Moved (server-side) to: two 2021/03/04 05:13:40 INFO : three.rcc.001_fnpnzq: Moved (server-side) to: three.rcc.001 2021/03/04 05:13:40 INFO : three.rcc.002_fnpnzq: Moved (server-side) to: three.rcc.002 2021/03/04 05:13:40 INFO : four.rcc.001_fnpnr9: Moved (server-side) to: four.rcc.001 2021/03/04 05:13:40 INFO : four.rcc.002_fnpnr9: Moved (server-side) to: four.rcc.002 2021/03/04 05:13:40 INFO : four.rcc.003_fnpnr9: Moved (server-side) to: four.rcc.003 2021/03/04 05:13:40 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/03/04 05:13:40 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/03/04 05:13:40 DEBUG : four: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : five: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : four.rcc.001_fnpngq: Moved (server-side) to: four.rcc.001 2021/03/04 05:13:40 INFO : four.rcc.002_fnpngq: Moved (server-side) to: four.rcc.002 2021/03/04 05:13:40 DEBUG : one: Destination is newer than source, skipping 2021/03/04 05:13:40 DEBUG : three: Sizes identical 2021/03/04 05:13:40 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/03/04 05:13:40 DEBUG : two: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : two: Deleted 2021/03/04 05:13:40 INFO : two.rcc.001_fnpn9o: Moved (server-side) to: two 2021/03/04 05:13:40 INFO : four: Copied (replaced existing) 2021/03/04 05:13:40 INFO : two: Copied (replaced existing) 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : five.rcc.001_fnpnhz: Moved (server-side) to: five.rcc.001 2021/03/04 05:13:40 INFO : five.rcc.002_fnpnhz: Moved (server-side) to: five.rcc.002 2021/03/04 05:13:40 INFO : five: Copied (new) 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish sync_test.go:1056: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (0.04s) === RUN TestSyncWithMaxDuration sync_test.go:1074: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:1128: Can track renames: false 2021/03/04 05:13:40 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Ignoring --track-renames as the source and destination do not have a common hash 2021/03/04 05:13:40 DEBUG : yam: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : yam.rcc.001_fnpnu6: Moved (server-side) to: yam.rcc.001 2021/03/04 05:13:40 INFO : yam.rcc.002_fnpnu6: Moved (server-side) to: yam.rcc.002 2021/03/04 05:13:40 INFO : yam.rcc.003_fnpnu6: Moved (server-side) to: yam.rcc.003 2021/03/04 05:13:40 INFO : yam.rcc.004_fnpnu6: Moved (server-side) to: yam.rcc.004 2021/03/04 05:13:40 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : yam: Copied (new) 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : potato.rcc.001_fnpnsn: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:40 INFO : potato.rcc.002_fnpnsn: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:40 INFO : potato.rcc.003_fnpnsn: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:40 INFO : potato.rcc.004_fnpnsn: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:40 INFO : potato.rcc.005_fnpnsn: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:40 INFO : potato: Copied (new) 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Ignoring --track-renames as the source and destination do not have a common hash 2021/03/04 05:13:40 DEBUG : yaml: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:40 DEBUG : potato: Unchanged skipping 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : yaml.rcc.001_fnpno8: Moved (server-side) to: yaml.rcc.001 2021/03/04 05:13:40 INFO : yaml.rcc.002_fnpno8: Moved (server-side) to: yaml.rcc.002 2021/03/04 05:13:40 INFO : yaml.rcc.003_fnpno8: Moved (server-side) to: yaml.rcc.003 2021/03/04 05:13:40 INFO : yaml.rcc.004_fnpno8: Moved (server-side) to: yaml.rcc.004 2021/03/04 05:13:40 INFO : yaml: Copied (new) 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (0.04s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:1201: Can track renames: true 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Making map for --track-renames 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Finished making map for --track-renames 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : potato.rcc.001_fnpnc3: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:40 INFO : potato.rcc.002_fnpnc3: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:40 INFO : potato.rcc.003_fnpnc3: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:40 INFO : potato.rcc.004_fnpnc3: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:40 INFO : potato.rcc.005_fnpnc3: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:40 DEBUG : yam: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : yam.rcc.001_fnpn9v: Moved (server-side) to: yam.rcc.001 2021/03/04 05:13:40 INFO : yam.rcc.002_fnpn9v: Moved (server-side) to: yam.rcc.002 2021/03/04 05:13:40 INFO : yam.rcc.003_fnpn9v: Moved (server-side) to: yam.rcc.003 2021/03/04 05:13:40 INFO : yam.rcc.004_fnpn9v: Moved (server-side) to: yam.rcc.004 2021/03/04 05:13:40 INFO : potato: Copied (new) 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for renames to finish 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : yam: Copied (new) 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Making map for --track-renames 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Finished making map for --track-renames 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:40 DEBUG : potato: Unchanged skipping 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for renames to finish 2021/03/04 05:13:40 DEBUG : yam: move 4 data chunks... 2021/03/04 05:13:40 INFO : yam.rcc.001: Moved (server-side) to: yaml.rcc.001 2021/03/04 05:13:40 INFO : yam.rcc.002: Moved (server-side) to: yaml.rcc.002 2021/03/04 05:13:40 INFO : yam.rcc.003: Moved (server-side) to: yaml.rcc.003 2021/03/04 05:13:40 INFO : yam.rcc.004: Moved (server-side) to: yaml.rcc.004 2021/03/04 05:13:40 INFO : yam: Moved (server-side) to: yaml 2021/03/04 05:13:40 INFO : yam: Moved (server-side) to: yaml 2021/03/04 05:13:40 INFO : yaml: Renamed from "yam" 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (0.03s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:1241: Can track renames: true 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Making map for --track-renames 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Finished making map for --track-renames 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : sub/yam: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for renames to finish 2021/03/04 05:13:40 INFO : sub/yam.rcc.001_fnpny1: Moved (server-side) to: sub/yam.rcc.001 2021/03/04 05:13:40 INFO : sub/yam.rcc.002_fnpny1: Moved (server-side) to: sub/yam.rcc.002 2021/03/04 05:13:40 INFO : sub/yam.rcc.003_fnpny1: Moved (server-side) to: sub/yam.rcc.003 2021/03/04 05:13:40 INFO : sub/yam.rcc.004_fnpny1: Moved (server-side) to: sub/yam.rcc.004 2021/03/04 05:13:40 INFO : sub/yam: Copied (new) 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : potato.rcc.001_fnpnwu: Moved (server-side) to: potato.rcc.001 2021/03/04 05:13:40 INFO : potato.rcc.002_fnpnwu: Moved (server-side) to: potato.rcc.002 2021/03/04 05:13:40 INFO : potato.rcc.003_fnpnwu: Moved (server-side) to: potato.rcc.003 2021/03/04 05:13:40 INFO : potato.rcc.004_fnpnwu: Moved (server-side) to: potato.rcc.004 2021/03/04 05:13:40 INFO : potato.rcc.005_fnpnwu: Moved (server-side) to: potato.rcc.005 2021/03/04 05:13:40 INFO : potato: Copied (new) 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:40 DEBUG : potato: Unchanged skipping 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Making map for --track-renames 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Finished making map for --track-renames 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for renames to finish 2021/03/04 05:13:40 DEBUG : sub/yam: move 4 data chunks... 2021/03/04 05:13:40 INFO : sub/yam.rcc.001: Moved (server-side) to: yam.rcc.001 2021/03/04 05:13:40 INFO : sub/yam.rcc.002: Moved (server-side) to: yam.rcc.002 2021/03/04 05:13:40 INFO : sub/yam.rcc.003: Moved (server-side) to: yam.rcc.003 2021/03/04 05:13:40 INFO : sub/yam.rcc.004: Moved (server-side) to: yam.rcc.004 2021/03/04 05:13:40 INFO : sub/yam: Moved (server-side) to: yam 2021/03/04 05:13:40 INFO : sub/yam: Moved (server-side) to: yam 2021/03/04 05:13:40 INFO : yam: Renamed from "sub/yam" 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:40 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (0.03s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:40 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : nested/sub dir/file: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : nested/sub dir/file.rcc.001_fnpni1: Moved (server-side) to: nested/sub dir/file.rcc.001 2021/03/04 05:13:40 INFO : nested/sub dir/file.rcc.002_fnpni1: Moved (server-side) to: nested/sub dir/file.rcc.002 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.001_fnpng3: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 INFO : nested/sub dir/file: Copied (new) 2021/03/04 05:13:40 INFO : nested/sub dir/file: Deleted 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.002_fnpng3: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.003_fnpng3: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.004_fnpng3: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:40 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:40 INFO : sub dir/hello world: Deleted 2021/03/04 05:13:40 DEBUG : sub dir: Removing directory 2021/03/04 05:13:40 DEBUG : nested/sub dir: Removing directory 2021/03/04 05:13:40 DEBUG : nested: Removing directory 2021/03/04 05:13:40 DEBUG : Local file system at /tmp/rclone486996259: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.02s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:40 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 DEBUG : nested/sub dir/file: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:40 INFO : nested/sub dir/file.rcc.001_fnpnvk: Moved (server-side) to: nested/sub dir/file.rcc.001 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.001_fnpnnk: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/04 05:13:40 INFO : nested/sub dir/file.rcc.002_fnpnvk: Moved (server-side) to: nested/sub dir/file.rcc.002 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.002_fnpnnk: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.003_fnpnnk: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/04 05:13:40 INFO : sub dir/hello world.rcc.004_fnpnnk: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:40 INFO : sub dir/hello world: Copied (new) 2021/03/04 05:13:40 INFO : sub dir/hello world: Deleted 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:40 INFO : nested/sub dir/file: Copied (new) 2021/03/04 05:13:40 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.01s) === RUN TestServerSideMove run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/kn9vomuu465cla6o2u1t4htnao1r41urb39oplsq5h7lf3vs0oolg671m7djb7gks20ube4nl7lpu" 2021/03/04 05:13:40 DEBUG : Reset feature "ListR" 2021/03/04 05:13:40 INFO : potato2.rcc.001_fnpn8u: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:40 INFO : potato2.rcc.002_fnpn8u: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:40 INFO : potato2.rcc.003_fnpn8u: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:40 INFO : potato2.rcc.004_fnpn8u: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:40 INFO : potato2.rcc.005_fnpn8u: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:40 INFO : potato2.rcc.006_fnpn8u: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:40 INFO : potato2.rcc.007_fnpn8u: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:40 INFO : potato2.rcc.008_fnpn8u: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:40 INFO : potato2.rcc.009_fnpn8u: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:40 INFO : potato2.rcc.010_fnpn8u: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:40 INFO : potato2.rcc.011_fnpn8u: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:40 INFO : potato2.rcc.012_fnpn8u: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:40 INFO : potato2.rcc.013_fnpn8u: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:40 INFO : potato2.rcc.014_fnpn8u: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:40 INFO : potato2.rcc.015_fnpn8u: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:40 INFO : potato2.rcc.016_fnpn8u: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:40 INFO : potato2.rcc.017_fnpn8u: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:40 INFO : potato2.rcc.018_fnpn8u: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:40 INFO : potato2.rcc.019_fnpn8u: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:40 INFO : potato2.rcc.020_fnpn8u: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:40 INFO : empty space.rcc.001_fnpnu2: Moved (server-side) to: empty space 2021/03/04 05:13:40 INFO : potato3.rcc.001_fnpnk7: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:40 INFO : potato3.rcc.002_fnpnk7: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:40 INFO : potato3.rcc.003_fnpnk7: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:40 INFO : potato3.rcc.004_fnpnk7: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:40 INFO : potato3.rcc.005_fnpnk7: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:40 INFO : potato3.rcc.006_fnpnk7: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:40 INFO : potato3.rcc.007_fnpnk7: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:40 INFO : potato3.rcc.008_fnpnk7: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:40 INFO : potato3.rcc.009_fnpnk7: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:40 INFO : potato3.rcc.010_fnpnk7: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:40 INFO : potato3.rcc.011_fnpnk7: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:40 INFO : potato3.rcc.012_fnpnk7: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:40 INFO : potato3.rcc.013_fnpnk7: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:40 INFO : potato3.rcc.014_fnpnk7: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:40 INFO : potato3.rcc.015_fnpnk7: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:40 INFO : potato3.rcc.016_fnpnk7: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:40 INFO : potato3.rcc.017_fnpnk7: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:40 INFO : potato3.rcc.018_fnpnk7: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:40 INFO : potato3.rcc.019_fnpnk7: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:40 INFO : potato3.rcc.020_fnpnk7: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:40 INFO : potato3.rcc.021_fnpnk7: Moved (server-side) to: potato3.rcc.021 2021/03/04 05:13:40 INFO : potato3.rcc.022_fnpnk7: Moved (server-side) to: potato3.rcc.022 2021/03/04 05:13:40 INFO : potato3.rcc.023_fnpnk7: Moved (server-side) to: potato3.rcc.023 sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0' -> Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4' 2021/03/04 05:13:40 INFO : empty space.rcc.001_fnpnzw: Moved (server-side) to: empty space 2021/03/04 05:13:40 INFO : potato3.rcc.001_fnpnhf: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:40 INFO : potato3.rcc.002_fnpnhf: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:40 INFO : potato3.rcc.003_fnpnhf: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:40 INFO : potato3.rcc.004_fnpnhf: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:40 INFO : potato3.rcc.005_fnpnhf: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:40 INFO : potato3.rcc.006_fnpnhf: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:40 INFO : potato3.rcc.007_fnpnhf: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:40 INFO : potato3.rcc.008_fnpnhf: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:40 INFO : potato3.rcc.009_fnpnhf: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:40 INFO : potato3.rcc.010_fnpnhf: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:40 INFO : potato3.rcc.011_fnpnhf: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:40 INFO : potato3.rcc.012_fnpnhf: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:40 INFO : potato3.rcc.013_fnpnhf: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:40 INFO : potato3.rcc.014_fnpnhf: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:40 INFO : potato3.rcc.015_fnpnhf: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:40 INFO : potato3.rcc.016_fnpnhf: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:40 INFO : potato3.rcc.017_fnpnhf: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:40 INFO : potato3.rcc.018_fnpnhf: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:40 INFO : potato3.rcc.019_fnpnhf: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:40 INFO : potato3.rcc.020_fnpnhf: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4': Using server-side directory move 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/03/04 05:13:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:40 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:40 INFO : empty space: Deleted 2021/03/04 05:13:40 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/04 05:13:40 INFO : potato3: Deleted 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4': Waiting for checks to finish 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4': Waiting for transfers to finish 2021/03/04 05:13:40 DEBUG : potato3: move 23 data chunks... 2021/03/04 05:13:40 INFO : potato3.rcc.001: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.002: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.003: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.004: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.005: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.006: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.007: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.008: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.009: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.010: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.011: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.012: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.013: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.014: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.015: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.016: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.017: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.018: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.019: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.020: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.021: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.022: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3.rcc.023: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3: Moved (server-side) 2021/03/04 05:13:40 INFO : potato3: Moved (server-side) 2021/03/04 05:13:40 DEBUG : potato2: move 20 data chunks... 2021/03/04 05:13:40 INFO : potato2.rcc.001: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.002: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.003: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.004: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.005: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.006: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.007: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.008: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.009: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.010: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.011: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.012: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.013: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.014: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.015: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.016: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.017: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.018: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.019: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2.rcc.020: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2: Moved (server-side) 2021/03/04 05:13:40 INFO : potato2: Moved (server-side) 2021/03/04 05:13:40 INFO : There was nothing to transfer 2021/03/04 05:13:40 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-wuxewiw9necifav5poxihuf5" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-wuxewiw9necifav5poxihuf5" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/8a57d5c8bgdrsver7e5cj7lqa8151o55s77qate6ns7p4o0mgaihkdu18qamqoqq4oaaks13vda8c" 2021/03/04 05:13:40 DEBUG : Reset feature "ListR" 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-wuxewiw9necifav5poxihuf5': Using server-side directory move 2021/03/04 05:13:40 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-wuxewiw9necifav5poxihuf5': Server side directory move succeeded 2021/03/04 05:13:40 ERROR : : error listing: directory not found 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-wuxewiw9necifav5poxihuf5': Purge remote 2021/03/04 05:13:40 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-gimeyet3diyamun0tewowoh4': Purge remote 2021/03/04 05:13:40 purge failed: directory not found --- PASS: TestServerSideMove (0.63s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1" 2021/03/04 05:13:40 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/uce7ke4ia2hia8h7ksih10ipj79rsdvv9jqaf0t87u6ff31jhnr5notq3qn3i3f9tmc9dpbkoe1pq" 2021/03/04 05:13:40 DEBUG : Reset feature "ListR" 2021/03/04 05:13:40 INFO : potato2.rcc.001_fnpn76: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:40 INFO : potato2.rcc.002_fnpn76: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:40 INFO : potato2.rcc.003_fnpn76: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:40 INFO : potato2.rcc.004_fnpn76: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:40 INFO : potato2.rcc.005_fnpn76: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:40 INFO : potato2.rcc.006_fnpn76: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:40 INFO : potato2.rcc.007_fnpn76: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:40 INFO : potato2.rcc.008_fnpn76: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:40 INFO : potato2.rcc.009_fnpn76: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:40 INFO : potato2.rcc.010_fnpn76: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:40 INFO : potato2.rcc.011_fnpn76: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:40 INFO : potato2.rcc.012_fnpn76: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:40 INFO : potato2.rcc.013_fnpn76: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:40 INFO : potato2.rcc.014_fnpn76: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:40 INFO : potato2.rcc.015_fnpn76: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:40 INFO : potato2.rcc.016_fnpn76: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:40 INFO : potato2.rcc.017_fnpn76: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:40 INFO : potato2.rcc.018_fnpn76: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:40 INFO : potato2.rcc.019_fnpn76: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:40 INFO : potato2.rcc.020_fnpn76: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:40 INFO : empty space.rcc.001_fnpnh3: Moved (server-side) to: empty space 2021/03/04 05:13:40 INFO : potato3.rcc.001_fnpnr5: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:40 INFO : potato3.rcc.002_fnpnr5: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:40 INFO : potato3.rcc.003_fnpnr5: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:40 INFO : potato3.rcc.004_fnpnr5: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:41 INFO : potato3.rcc.005_fnpnr5: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:41 INFO : potato3.rcc.006_fnpnr5: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:41 INFO : potato3.rcc.007_fnpnr5: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:41 INFO : potato3.rcc.008_fnpnr5: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:41 INFO : potato3.rcc.009_fnpnr5: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:41 INFO : potato3.rcc.010_fnpnr5: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:41 INFO : potato3.rcc.011_fnpnr5: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:41 INFO : potato3.rcc.012_fnpnr5: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:41 INFO : potato3.rcc.013_fnpnr5: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:41 INFO : potato3.rcc.014_fnpnr5: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:41 INFO : potato3.rcc.015_fnpnr5: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:41 INFO : potato3.rcc.016_fnpnr5: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:41 INFO : potato3.rcc.017_fnpnr5: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:41 INFO : potato3.rcc.018_fnpnr5: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:41 INFO : potato3.rcc.019_fnpnr5: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:41 INFO : potato3.rcc.020_fnpnr5: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:41 INFO : potato3.rcc.021_fnpnr5: Moved (server-side) to: potato3.rcc.021 2021/03/04 05:13:41 INFO : potato3.rcc.022_fnpnr5: Moved (server-side) to: potato3.rcc.022 2021/03/04 05:13:41 INFO : potato3.rcc.023_fnpnr5: Moved (server-side) to: potato3.rcc.023 sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0' -> Chunked 'TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1' 2021/03/04 05:13:41 INFO : empty space.rcc.001_fnpo29: Moved (server-side) to: empty space 2021/03/04 05:13:41 INFO : potato3.rcc.001_fnpox3: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:41 INFO : potato3.rcc.002_fnpox3: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:41 INFO : potato3.rcc.003_fnpox3: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:41 INFO : potato3.rcc.004_fnpox3: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:41 INFO : potato3.rcc.005_fnpox3: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:41 INFO : potato3.rcc.006_fnpox3: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:41 INFO : potato3.rcc.007_fnpox3: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:41 INFO : potato3.rcc.008_fnpox3: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:41 INFO : potato3.rcc.009_fnpox3: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:41 INFO : potato3.rcc.010_fnpox3: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:41 INFO : potato3.rcc.011_fnpox3: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:41 INFO : potato3.rcc.012_fnpox3: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:41 INFO : potato3.rcc.013_fnpox3: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:41 INFO : potato3.rcc.014_fnpox3: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:41 INFO : potato3.rcc.015_fnpox3: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:41 INFO : potato3.rcc.016_fnpox3: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:41 INFO : potato3.rcc.017_fnpox3: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:41 INFO : potato3.rcc.018_fnpox3: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:41 INFO : potato3.rcc.019_fnpox3: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:41 INFO : potato3.rcc.020_fnpox3: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:41 DEBUG : empty space: Excluded 2021/03/04 05:13:41 DEBUG : empty space: Excluded 2021/03/04 05:13:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/04 05:13:41 DEBUG : potato2: move 20 data chunks... 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1': Waiting for transfers to finish 2021/03/04 05:13:41 INFO : potato2.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.011: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.020: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Deleted 2021/03/04 05:13:41 DEBUG : potato3: move 23 data chunks... 2021/03/04 05:13:41 INFO : potato3.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.011: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.020: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.021: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.022: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.023: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-wizozaz8fenonen2suzuxeb1" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-wizozaz8fenonen2suzuxeb1" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/urs50da8lkbtt3dkla0h2kqkgtg0vj7lks4m7m45qoe93j8nqu7etad9es9qon8or5u7ukpb43f1i" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" 2021/03/04 05:13:41 DEBUG : empty space: Excluded 2021/03/04 05:13:41 DEBUG : potato2: move 20 data chunks... 2021/03/04 05:13:41 INFO : potato2.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.011: Moved (server-side) 2021/03/04 05:13:41 DEBUG : potato3: move 23 data chunks... 2021/03/04 05:13:41 INFO : potato2.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.020: Moved (server-side) 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-wizozaz8fenonen2suzuxeb1': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-wizozaz8fenonen2suzuxeb1': Waiting for transfers to finish 2021/03/04 05:13:41 INFO : potato3.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.011: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.020: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.021: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.022: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.023: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-wizozaz8fenonen2suzuxeb1': Purge remote 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-qisoweq3javubus8gimagas1': Purge remote --- PASS: TestServerSideMoveWithFilter (0.46s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/v5so2lb89o2nf4650jdrfhaqflfubh4ojgl5hmj2ff59sk1gpnn6flu4vm0feend6pns4ocg078he" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" 2021/03/04 05:13:41 INFO : potato2.rcc.001_fnpohk: Moved (server-side) to: potato2.rcc.001 2021/03/04 05:13:41 INFO : potato2.rcc.002_fnpohk: Moved (server-side) to: potato2.rcc.002 2021/03/04 05:13:41 INFO : potato2.rcc.003_fnpohk: Moved (server-side) to: potato2.rcc.003 2021/03/04 05:13:41 INFO : potato2.rcc.004_fnpohk: Moved (server-side) to: potato2.rcc.004 2021/03/04 05:13:41 INFO : potato2.rcc.005_fnpohk: Moved (server-side) to: potato2.rcc.005 2021/03/04 05:13:41 INFO : potato2.rcc.006_fnpohk: Moved (server-side) to: potato2.rcc.006 2021/03/04 05:13:41 INFO : potato2.rcc.007_fnpohk: Moved (server-side) to: potato2.rcc.007 2021/03/04 05:13:41 INFO : potato2.rcc.008_fnpohk: Moved (server-side) to: potato2.rcc.008 2021/03/04 05:13:41 INFO : potato2.rcc.009_fnpohk: Moved (server-side) to: potato2.rcc.009 2021/03/04 05:13:41 INFO : potato2.rcc.010_fnpohk: Moved (server-side) to: potato2.rcc.010 2021/03/04 05:13:41 INFO : potato2.rcc.011_fnpohk: Moved (server-side) to: potato2.rcc.011 2021/03/04 05:13:41 INFO : potato2.rcc.012_fnpohk: Moved (server-side) to: potato2.rcc.012 2021/03/04 05:13:41 INFO : potato2.rcc.013_fnpohk: Moved (server-side) to: potato2.rcc.013 2021/03/04 05:13:41 INFO : potato2.rcc.014_fnpohk: Moved (server-side) to: potato2.rcc.014 2021/03/04 05:13:41 INFO : potato2.rcc.015_fnpohk: Moved (server-side) to: potato2.rcc.015 2021/03/04 05:13:41 INFO : potato2.rcc.016_fnpohk: Moved (server-side) to: potato2.rcc.016 2021/03/04 05:13:41 INFO : potato2.rcc.017_fnpohk: Moved (server-side) to: potato2.rcc.017 2021/03/04 05:13:41 INFO : potato2.rcc.018_fnpohk: Moved (server-side) to: potato2.rcc.018 2021/03/04 05:13:41 INFO : potato2.rcc.019_fnpohk: Moved (server-side) to: potato2.rcc.019 2021/03/04 05:13:41 INFO : potato2.rcc.020_fnpohk: Moved (server-side) to: potato2.rcc.020 2021/03/04 05:13:41 INFO : empty space.rcc.001_fnpotr: Moved (server-side) to: empty space 2021/03/04 05:13:41 INFO : potato3.rcc.001_fnpol0: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:41 INFO : potato3.rcc.002_fnpol0: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:41 INFO : potato3.rcc.003_fnpol0: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:41 INFO : potato3.rcc.004_fnpol0: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:41 INFO : potato3.rcc.005_fnpol0: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:41 INFO : potato3.rcc.006_fnpol0: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:41 INFO : potato3.rcc.007_fnpol0: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:41 INFO : potato3.rcc.008_fnpol0: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:41 INFO : potato3.rcc.009_fnpol0: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:41 INFO : potato3.rcc.010_fnpol0: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:41 INFO : potato3.rcc.011_fnpol0: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:41 INFO : potato3.rcc.012_fnpol0: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:41 INFO : potato3.rcc.013_fnpol0: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:41 INFO : potato3.rcc.014_fnpol0: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:41 INFO : potato3.rcc.015_fnpol0: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:41 INFO : potato3.rcc.016_fnpol0: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:41 INFO : potato3.rcc.017_fnpol0: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:41 INFO : potato3.rcc.018_fnpol0: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:41 INFO : potato3.rcc.019_fnpol0: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:41 INFO : potato3.rcc.020_fnpol0: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:41 INFO : potato3.rcc.021_fnpol0: Moved (server-side) to: potato3.rcc.021 2021/03/04 05:13:41 INFO : potato3.rcc.022_fnpol0: Moved (server-side) to: potato3.rcc.022 2021/03/04 05:13:41 INFO : potato3.rcc.023_fnpol0: Moved (server-side) to: potato3.rcc.023 2021/03/04 05:13:41 DEBUG : tomatoDir: Making directory sync_test.go:1294: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0' -> Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5' 2021/03/04 05:13:41 INFO : empty space.rcc.001_fnpozq: Moved (server-side) to: empty space 2021/03/04 05:13:41 INFO : potato3.rcc.001_fnpooi: Moved (server-side) to: potato3.rcc.001 2021/03/04 05:13:41 INFO : potato3.rcc.002_fnpooi: Moved (server-side) to: potato3.rcc.002 2021/03/04 05:13:41 INFO : potato3.rcc.003_fnpooi: Moved (server-side) to: potato3.rcc.003 2021/03/04 05:13:41 INFO : potato3.rcc.004_fnpooi: Moved (server-side) to: potato3.rcc.004 2021/03/04 05:13:41 INFO : potato3.rcc.005_fnpooi: Moved (server-side) to: potato3.rcc.005 2021/03/04 05:13:41 INFO : potato3.rcc.006_fnpooi: Moved (server-side) to: potato3.rcc.006 2021/03/04 05:13:41 INFO : potato3.rcc.007_fnpooi: Moved (server-side) to: potato3.rcc.007 2021/03/04 05:13:41 INFO : potato3.rcc.008_fnpooi: Moved (server-side) to: potato3.rcc.008 2021/03/04 05:13:41 INFO : potato3.rcc.009_fnpooi: Moved (server-side) to: potato3.rcc.009 2021/03/04 05:13:41 INFO : potato3.rcc.010_fnpooi: Moved (server-side) to: potato3.rcc.010 2021/03/04 05:13:41 INFO : potato3.rcc.011_fnpooi: Moved (server-side) to: potato3.rcc.011 2021/03/04 05:13:41 INFO : potato3.rcc.012_fnpooi: Moved (server-side) to: potato3.rcc.012 2021/03/04 05:13:41 INFO : potato3.rcc.013_fnpooi: Moved (server-side) to: potato3.rcc.013 2021/03/04 05:13:41 INFO : potato3.rcc.014_fnpooi: Moved (server-side) to: potato3.rcc.014 2021/03/04 05:13:41 INFO : potato3.rcc.015_fnpooi: Moved (server-side) to: potato3.rcc.015 2021/03/04 05:13:41 INFO : potato3.rcc.016_fnpooi: Moved (server-side) to: potato3.rcc.016 2021/03/04 05:13:41 INFO : potato3.rcc.017_fnpooi: Moved (server-side) to: potato3.rcc.017 2021/03/04 05:13:41 INFO : potato3.rcc.018_fnpooi: Moved (server-side) to: potato3.rcc.018 2021/03/04 05:13:41 INFO : potato3.rcc.019_fnpooi: Moved (server-side) to: potato3.rcc.019 2021/03/04 05:13:41 INFO : potato3.rcc.020_fnpooi: Moved (server-side) to: potato3.rcc.020 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5': Using server-side directory move 2021/03/04 05:13:41 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/03/04 05:13:41 DEBUG : potato2: move 20 data chunks... 2021/03/04 05:13:41 INFO : potato2.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.011: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2.rcc.020: Moved (server-side) 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : empty space: Unchanged skipping 2021/03/04 05:13:41 INFO : empty space: Deleted 2021/03/04 05:13:41 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/04 05:13:41 INFO : potato3: Deleted 2021/03/04 05:13:41 DEBUG : potato3: move 23 data chunks... 2021/03/04 05:13:41 INFO : potato3.rcc.001: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.002: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.003: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.004: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.005: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.006: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.007: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.008: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.009: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.010: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.011: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.012: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.013: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.014: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.015: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.016: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.017: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.018: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.019: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.020: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.021: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.022: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3.rcc.023: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 INFO : potato3: Moved (server-side) 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5': Waiting for transfers to finish 2021/03/04 05:13:41 INFO : potato2: Moved (server-side) 2021/03/04 05:13:41 DEBUG : tomatoDir: Removing directory 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': deleted 1 directories 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-riyikij1natoqaq2kuparuv6" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-riyikij1natoqaq2kuparuv6" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/c8e48oqf87fd3otb89p4cimep4m6pmdln7hnohjj7l6ek17769pdo83chbvghe67963nf8d49ik0k" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" 2021/03/04 05:13:41 DEBUG : tomatoDir: Making directory 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-riyikij1natoqaq2kuparuv6': Using server-side directory move 2021/03/04 05:13:41 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-riyikij1natoqaq2kuparuv6': Server side directory move succeeded 2021/03/04 05:13:41 ERROR : : error listing: directory not found 2021/03/04 05:13:41 ERROR : : error listing: directory not found 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-riyikij1natoqaq2kuparuv6': Purge remote 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-huhowab4giqarop0yiwenid5': Purge remote 2021/03/04 05:13:41 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (0.36s) === RUN TestServerSideMoveOverlap run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:1430: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.00s) === RUN TestSyncOverlap run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/rclone-sync-test" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/rclone-sync-test" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/0kji1l44g89hp20dijrue5bugvdmdnsgj3kfrnt75fkt5gqolp9g" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" --- PASS: TestSyncOverlap (0.12s) === RUN TestSyncCompareDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/CompareDest" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/CompareDest" 2021/03/04 05:13:41 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/fcu437f1ephh64b8cv237u29t4" 2021/03/04 05:13:41 DEBUG : Reset feature "ListR" 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:41 INFO : one.rcc.001_fnpo93: Moved (server-side) to: one 2021/03/04 05:13:41 INFO : one: Copied (new) 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:41 INFO : one.rcc.001_fnpo64: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:41 INFO : one.rcc.002_fnpo64: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:41 INFO : one: Copied (replaced existing) 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:41 INFO : dst/one: Deleted 2021/03/04 05:13:41 INFO : dst/one.rcc.001_fnpoc8: Moved (server-side) to: dst/one 2021/03/04 05:13:41 INFO : CompareDest/one.rcc.001_fnpoor: Moved (server-side) to: CompareDest/one.rcc.001 2021/03/04 05:13:41 INFO : CompareDest/one.rcc.002_fnpoor: Moved (server-side) to: CompareDest/one.rcc.002 2021/03/04 05:13:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 INFO : CompareDest/two.rcc.001_fnpo0u: Moved (server-side) to: CompareDest/two 2021/03/04 05:13:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:41 INFO : There was nothing to transfer 2021/03/04 05:13:41 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/03/04 05:13:41 DEBUG : two: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:41 INFO : two.rcc.001_fnpop8: Moved (server-side) to: two.rcc.001 2021/03/04 05:13:41 INFO : two.rcc.002_fnpop8: Moved (server-side) to: two.rcc.002 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:41 INFO : two: Copied (new) 2021/03/04 05:13:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:41 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/04 05:13:41 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (0.25s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:42 INFO : pre-dest1/1.rcc.001_fnpp5j: Moved (server-side) to: pre-dest1/1 2021/03/04 05:13:42 INFO : pre-dest2/2.rcc.001_fnppqg: Moved (server-side) to: pre-dest2/2 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dest" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dest" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/0na9j9v8qd02gospsl4qad4d2c" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/pre-dest1" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/pre-dest1" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/puq6ljvda69ueq7v3a16pqbqgo" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/pre-dest2" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/pre-dest2" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/0v83beu90ap6a4em88hf7dufv0" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:42 DEBUG : 1: Destination found in --compare-dest, skipping 2021/03/04 05:13:42 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:42 DEBUG : 2: Destination found in --compare-dest, skipping 2021/03/04 05:13:42 DEBUG : 3: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:42 INFO : 3.rcc.001_fnppia: Moved (server-side) to: 3 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dest': Waiting for checks to finish 2021/03/04 05:13:42 INFO : 3: Copied (new) 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dest': Waiting for transfers to finish 2021/03/04 05:13:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (0.49s) === RUN TestSyncCopyDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:1607: Skipping test as remote does not support server-side copy --- SKIP: TestSyncCopyDest (0.00s) === RUN TestSyncBackupDir run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:42 INFO : dst/one.rcc.001_fnppfm: Moved (server-side) to: dst/one 2021/03/04 05:13:42 INFO : dst/two.rcc.001_fnpprt: Moved (server-side) to: dst/two 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.001_fnppka: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.002_fnppka: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:42 DEBUG : two: Unchanged skipping 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:42 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:42 DEBUG : one: move non-chunked object... 2021/03/04 05:13:42 INFO : one: Moved (server-side) 2021/03/04 05:13:42 INFO : one: Moved (server-side) 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:42 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:42 INFO : one.rcc.001_fnppun: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:42 INFO : one.rcc.002_fnppun: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:42 INFO : one: Copied (new) 2021/03/04 05:13:42 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:42 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:42 INFO : three.txt.rcc.001: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt.rcc.002: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved into backup dir 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.001_fnppbv: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.002_fnppbv: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:42 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:42 INFO : one: Deleted 2021/03/04 05:13:42 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:42 INFO : one.rcc.001: Moved (server-side) 2021/03/04 05:13:42 INFO : one.rcc.002: Moved (server-side) 2021/03/04 05:13:42 INFO : one: Moved (server-side) 2021/03/04 05:13:42 INFO : one: Moved (server-side) 2021/03/04 05:13:42 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:42 DEBUG : two: Unchanged skipping 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:42 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:42 INFO : one.rcc.001_fnpprs: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:42 INFO : one.rcc.002_fnpprs: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:42 INFO : one: Copied (new) 2021/03/04 05:13:42 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:42 INFO : three.txt: Deleted 2021/03/04 05:13:42 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:42 INFO : three.txt.rcc.001: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt.rcc.002: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved (server-side) 2021/03/04 05:13:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (0.35s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:42 INFO : dst/one.rcc.001_fnpplm: Moved (server-side) to: dst/one 2021/03/04 05:13:42 INFO : dst/two.rcc.001_fnpp95: Moved (server-side) to: dst/two 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.001_fnppz5: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:42 INFO : dst/three.txt.rcc.002_fnppz5: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:42 DEBUG : Reset feature "ListR" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:42 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/04 05:13:43 DEBUG : Reset feature "ListR" 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:43 DEBUG : one: move non-chunked object... 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqfo: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqfo: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpqho: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpqho: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:43 INFO : one.bak: Deleted 2021/03/04 05:13:43 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:43 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqyo: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqyo: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 INFO : three.txt.bak: Deleted 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (0.23s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:43 INFO : dst/one.rcc.001_fnpq0h: Moved (server-side) to: dst/one 2021/03/04 05:13:43 INFO : dst/two.rcc.001_fnpqos: Moved (server-side) to: dst/two 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpqz6: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpqz6: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:43 DEBUG : Reset feature "ListR" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/backup" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/04 05:13:43 DEBUG : Reset feature "ListR" 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:43 DEBUG : one: move non-chunked object... 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqhq: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqhq: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpq3u: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpq3u: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:43 INFO : one-2019-01-01: Deleted 2021/03/04 05:13:43 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:43 INFO : one.rcc.001: Moved (server-side) to: one-2019-01-01.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002: Moved (server-side) to: one-2019-01-01.rcc.002 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqhb: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqhb: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 INFO : three-2019-01-01.txt: Deleted 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (0.32s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:43 INFO : dst/one.rcc.001_fnpqai: Moved (server-side) to: dst/one 2021/03/04 05:13:43 INFO : dst/two.rcc.001_fnpqq8: Moved (server-side) to: dst/two 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpqaf: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpqaf: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:43 DEBUG : Reset feature "ListR" 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : one: move non-chunked object... 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqb1: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqb1: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpqp1: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpqp1: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : one.bak: Excluded 2021/03/04 05:13:43 DEBUG : three.txt.bak: Excluded 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for checks to finish 2021/03/04 05:13:43 INFO : one.bak: Deleted 2021/03/04 05:13:43 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:43 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst': Waiting for transfers to finish 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpqz3: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpqz3: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:43 INFO : three.txt.bak: Deleted 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (0.34s) === RUN TestSyncSuffix run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:43 INFO : dst/one.rcc.001_fnpq2t: Moved (server-side) to: dst/one 2021/03/04 05:13:43 INFO : dst/two.rcc.001_fnpq8k: Moved (server-side) to: dst/two 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpq1c: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpq1c: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:43 DEBUG : Reset feature "ListR" 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:43 DEBUG : one: move non-chunked object... 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpq4x: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpq4x: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : three.txt.rcc.001_fnpq39: Moved (server-side) to: three.txt.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002_fnpq39: Moved (server-side) to: three.txt.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Copied (new) 2021/03/04 05:13:43 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:43 INFO : one.bak: Deleted 2021/03/04 05:13:43 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:43 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 INFO : one: Moved (server-side) to: one.bak 2021/03/04 05:13:43 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : one.rcc.001_fnpq5g: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:43 INFO : one.rcc.002_fnpq5g: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:43 INFO : one: Copied (new) 2021/03/04 05:13:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:43 DEBUG : two: Unchanged skipping 2021/03/04 05:13:43 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/04 05:13:43 INFO : three.txt.bak: Deleted 2021/03/04 05:13:43 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:43 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/04 05:13:43 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:43 INFO : three.txt.rcc.001_fnpqlh: Moved (server-side) to: three.txt.rcc.001 2021/03/04 05:13:43 INFO : three.txt.rcc.002_fnpqlh: Moved (server-side) to: three.txt.rcc.002 2021/03/04 05:13:43 INFO : three.txt.rcc.003_fnpqlh: Moved (server-side) to: three.txt.rcc.003 2021/03/04 05:13:43 INFO : three.txt.rcc.004_fnpqlh: Moved (server-side) to: three.txt.rcc.004 2021/03/04 05:13:43 INFO : three.txt.rcc.005_fnpqlh: Moved (server-side) to: three.txt.rcc.005 2021/03/04 05:13:43 INFO : three.txt.rcc.006_fnpqlh: Moved (server-side) to: three.txt.rcc.006 2021/03/04 05:13:43 INFO : three.txt.rcc.007_fnpqlh: Moved (server-side) to: three.txt.rcc.007 2021/03/04 05:13:43 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (0.19s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:43 INFO : dst/one.rcc.001_fnpqzr: Moved (server-side) to: dst/one 2021/03/04 05:13:43 INFO : dst/two.rcc.001_fnpqp6: Moved (server-side) to: dst/two 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.001_fnpqp8: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/04 05:13:43 INFO : dst/three.txt.rcc.002_fnpqp8: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/04 05:13:43 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:43 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0/dst" 2021/03/04 05:13:44 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/6rlmkn4rt87drjqumfmc3rh5ae03cev4ghqdep9ib94shfm5t40e5rfnqoomquoud3aee1uqv2nta/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/04 05:13:44 DEBUG : Reset feature "ListR" 2021/03/04 05:13:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/04 05:13:44 DEBUG : one: move non-chunked object... 2021/03/04 05:13:44 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:44 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:44 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 INFO : one.rcc.001_fnpr8g: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:44 INFO : one.rcc.002_fnpr8g: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:44 INFO : one: Copied (new) 2021/03/04 05:13:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:44 DEBUG : two: Unchanged skipping 2021/03/04 05:13:44 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/04 05:13:44 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:44 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/04 05:13:44 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/04 05:13:44 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:44 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:44 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 INFO : three.txt.rcc.001_fnpr7b: Moved (server-side) to: three.txt.rcc.001 2021/03/04 05:13:44 INFO : three.txt.rcc.002_fnpr7b: Moved (server-side) to: three.txt.rcc.002 2021/03/04 05:13:44 INFO : three.txt: Copied (new) 2021/03/04 05:13:44 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/04 05:13:44 INFO : one-2019-01-01: Deleted 2021/03/04 05:13:44 DEBUG : one: move 2 data chunks... 2021/03/04 05:13:44 INFO : one.rcc.001: Moved (server-side) to: one-2019-01-01.rcc.001 2021/03/04 05:13:44 INFO : one.rcc.002: Moved (server-side) to: one-2019-01-01.rcc.002 2021/03/04 05:13:44 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:44 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/04 05:13:44 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 INFO : one.rcc.001_fnpraj: Moved (server-side) to: one.rcc.001 2021/03/04 05:13:44 INFO : one.rcc.002_fnpraj: Moved (server-side) to: one.rcc.002 2021/03/04 05:13:44 INFO : one: Copied (new) 2021/03/04 05:13:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:44 DEBUG : two: Unchanged skipping 2021/03/04 05:13:44 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/04 05:13:44 INFO : three-2019-01-01.txt: Deleted 2021/03/04 05:13:44 DEBUG : three.txt: move 2 data chunks... 2021/03/04 05:13:44 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/04 05:13:44 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/04 05:13:44 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:44 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/04 05:13:44 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 INFO : three.txt.rcc.001_fnprsd: Moved (server-side) to: three.txt.rcc.001 2021/03/04 05:13:44 INFO : three.txt.rcc.002_fnprsd: Moved (server-side) to: three.txt.rcc.002 2021/03/04 05:13:44 INFO : three.txt.rcc.003_fnprsd: Moved (server-side) to: three.txt.rcc.003 2021/03/04 05:13:44 INFO : three.txt.rcc.004_fnprsd: Moved (server-side) to: three.txt.rcc.004 2021/03/04 05:13:44 INFO : three.txt.rcc.005_fnprsd: Moved (server-side) to: three.txt.rcc.005 2021/03/04 05:13:44 INFO : three.txt.rcc.006_fnprsd: Moved (server-side) to: three.txt.rcc.006 2021/03/04 05:13:44 INFO : three.txt.rcc.007_fnprsd: Moved (server-side) to: three.txt.rcc.007 2021/03/04 05:13:44 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (0.23s) === RUN TestSyncUTFNorm run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:44 INFO : Testêé.rcc.001_fnprp9: Moved (server-side) to: Testêé.rcc.001 2021/03/04 05:13:44 INFO : Testêé.rcc.002_fnprp9: Moved (server-side) to: Testêé.rcc.002 2021/03/04 05:13:44 INFO : Testêé.rcc.003_fnprp9: Moved (server-side) to: Testêé.rcc.003 2021/03/04 05:13:44 INFO : Testêé.rcc.004_fnprp9: Moved (server-side) to: Testêé.rcc.004 2021/03/04 05:13:44 INFO : Testêé.rcc.005_fnprp9: Moved (server-side) to: Testêé.rcc.005 2021/03/04 05:13:44 INFO : Testêé.rcc.006_fnprp9: Moved (server-side) to: Testêé.rcc.006 2021/03/04 05:13:44 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/03/04 05:13:44 DEBUG : Testêé: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:44 INFO : Testêé.rcc.001_fnprzy: Moved (server-side) to: Testêé.rcc.001 2021/03/04 05:13:44 INFO : Testêé.rcc.002_fnprzy: Moved (server-side) to: Testêé.rcc.002 2021/03/04 05:13:44 INFO : Testêé.rcc.003_fnprzy: Moved (server-side) to: Testêé.rcc.003 2021/03/04 05:13:44 INFO : Testêé.rcc.004_fnprzy: Moved (server-side) to: Testêé.rcc.004 2021/03/04 05:13:44 INFO : Testêé.rcc.005_fnprzy: Moved (server-side) to: Testêé.rcc.005 2021/03/04 05:13:44 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/03/04 05:13:44 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.07s) === RUN TestSyncImmutable run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:44 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/04 05:13:44 INFO : existing.rcc.001_fnpr5l: Moved (server-side) to: existing.rcc.001 2021/03/04 05:13:44 INFO : existing.rcc.002_fnpr5l: Moved (server-side) to: existing.rcc.002 2021/03/04 05:13:44 INFO : existing: Copied (new) 2021/03/04 05:13:44 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:44 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/03/04 05:13:44 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:44 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': not deleting files as there were IO errors 2021/03/04 05:13:44 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (0.02s) === RUN TestSyncIgnoreCase run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" 2021/03/04 05:13:44 INFO : EXISTING.rcc.001_fnpr3r: Moved (server-side) to: EXISTING.rcc.001 2021/03/04 05:13:44 INFO : EXISTING.rcc.002_fnpr3r: Moved (server-side) to: EXISTING.rcc.002 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for checks to finish 2021/03/04 05:13:44 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/04 05:13:44 DEBUG : existing: Unchanged skipping 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Waiting for transfers to finish 2021/03/04 05:13:44 DEBUG : Waiting for deletions to finish 2021/03/04 05:13:44 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (0.01s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:2020: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:2020: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0'", Local "Local file system at /tmp/rclone486996259", Modify Window "1ns" sync_test.go:2020: This test only runs on local --- PASS: TestMaxTransfer (0.00s) --- SKIP: TestMaxTransfer/Hard (0.00s) --- SKIP: TestMaxTransfer/Soft (0.00s) --- SKIP: TestMaxTransfer/Cautious (0.00s) PASS 2021/03/04 05:13:44 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-raxekih7jazaher3fozased0': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerOverCrypt: -verbose -size-limit 6144" - Finished OK in 5.967416437s (try 1/5)