"./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerOverCrypt: -verbose -fast-list -size-limit 6144" - Starting (try 1/5) 2021/03/23 05:36:34 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg" 2021/03/23 05:36:34 DEBUG : Reset feature "ListR" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "/tmp/rclone944960434" === 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-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.00s) === RUN TestCopy run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0lp: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0lp: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0lp: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0lp: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (0.01s) === RUN TestCopyMissingDirectory run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "/non-existing" 2021/03/23 05:36:34 ERROR : : error reading source directory: directory not found 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.00s) === RUN TestCopyNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0k5: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0k5: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0k5: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0k5: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.01s) === RUN TestCopyCheckFirst run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Running all checks before starting transfers 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Checks finished, now starting transfers 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0gb: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0gb: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0gb: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0gb: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.01s) === RUN TestSyncNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 ERROR : Ignoring --no-traverse with sync 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0kj: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0kj: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0kj: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0kj: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.01s) === RUN TestCopyWithDepth run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : hello world2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : hello world2.rcc.001_evg0tn: Moved (server-side) to: hello world2.rcc.001 2021/03/23 05:36:34 INFO : hello world2.rcc.002_evg0tn: Moved (server-side) to: hello world2.rcc.002 2021/03/23 05:36:34 INFO : hello world2.rcc.003_evg0tn: Moved (server-side) to: hello world2.rcc.003 2021/03/23 05:36:34 INFO : hello world2.rcc.004_evg0tn: Moved (server-side) to: hello world2.rcc.004 2021/03/23 05:36:34 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (0.01s) === RUN TestCopyWithFilesFrom run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : hello world2: Excluded 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : potato2.rcc.001_evg0mu: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:34 INFO : potato2.rcc.002_evg0mu: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:34 INFO : potato2.rcc.003_evg0mu: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:34 INFO : potato2.rcc.004_evg0mu: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:34 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (0.01s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : potato2.rcc.001_evg05l: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:34 INFO : potato2.rcc.002_evg05l: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:34 INFO : potato2.rcc.003_evg05l: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:34 INFO : potato2.rcc.004_evg05l: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:34 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.01s) === RUN TestCopyEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0d5: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0d5: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0d5: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0d5: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': copied 1 directories --- PASS: TestCopyEmptyDirectories (0.01s) === RUN TestMoveEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0xd: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0xd: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0xd: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0xd: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:34 INFO : sub dir/hello world: Deleted 2021/03/23 05:36:34 DEBUG : sub dir: Making directory 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': copied 2 directories --- PASS: TestMoveEmptyDirectories (0.01s) === RUN TestSyncEmptyDirectories run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0sl: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0sl: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0sl: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0sl: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sub dir2: Making directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': copied 1 directories 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncEmptyDirectories (0.01s) === RUN TestServerSideCopy run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0pp: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0pp: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0pp: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0pp: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5" 2021/03/23 05:36:34 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/vhqlh5khpnptbd0d3dg8nehsve9h1hnlqd0j9p4hpsul10ss4e1ksrsogp21qg7bq18qo2v4nf35e" 2021/03/23 05:36:34 DEBUG : Reset feature "ListR" sync_test.go:281: Server side copy (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1' -> Chunked 'TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5' 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0ja: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0ja: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0ja: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0ja: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-xuwoper5mazilaq5yevasul5': Purge remote --- PASS: TestServerSideCopy (0.09s) === RUN TestCopyAfterDelete run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0cs: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0cs: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0cs: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0cs: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 ERROR : : error listing: directory not found 2021/03/23 05:36:34 DEBUG : Local file system at /tmp/rclone944960434: Making directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (0.01s) === RUN TestCopyRedownload run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.001_evg0qr: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.002_evg0qr: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.003_evg0qr: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:34 INFO : sub dir/hello world.rcc.004_evg0qr: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:34 DEBUG : Local file system at /tmp/rclone944960434: Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Local file system at /tmp/rclone944960434: Waiting for transfers to finish 2021/03/23 05:36:34 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (0.01s) === RUN TestSyncBasedOnCheckSum run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : check sum: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : check sum.rcc.001_evg097: Moved (server-side) to: check sum 2021/03/23 05:36:34 INFO : check sum: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 NOTICE: Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2021/03/23 05:36:34 DEBUG : check sum: Size of src and dst objects identical 2021/03/23 05:36:34 DEBUG : check sum: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (0.01s) === RUN TestSyncSizeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : sizeonly: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : sizeonly.rcc.001_evg06e: Moved (server-side) to: sizeonly.rcc.001 2021/03/23 05:36:34 INFO : sizeonly.rcc.002_evg06e: Moved (server-side) to: sizeonly.rcc.002 2021/03/23 05:36:34 INFO : sizeonly: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 DEBUG : sizeonly: Sizes identical 2021/03/23 05:36:34 DEBUG : sizeonly: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (0.02s) === RUN TestSyncIgnoreSize run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : ignore-size: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : ignore-size.rcc.001_evg0g4: Moved (server-side) to: ignore-size.rcc.001 2021/03/23 05:36:34 INFO : ignore-size.rcc.002_evg0g4: Moved (server-side) to: ignore-size.rcc.002 2021/03/23 05:36:34 INFO : ignore-size.rcc.003_evg0g4: Moved (server-side) to: ignore-size.rcc.003 2021/03/23 05:36:34 INFO : ignore-size: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : ignore-size: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (0.02s) === RUN TestSyncIgnoreTimes run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : existing.rcc.001_evg0m9: Moved (server-side) to: existing.rcc.001 2021/03/23 05:36:34 INFO : existing.rcc.002_evg0m9: Moved (server-side) to: existing.rcc.002 2021/03/23 05:36:34 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : existing: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : There was nothing to transfer 2021/03/23 05:36:34 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2021/03/23 05:36:34 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : existing.rcc.001_evg0v2: Moved (server-side) to: existing.rcc.001 2021/03/23 05:36:34 INFO : existing.rcc.002_evg0v2: Moved (server-side) to: existing.rcc.002 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 INFO : existing: Copied (replaced existing) 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (0.01s) === RUN TestSyncIgnoreExisting run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : existing.rcc.001_evg0q0: Moved (server-side) to: existing.rcc.001 2021/03/23 05:36:34 INFO : existing.rcc.002_evg0q0: Moved (server-side) to: existing.rcc.002 2021/03/23 05:36:34 INFO : existing: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : existing: Destination exists, skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.00s) === RUN TestSyncIgnoreErrors run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : b/potato.rcc.001_evg0qh: Moved (server-side) to: b/potato.rcc.001 2021/03/23 05:36:34 INFO : b/potato.rcc.002_evg0qh: Moved (server-side) to: b/potato.rcc.002 2021/03/23 05:36:34 INFO : b/potato.rcc.003_evg0qh: Moved (server-side) to: b/potato.rcc.003 2021/03/23 05:36:34 INFO : b/potato.rcc.004_evg0qh: Moved (server-side) to: b/potato.rcc.004 2021/03/23 05:36:34 INFO : b/potato.rcc.005_evg0qh: Moved (server-side) to: b/potato.rcc.005 2021/03/23 05:36:34 INFO : b/potato.rcc.006_evg0qh: Moved (server-side) to: b/potato.rcc.006 2021/03/23 05:36:34 INFO : b/potato.rcc.007_evg0qh: Moved (server-side) to: b/potato.rcc.007 2021/03/23 05:36:34 INFO : c/non empty space.rcc.001_evg03v: Moved (server-side) to: c/non empty space.rcc.001 2021/03/23 05:36:34 INFO : c/non empty space.rcc.002_evg03v: Moved (server-side) to: c/non empty space.rcc.002 2021/03/23 05:36:34 DEBUG : d: Making directory 2021/03/23 05:36:34 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : a/potato2.rcc.001_evg0j3: Moved (server-side) to: a/potato2.rcc.001 2021/03/23 05:36:34 INFO : a/potato2.rcc.002_evg0j3: Moved (server-side) to: a/potato2.rcc.002 2021/03/23 05:36:34 INFO : a/potato2.rcc.003_evg0j3: Moved (server-side) to: a/potato2.rcc.003 2021/03/23 05:36:34 INFO : a/potato2.rcc.004_evg0j3: Moved (server-side) to: a/potato2.rcc.004 2021/03/23 05:36:34 INFO : a/potato2.rcc.005_evg0j3: Moved (server-side) to: a/potato2.rcc.005 2021/03/23 05:36:34 INFO : a/potato2.rcc.006_evg0j3: Moved (server-side) to: a/potato2.rcc.006 2021/03/23 05:36:34 INFO : a/potato2.rcc.007_evg0j3: Moved (server-side) to: a/potato2.rcc.007 2021/03/23 05:36:34 INFO : a/potato2.rcc.008_evg0j3: Moved (server-side) to: a/potato2.rcc.008 2021/03/23 05:36:34 INFO : a/potato2.rcc.009_evg0j3: Moved (server-side) to: a/potato2.rcc.009 2021/03/23 05:36:34 INFO : a/potato2.rcc.010_evg0j3: Moved (server-side) to: a/potato2.rcc.010 2021/03/23 05:36:34 INFO : a/potato2.rcc.011_evg0j3: Moved (server-side) to: a/potato2.rcc.011 2021/03/23 05:36:34 INFO : a/potato2.rcc.012_evg0j3: Moved (server-side) to: a/potato2.rcc.012 2021/03/23 05:36:34 INFO : a/potato2.rcc.013_evg0j3: Moved (server-side) to: a/potato2.rcc.013 2021/03/23 05:36:34 INFO : a/potato2.rcc.014_evg0j3: Moved (server-side) to: a/potato2.rcc.014 2021/03/23 05:36:34 INFO : a/potato2.rcc.015_evg0j3: Moved (server-side) to: a/potato2.rcc.015 2021/03/23 05:36:34 INFO : a/potato2.rcc.016_evg0j3: Moved (server-side) to: a/potato2.rcc.016 2021/03/23 05:36:34 INFO : a/potato2.rcc.017_evg0j3: Moved (server-side) to: a/potato2.rcc.017 2021/03/23 05:36:34 INFO : a/potato2.rcc.018_evg0j3: Moved (server-side) to: a/potato2.rcc.018 2021/03/23 05:36:34 INFO : a/potato2.rcc.019_evg0j3: Moved (server-side) to: a/potato2.rcc.019 2021/03/23 05:36:34 INFO : a/potato2.rcc.020_evg0j3: Moved (server-side) to: a/potato2.rcc.020 2021/03/23 05:36:34 INFO : a/potato2: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : b/potato: Deleted 2021/03/23 05:36:34 DEBUG : d: Removing directory 2021/03/23 05:36:34 DEBUG : b: Removing directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': deleted 2 directories --- PASS: TestSyncIgnoreErrors (0.03s) === RUN TestSyncAfterChangingModtimeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : empty space.rcc.001_evg0ji: Moved (server-side) to: empty space 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 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/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 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/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : empty space: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : empty space: Deleted 2021/03/23 05:36:34 INFO : empty space.rcc.001_evg0q7: Moved (server-side) to: empty space 2021/03/23 05:36:34 INFO : empty space: Copied (replaced existing) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (0.01s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:591: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.00s) === RUN TestSyncDoesntUpdateModtime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : foo.rcc.001_evg0vx: Moved (server-side) to: foo 2021/03/23 05:36:34 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/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : foo: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : foo: Deleted 2021/03/23 05:36:34 INFO : foo.rcc.001_evg0yw: Moved (server-side) to: foo 2021/03/23 05:36:34 INFO : foo: Copied (replaced existing) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (0.01s) === RUN TestSyncAfterAddingAFile run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : empty space.rcc.001_evg065: Moved (server-side) to: empty space 2021/03/23 05:36:34 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : potato.rcc.001_evg00s: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg00s: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg00s: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg00s: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg00s: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg00s: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg00s: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : potato.rcc.008_evg00s: Moved (server-side) to: potato.rcc.008 2021/03/23 05:36:34 INFO : potato.rcc.009_evg00s: Moved (server-side) to: potato.rcc.009 2021/03/23 05:36:34 INFO : potato.rcc.010_evg00s: Moved (server-side) to: potato.rcc.010 2021/03/23 05:36:34 INFO : potato.rcc.011_evg00s: Moved (server-side) to: potato.rcc.011 2021/03/23 05:36:34 INFO : potato.rcc.012_evg00s: Moved (server-side) to: potato.rcc.012 2021/03/23 05:36:34 INFO : potato.rcc.013_evg00s: Moved (server-side) to: potato.rcc.013 2021/03/23 05:36:34 INFO : potato.rcc.014_evg00s: Moved (server-side) to: potato.rcc.014 2021/03/23 05:36:34 INFO : potato.rcc.015_evg00s: Moved (server-side) to: potato.rcc.015 2021/03/23 05:36:34 INFO : potato.rcc.016_evg00s: Moved (server-side) to: potato.rcc.016 2021/03/23 05:36:34 INFO : potato.rcc.017_evg00s: Moved (server-side) to: potato.rcc.017 2021/03/23 05:36:34 INFO : potato.rcc.018_evg00s: Moved (server-side) to: potato.rcc.018 2021/03/23 05:36:34 INFO : potato.rcc.019_evg00s: Moved (server-side) to: potato.rcc.019 2021/03/23 05:36:34 INFO : potato.rcc.020_evg00s: Moved (server-side) to: potato.rcc.020 2021/03/23 05:36:34 INFO : potato: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (0.04s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : potato.rcc.001_evg0o3: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg0o3: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg0o3: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg0o3: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg0o3: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg0o3: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg0o3: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : potato.rcc.008_evg0o3: Moved (server-side) to: potato.rcc.008 2021/03/23 05:36:34 INFO : potato.rcc.009_evg0o3: Moved (server-side) to: potato.rcc.009 2021/03/23 05:36:34 INFO : potato.rcc.010_evg0o3: Moved (server-side) to: potato.rcc.010 2021/03/23 05:36:34 INFO : potato.rcc.011_evg0o3: Moved (server-side) to: potato.rcc.011 2021/03/23 05:36:34 INFO : potato.rcc.012_evg0o3: Moved (server-side) to: potato.rcc.012 2021/03/23 05:36:34 INFO : potato.rcc.013_evg0o3: Moved (server-side) to: potato.rcc.013 2021/03/23 05:36:34 INFO : potato.rcc.014_evg0o3: Moved (server-side) to: potato.rcc.014 2021/03/23 05:36:34 INFO : potato.rcc.015_evg0o3: Moved (server-side) to: potato.rcc.015 2021/03/23 05:36:34 INFO : potato.rcc.016_evg0o3: Moved (server-side) to: potato.rcc.016 2021/03/23 05:36:34 INFO : potato.rcc.017_evg0o3: Moved (server-side) to: potato.rcc.017 2021/03/23 05:36:34 INFO : potato.rcc.018_evg0o3: Moved (server-side) to: potato.rcc.018 2021/03/23 05:36:34 INFO : potato.rcc.019_evg0o3: Moved (server-side) to: potato.rcc.019 2021/03/23 05:36:34 INFO : potato.rcc.020_evg0o3: Moved (server-side) to: potato.rcc.020 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : potato.rcc.001_evg0od: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg0od: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg0od: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg0od: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg0od: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg0od: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg0od: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : potato: Copied (replaced existing) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (0.02s) === RUN TestSyncAfterChangingContentsOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : potato.rcc.001_evg0l9: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg0l9: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg0l9: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg0l9: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg0l9: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg0l9: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg0l9: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 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/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : potato.rcc.001_evg01z: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg01z: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg01z: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg01z: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg01z: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg01z: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg01z: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : potato: Copied (replaced existing) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (0.02s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : potato.rcc.001_evg02j: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg02j: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg02j: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg02j: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg02j: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg02j: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg02j: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : empty space.rcc.001_evg0dt: Moved (server-side) to: empty space 2021/03/23 05:36:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 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-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : potato.rcc.001_evg0mh: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:34 INFO : potato.rcc.002_evg0mh: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:34 INFO : potato.rcc.003_evg0mh: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:34 INFO : potato.rcc.004_evg0mh: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:34 INFO : potato.rcc.005_evg0mh: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:34 INFO : potato.rcc.006_evg0mh: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:34 INFO : potato.rcc.007_evg0mh: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:34 INFO : empty space.rcc.001_evg04j: Moved (server-side) to: empty space 2021/03/23 05:36:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 INFO : potato2.rcc.001_evg0bo: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:34 INFO : potato2.rcc.002_evg0bo: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:34 INFO : potato2.rcc.003_evg0bo: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:34 INFO : potato2.rcc.004_evg0bo: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:34 INFO : potato2.rcc.005_evg0bo: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:34 INFO : potato2.rcc.006_evg0bo: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:34 INFO : potato2.rcc.007_evg0bo: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:34 INFO : potato2.rcc.008_evg0bo: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:34 INFO : potato2.rcc.009_evg0bo: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:34 INFO : potato2.rcc.010_evg0bo: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:34 INFO : potato2.rcc.011_evg0bo: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:34 INFO : potato2.rcc.012_evg0bo: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:34 INFO : potato2.rcc.013_evg0bo: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:34 INFO : potato2.rcc.014_evg0bo: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:34 INFO : potato2.rcc.015_evg0bo: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:34 INFO : potato2.rcc.016_evg0bo: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:34 INFO : potato2.rcc.017_evg0bo: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:34 INFO : potato2.rcc.018_evg0bo: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:34 INFO : potato2.rcc.019_evg0bo: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:34 INFO : potato2.rcc.020_evg0bo: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:34 INFO : potato2: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (0.04s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:34 INFO : b/potato.rcc.001_evg01p: Moved (server-side) to: b/potato.rcc.001 2021/03/23 05:36:34 INFO : b/potato.rcc.002_evg01p: Moved (server-side) to: b/potato.rcc.002 2021/03/23 05:36:34 INFO : b/potato.rcc.003_evg01p: Moved (server-side) to: b/potato.rcc.003 2021/03/23 05:36:34 INFO : b/potato.rcc.004_evg01p: Moved (server-side) to: b/potato.rcc.004 2021/03/23 05:36:34 INFO : b/potato.rcc.005_evg01p: Moved (server-side) to: b/potato.rcc.005 2021/03/23 05:36:34 INFO : b/potato.rcc.006_evg01p: Moved (server-side) to: b/potato.rcc.006 2021/03/23 05:36:34 INFO : b/potato.rcc.007_evg01p: Moved (server-side) to: b/potato.rcc.007 2021/03/23 05:36:34 INFO : c/non empty space.rcc.001_evg0f8: Moved (server-side) to: c/non empty space.rcc.001 2021/03/23 05:36:34 INFO : c/non empty space.rcc.002_evg0f8: Moved (server-side) to: c/non empty space.rcc.002 2021/03/23 05:36:34 DEBUG : d: Making directory 2021/03/23 05:36:34 DEBUG : d/e: Making directory 2021/03/23 05:36:34 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:34 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:34 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:34 INFO : a/potato2.rcc.001_evg0s9: Moved (server-side) to: a/potato2.rcc.001 2021/03/23 05:36:34 INFO : a/potato2.rcc.002_evg0s9: Moved (server-side) to: a/potato2.rcc.002 2021/03/23 05:36:34 INFO : a/potato2.rcc.003_evg0s9: Moved (server-side) to: a/potato2.rcc.003 2021/03/23 05:36:34 INFO : a/potato2.rcc.004_evg0s9: Moved (server-side) to: a/potato2.rcc.004 2021/03/23 05:36:34 INFO : a/potato2.rcc.005_evg0s9: Moved (server-side) to: a/potato2.rcc.005 2021/03/23 05:36:34 INFO : a/potato2.rcc.006_evg0s9: Moved (server-side) to: a/potato2.rcc.006 2021/03/23 05:36:34 INFO : a/potato2.rcc.007_evg0s9: Moved (server-side) to: a/potato2.rcc.007 2021/03/23 05:36:34 INFO : a/potato2.rcc.008_evg0s9: Moved (server-side) to: a/potato2.rcc.008 2021/03/23 05:36:34 INFO : a/potato2.rcc.009_evg0s9: Moved (server-side) to: a/potato2.rcc.009 2021/03/23 05:36:34 INFO : a/potato2.rcc.010_evg0s9: Moved (server-side) to: a/potato2.rcc.010 2021/03/23 05:36:34 INFO : a/potato2.rcc.011_evg0s9: Moved (server-side) to: a/potato2.rcc.011 2021/03/23 05:36:34 INFO : a/potato2.rcc.012_evg0s9: Moved (server-side) to: a/potato2.rcc.012 2021/03/23 05:36:34 INFO : a/potato2.rcc.013_evg0s9: Moved (server-side) to: a/potato2.rcc.013 2021/03/23 05:36:34 INFO : a/potato2.rcc.014_evg0s9: Moved (server-side) to: a/potato2.rcc.014 2021/03/23 05:36:34 INFO : a/potato2.rcc.015_evg0s9: Moved (server-side) to: a/potato2.rcc.015 2021/03/23 05:36:34 INFO : a/potato2.rcc.016_evg0s9: Moved (server-side) to: a/potato2.rcc.016 2021/03/23 05:36:34 INFO : a/potato2.rcc.017_evg0s9: Moved (server-side) to: a/potato2.rcc.017 2021/03/23 05:36:34 INFO : a/potato2.rcc.018_evg0s9: Moved (server-side) to: a/potato2.rcc.018 2021/03/23 05:36:34 INFO : a/potato2.rcc.019_evg0s9: Moved (server-side) to: a/potato2.rcc.019 2021/03/23 05:36:34 INFO : a/potato2.rcc.020_evg0s9: Moved (server-side) to: a/potato2.rcc.020 2021/03/23 05:36:34 INFO : a/potato2: Copied (new) 2021/03/23 05:36:34 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:34 INFO : b/potato: Deleted 2021/03/23 05:36:34 DEBUG : d/e: Removing directory 2021/03/23 05:36:34 DEBUG : d: Removing directory 2021/03/23 05:36:34 DEBUG : b: Removing directory 2021/03/23 05:36:34 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': deleted 3 directories --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (0.03s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : b/potato.rcc.001_evg12f: Moved (server-side) to: b/potato.rcc.001 2021/03/23 05:36:35 INFO : b/potato.rcc.002_evg12f: Moved (server-side) to: b/potato.rcc.002 2021/03/23 05:36:35 INFO : b/potato.rcc.003_evg12f: Moved (server-side) to: b/potato.rcc.003 2021/03/23 05:36:35 INFO : b/potato.rcc.004_evg12f: Moved (server-side) to: b/potato.rcc.004 2021/03/23 05:36:35 INFO : b/potato.rcc.005_evg12f: Moved (server-side) to: b/potato.rcc.005 2021/03/23 05:36:35 INFO : b/potato.rcc.006_evg12f: Moved (server-side) to: b/potato.rcc.006 2021/03/23 05:36:35 INFO : b/potato.rcc.007_evg12f: Moved (server-side) to: b/potato.rcc.007 2021/03/23 05:36:35 INFO : c/non empty space.rcc.001_evg18o: Moved (server-side) to: c/non empty space.rcc.001 2021/03/23 05:36:35 INFO : c/non empty space.rcc.002_evg18o: Moved (server-side) to: c/non empty space.rcc.002 2021/03/23 05:36:35 DEBUG : d: Making directory 2021/03/23 05:36:35 DEBUG : a/potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : c/non empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : a/potato2.rcc.001_evg1ju: Moved (server-side) to: a/potato2.rcc.001 2021/03/23 05:36:35 INFO : a/potato2.rcc.002_evg1ju: Moved (server-side) to: a/potato2.rcc.002 2021/03/23 05:36:35 INFO : a/potato2.rcc.003_evg1ju: Moved (server-side) to: a/potato2.rcc.003 2021/03/23 05:36:35 INFO : a/potato2.rcc.004_evg1ju: Moved (server-side) to: a/potato2.rcc.004 2021/03/23 05:36:35 INFO : a/potato2.rcc.005_evg1ju: Moved (server-side) to: a/potato2.rcc.005 2021/03/23 05:36:35 INFO : a/potato2.rcc.006_evg1ju: Moved (server-side) to: a/potato2.rcc.006 2021/03/23 05:36:35 INFO : a/potato2.rcc.007_evg1ju: Moved (server-side) to: a/potato2.rcc.007 2021/03/23 05:36:35 INFO : a/potato2.rcc.008_evg1ju: Moved (server-side) to: a/potato2.rcc.008 2021/03/23 05:36:35 INFO : a/potato2.rcc.009_evg1ju: Moved (server-side) to: a/potato2.rcc.009 2021/03/23 05:36:35 INFO : a/potato2.rcc.010_evg1ju: Moved (server-side) to: a/potato2.rcc.010 2021/03/23 05:36:35 INFO : a/potato2.rcc.011_evg1ju: Moved (server-side) to: a/potato2.rcc.011 2021/03/23 05:36:35 INFO : a/potato2.rcc.012_evg1ju: Moved (server-side) to: a/potato2.rcc.012 2021/03/23 05:36:35 INFO : a/potato2.rcc.013_evg1ju: Moved (server-side) to: a/potato2.rcc.013 2021/03/23 05:36:35 INFO : a/potato2.rcc.014_evg1ju: Moved (server-side) to: a/potato2.rcc.014 2021/03/23 05:36:35 INFO : a/potato2.rcc.015_evg1ju: Moved (server-side) to: a/potato2.rcc.015 2021/03/23 05:36:35 INFO : a/potato2.rcc.016_evg1ju: Moved (server-side) to: a/potato2.rcc.016 2021/03/23 05:36:35 INFO : a/potato2.rcc.017_evg1ju: Moved (server-side) to: a/potato2.rcc.017 2021/03/23 05:36:35 INFO : a/potato2.rcc.018_evg1ju: Moved (server-side) to: a/potato2.rcc.018 2021/03/23 05:36:35 INFO : a/potato2.rcc.019_evg1ju: Moved (server-side) to: a/potato2.rcc.019 2021/03/23 05:36:35 INFO : a/potato2.rcc.020_evg1ju: Moved (server-side) to: a/potato2.rcc.020 2021/03/23 05:36:35 INFO : a/potato2: Copied (new) 2021/03/23 05:36:35 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': not deleting files as there were IO errors 2021/03/23 05:36:35 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': not deleting directories as there were IO errors --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (0.03s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : potato.rcc.001_evg1vd: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.002_evg1vd: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg1vd: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : potato.rcc.004_evg1vd: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg1vd: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : potato.rcc.006_evg1vd: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:35 INFO : potato.rcc.007_evg1vd: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1yh: Moved (server-side) to: empty space 2021/03/23 05:36:35 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg1rk: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg1rk: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg1rk: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg1rk: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg1rk: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg1rk: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg1rk: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg1rk: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg1rk: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg1rk: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg1rk: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg1rk: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg1rk: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg1rk: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato: Deleted 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg1rk: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg1rk: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg1rk: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg1rk: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg1rk: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg1rk: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : potato2: Copied (new) 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish --- PASS: TestSyncDeleteDuring (0.04s) === RUN TestSyncDeleteBefore run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : potato.rcc.001_evg18e: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.002_evg18e: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg18e: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : potato.rcc.004_evg18e: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg18e: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : potato.rcc.006_evg18e: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:35 INFO : potato.rcc.007_evg18e: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1h0: Moved (server-side) to: empty space 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : potato: Deleted 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg1da: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg1da: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg1da: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg1da: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg1da: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg1da: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg1da: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg1da: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg1da: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg1da: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg1da: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg1da: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg1da: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg1da: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg1da: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg1da: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg1da: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg1da: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg1da: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg1da: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (0.03s) === RUN TestCopyDeleteBefore run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : potato.rcc.001_evg1kg: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.002_evg1kg: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg1kg: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : potato.rcc.004_evg1kg: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg1kg: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : potato.rcc.006_evg1kg: Moved (server-side) to: potato.rcc.006 2021/03/23 05:36:35 INFO : potato.rcc.007_evg1kg: Moved (server-side) to: potato.rcc.007 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : potato2: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg1bk: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg1bk: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg1bk: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg1bk: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg1bk: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg1bk: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg1bk: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (0.02s) === RUN TestSyncWithExclude run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg1n1: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg1n1: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg1n1: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg1n1: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg1n1: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg1n1: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg1n1: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg1n1: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg1n1: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg1n1: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg1n1: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg1n1: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg1n1: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg1n1: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg1n1: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg1n1: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg1n1: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg1n1: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg1n1: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg1n1: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1r3: Moved (server-side) to: empty space 2021/03/23 05:36:35 DEBUG : enormous: Excluded 2021/03/23 05:36:35 DEBUG : potato2: Excluded 2021/03/23 05:36:35 DEBUG : potato2: Excluded 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : There was nothing to transfer 2021/03/23 05:36:35 DEBUG : enormous: Excluded 2021/03/23 05:36:35 DEBUG : potato2: Excluded 2021/03/23 05:36:35 DEBUG : potato2: Excluded 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Local file system at /tmp/rclone944960434: Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Local file system at /tmp/rclone944960434: Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (0.04s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg11q: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg11q: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg11q: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg11q: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg11q: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg11q: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg11q: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg11q: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg11q: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg11q: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg11q: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg11q: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg11q: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg11q: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg11q: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg11q: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg11q: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg11q: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg11q: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg11q: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1cp: Moved (server-side) to: empty space 2021/03/23 05:36:35 INFO : enormous.rcc.001_evg1pn: Moved (server-side) to: enormous.rcc.001 2021/03/23 05:36:35 INFO : enormous.rcc.002_evg1pn: Moved (server-side) to: enormous.rcc.002 2021/03/23 05:36:35 INFO : enormous.rcc.003_evg1pn: Moved (server-side) to: enormous.rcc.003 2021/03/23 05:36:35 INFO : enormous.rcc.004_evg1pn: Moved (server-side) to: enormous.rcc.004 2021/03/23 05:36:35 INFO : enormous.rcc.005_evg1pn: Moved (server-side) to: enormous.rcc.005 2021/03/23 05:36:35 INFO : enormous.rcc.006_evg1pn: Moved (server-side) to: enormous.rcc.006 2021/03/23 05:36:35 INFO : enormous.rcc.007_evg1pn: Moved (server-side) to: enormous.rcc.007 2021/03/23 05:36:35 INFO : enormous.rcc.008_evg1pn: Moved (server-side) to: enormous.rcc.008 2021/03/23 05:36:35 INFO : enormous.rcc.009_evg1pn: Moved (server-side) to: enormous.rcc.009 2021/03/23 05:36:35 INFO : enormous.rcc.010_evg1pn: Moved (server-side) to: enormous.rcc.010 2021/03/23 05:36:35 INFO : enormous.rcc.011_evg1pn: Moved (server-side) to: enormous.rcc.011 2021/03/23 05:36:35 INFO : enormous.rcc.012_evg1pn: Moved (server-side) to: enormous.rcc.012 2021/03/23 05:36:35 INFO : enormous.rcc.013_evg1pn: Moved (server-side) to: enormous.rcc.013 2021/03/23 05:36:35 INFO : enormous.rcc.014_evg1pn: Moved (server-side) to: enormous.rcc.014 2021/03/23 05:36:35 INFO : enormous.rcc.015_evg1pn: Moved (server-side) to: enormous.rcc.015 2021/03/23 05:36:35 INFO : enormous.rcc.016_evg1pn: Moved (server-side) to: enormous.rcc.016 2021/03/23 05:36:35 INFO : enormous.rcc.017_evg1pn: Moved (server-side) to: enormous.rcc.017 2021/03/23 05:36:35 INFO : enormous.rcc.018_evg1pn: Moved (server-side) to: enormous.rcc.018 2021/03/23 05:36:35 INFO : enormous.rcc.019_evg1pn: Moved (server-side) to: enormous.rcc.019 2021/03/23 05:36:35 INFO : enormous.rcc.020_evg1pn: Moved (server-side) to: enormous.rcc.020 2021/03/23 05:36:35 INFO : enormous.rcc.021_evg1pn: Moved (server-side) to: enormous.rcc.021 2021/03/23 05:36:35 INFO : enormous.rcc.022_evg1pn: Moved (server-side) to: enormous.rcc.022 2021/03/23 05:36:35 INFO : enormous.rcc.023_evg1pn: Moved (server-side) to: enormous.rcc.023 2021/03/23 05:36:35 INFO : enormous.rcc.024_evg1pn: Moved (server-side) to: enormous.rcc.024 2021/03/23 05:36:35 INFO : enormous.rcc.025_evg1pn: Moved (server-side) to: enormous.rcc.025 2021/03/23 05:36:35 INFO : enormous.rcc.026_evg1pn: Moved (server-side) to: enormous.rcc.026 2021/03/23 05:36:35 INFO : enormous.rcc.027_evg1pn: Moved (server-side) to: enormous.rcc.027 2021/03/23 05:36:35 INFO : enormous.rcc.028_evg1pn: Moved (server-side) to: enormous.rcc.028 2021/03/23 05:36:35 INFO : enormous.rcc.029_evg1pn: Moved (server-side) to: enormous.rcc.029 2021/03/23 05:36:35 INFO : enormous.rcc.030_evg1pn: Moved (server-side) to: enormous.rcc.030 2021/03/23 05:36:35 INFO : enormous.rcc.031_evg1pn: Moved (server-side) to: enormous.rcc.031 2021/03/23 05:36:35 INFO : enormous.rcc.032_evg1pn: Moved (server-side) to: enormous.rcc.032 2021/03/23 05:36:35 INFO : enormous.rcc.033_evg1pn: Moved (server-side) to: enormous.rcc.033 2021/03/23 05:36:35 INFO : enormous.rcc.034_evg1pn: Moved (server-side) to: enormous.rcc.034 2021/03/23 05:36:35 DEBUG : enormous: Excluded 2021/03/23 05:36:35 DEBUG : potato2: Excluded 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : potato2: Deleted 2021/03/23 05:36:35 INFO : enormous: Deleted 2021/03/23 05:36:35 INFO : There was nothing to transfer 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Local file system at /tmp/rclone944960434: Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Local file system at /tmp/rclone944960434: Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : potato2: Deleted 2021/03/23 05:36:35 INFO : enormous: Deleted 2021/03/23 05:36:35 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (0.06s) === RUN TestSyncWithUpdateOlder run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 INFO : one.rcc.001_evg1nh: Moved (server-side) to: one 2021/03/23 05:36:35 INFO : two.rcc.001_evg15m: Moved (server-side) to: two 2021/03/23 05:36:35 INFO : three.rcc.001_evg1ro: Moved (server-side) to: three.rcc.001 2021/03/23 05:36:35 INFO : three.rcc.002_evg1ro: Moved (server-side) to: three.rcc.002 2021/03/23 05:36:35 INFO : four.rcc.001_evg14s: Moved (server-side) to: four.rcc.001 2021/03/23 05:36:35 INFO : four.rcc.002_evg14s: Moved (server-side) to: four.rcc.002 2021/03/23 05:36:35 INFO : four.rcc.003_evg14s: Moved (server-side) to: four.rcc.003 2021/03/23 05:36:35 DEBUG : five: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : four: Sizes differ (src 4 vs dst 8) 2021/03/23 05:36:35 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2021/03/23 05:36:35 INFO : five.rcc.001_evg16n: Moved (server-side) to: five.rcc.001 2021/03/23 05:36:35 INFO : five.rcc.002_evg16n: Moved (server-side) to: five.rcc.002 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 INFO : five: Copied (new) 2021/03/23 05:36:35 DEBUG : one: Destination is newer than source, skipping 2021/03/23 05:36:35 DEBUG : three: Sizes identical 2021/03/23 05:36:35 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : two: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : four: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : two: Deleted 2021/03/23 05:36:35 INFO : two.rcc.001_evg143: Moved (server-side) to: two 2021/03/23 05:36:35 INFO : two: Copied (replaced existing) 2021/03/23 05:36:35 INFO : four.rcc.001_evg1wp: Moved (server-side) to: four.rcc.001 2021/03/23 05:36:35 INFO : four.rcc.002_evg1wp: Moved (server-side) to: four.rcc.002 2021/03/23 05:36:35 INFO : four: Copied (replaced existing) 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish sync_test.go:1022: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (0.04s) === RUN TestSyncWithMaxDuration sync_test.go:1039: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1088: Can track renames: false 2021/03/23 05:36:35 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Ignoring --track-renames as the source and destination do not have a common hash 2021/03/23 05:36:35 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : yam: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : yam.rcc.001_evg15k: Moved (server-side) to: yam.rcc.001 2021/03/23 05:36:35 INFO : yam.rcc.002_evg15k: Moved (server-side) to: yam.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.001_evg1yr: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : yam.rcc.003_evg15k: Moved (server-side) to: yam.rcc.003 2021/03/23 05:36:35 INFO : yam.rcc.004_evg15k: Moved (server-side) to: yam.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.002_evg1yr: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg1yr: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : potato.rcc.004_evg1yr: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg1yr: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : potato: Copied (new) 2021/03/23 05:36:35 INFO : yam: Copied (new) 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Ignoring --track-renames as the source and destination do not have a common hash 2021/03/23 05:36:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : potato: Unchanged skipping 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : yaml: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : yaml.rcc.001_evg118: Moved (server-side) to: yaml.rcc.001 2021/03/23 05:36:35 INFO : yaml.rcc.002_evg118: Moved (server-side) to: yaml.rcc.002 2021/03/23 05:36:35 INFO : yaml.rcc.003_evg118: Moved (server-side) to: yaml.rcc.003 2021/03/23 05:36:35 INFO : yaml.rcc.004_evg118: Moved (server-side) to: yaml.rcc.004 2021/03/23 05:36:35 INFO : yaml: Copied (new) 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (0.01s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1157: Can track renames: true 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Making map for --track-renames 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Finished making map for --track-renames 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : yam: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for renames to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : potato.rcc.001_evg1va: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.002_evg1va: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg1va: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : potato.rcc.004_evg1va: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg1va: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : yam.rcc.001_evg14v: Moved (server-side) to: yam.rcc.001 2021/03/23 05:36:35 INFO : potato: Copied (new) 2021/03/23 05:36:35 INFO : yam.rcc.002_evg14v: Moved (server-side) to: yam.rcc.002 2021/03/23 05:36:35 INFO : yam.rcc.003_evg14v: Moved (server-side) to: yam.rcc.003 2021/03/23 05:36:35 INFO : yam.rcc.004_evg14v: Moved (server-side) to: yam.rcc.004 2021/03/23 05:36:35 INFO : yam: Copied (new) 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Making map for --track-renames 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Finished making map for --track-renames 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : potato: Unchanged skipping 2021/03/23 05:36:35 DEBUG : yam: move 4 data chunks... 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for renames to finish 2021/03/23 05:36:35 INFO : yam.rcc.001: Moved (server-side) to: yaml.rcc.001 2021/03/23 05:36:35 INFO : yam.rcc.002: Moved (server-side) to: yaml.rcc.002 2021/03/23 05:36:35 INFO : yam.rcc.003: Moved (server-side) to: yaml.rcc.003 2021/03/23 05:36:35 INFO : yam.rcc.004: Moved (server-side) to: yaml.rcc.004 2021/03/23 05:36:35 INFO : yam: Moved (server-side) to: yaml 2021/03/23 05:36:35 INFO : yam: Moved (server-side) to: yaml 2021/03/23 05:36:35 INFO : yaml: Renamed from "yam" 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyModtime (0.01s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1193: Can track renames: true 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Making map for --track-renames 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Finished making map for --track-renames 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : potato: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : sub/yam: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : sub/yam.rcc.001_evg1ab: Moved (server-side) to: sub/yam.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.001_evg1yl: Moved (server-side) to: potato.rcc.001 2021/03/23 05:36:35 INFO : potato.rcc.002_evg1yl: Moved (server-side) to: potato.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.003_evg1yl: Moved (server-side) to: potato.rcc.003 2021/03/23 05:36:35 INFO : sub/yam.rcc.002_evg1ab: Moved (server-side) to: sub/yam.rcc.002 2021/03/23 05:36:35 INFO : potato.rcc.004_evg1yl: Moved (server-side) to: potato.rcc.004 2021/03/23 05:36:35 INFO : potato.rcc.005_evg1yl: Moved (server-side) to: potato.rcc.005 2021/03/23 05:36:35 INFO : sub/yam.rcc.003_evg1ab: Moved (server-side) to: sub/yam.rcc.003 2021/03/23 05:36:35 INFO : sub/yam.rcc.004_evg1ab: Moved (server-side) to: sub/yam.rcc.004 2021/03/23 05:36:35 INFO : potato: Copied (new) 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for renames to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : sub/yam: Copied (new) 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : potato: Unchanged skipping 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Making map for --track-renames 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Finished making map for --track-renames 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for renames to finish 2021/03/23 05:36:35 DEBUG : sub/yam: move 4 data chunks... 2021/03/23 05:36:35 INFO : sub/yam.rcc.001: Moved (server-side) to: yam.rcc.001 2021/03/23 05:36:35 INFO : sub/yam.rcc.002: Moved (server-side) to: yam.rcc.002 2021/03/23 05:36:35 INFO : sub/yam.rcc.003: Moved (server-side) to: yam.rcc.003 2021/03/23 05:36:35 INFO : sub/yam.rcc.004: Moved (server-side) to: yam.rcc.004 2021/03/23 05:36:35 INFO : sub/yam: Moved (server-side) to: yam 2021/03/23 05:36:35 INFO : sub/yam: Moved (server-side) to: yam 2021/03/23 05:36:35 INFO : yam: Renamed from "sub/yam" 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:35 INFO : There was nothing to transfer --- PASS: TestSyncWithTrackRenamesStrategyLeaf (0.01s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : nested/sub dir/file: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.001_evg1lo: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.002_evg1lo: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.003_evg1lo: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:35 INFO : nested/sub dir/file.rcc.001_evg11u: Moved (server-side) to: nested/sub dir/file.rcc.001 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.004_evg1lo: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:35 INFO : nested/sub dir/file.rcc.002_evg11u: Moved (server-side) to: nested/sub dir/file.rcc.002 2021/03/23 05:36:35 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:35 INFO : sub dir/hello world: Deleted 2021/03/23 05:36:35 INFO : nested/sub dir/file: Copied (new) 2021/03/23 05:36:35 INFO : nested/sub dir/file: Deleted 2021/03/23 05:36:35 DEBUG : sub dir: Removing directory 2021/03/23 05:36:35 DEBUG : nested/sub dir: Removing directory 2021/03/23 05:36:35 DEBUG : nested: Removing directory 2021/03/23 05:36:35 DEBUG : Local file system at /tmp/rclone944960434: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.00s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 DEBUG : sub dir/hello world: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 DEBUG : nested/sub dir/file: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.001_evg1p2: Moved (server-side) to: sub dir/hello world.rcc.001 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.002_evg1p2: Moved (server-side) to: sub dir/hello world.rcc.002 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.003_evg1p2: Moved (server-side) to: sub dir/hello world.rcc.003 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : sub dir/hello world.rcc.004_evg1p2: Moved (server-side) to: sub dir/hello world.rcc.004 2021/03/23 05:36:35 INFO : nested/sub dir/file.rcc.001_evg1f1: Moved (server-side) to: nested/sub dir/file.rcc.001 2021/03/23 05:36:35 INFO : nested/sub dir/file.rcc.002_evg1f1: Moved (server-side) to: nested/sub dir/file.rcc.002 2021/03/23 05:36:35 INFO : sub dir/hello world: Copied (new) 2021/03/23 05:36:35 INFO : sub dir/hello world: Deleted 2021/03/23 05:36:35 INFO : nested/sub dir/file: Copied (new) 2021/03/23 05:36:35 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (0.01s) === RUN TestServerSideMove run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/7ld7hb3al63uhtljnqs6hcr70jafjdgh21c2k3ibvi0lv1h0ko46fnkqnhpkp5f7585eksllerf2g" 2021/03/23 05:36:35 DEBUG : Reset feature "ListR" 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg193: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg193: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg193: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg193: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg193: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg193: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg193: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg193: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg193: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg193: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg193: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg193: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg193: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg193: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg193: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg193: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg193: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg193: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg193: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg193: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1yn: Moved (server-side) to: empty space 2021/03/23 05:36:35 INFO : potato3.rcc.001_evg1lq: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:35 INFO : potato3.rcc.002_evg1lq: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:35 INFO : potato3.rcc.003_evg1lq: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:35 INFO : potato3.rcc.004_evg1lq: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:35 INFO : potato3.rcc.005_evg1lq: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:35 INFO : potato3.rcc.006_evg1lq: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:35 INFO : potato3.rcc.007_evg1lq: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:35 INFO : potato3.rcc.008_evg1lq: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:35 INFO : potato3.rcc.009_evg1lq: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:35 INFO : potato3.rcc.010_evg1lq: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:35 INFO : potato3.rcc.011_evg1lq: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:35 INFO : potato3.rcc.012_evg1lq: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:35 INFO : potato3.rcc.013_evg1lq: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:35 INFO : potato3.rcc.014_evg1lq: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:35 INFO : potato3.rcc.015_evg1lq: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:35 INFO : potato3.rcc.016_evg1lq: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:35 INFO : potato3.rcc.017_evg1lq: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:35 INFO : potato3.rcc.018_evg1lq: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:35 INFO : potato3.rcc.019_evg1lq: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:35 INFO : potato3.rcc.020_evg1lq: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:35 INFO : potato3.rcc.021_evg1lq: Moved (server-side) to: potato3.rcc.021 2021/03/23 05:36:35 INFO : potato3.rcc.022_evg1lq: Moved (server-side) to: potato3.rcc.022 2021/03/23 05:36:35 INFO : potato3.rcc.023_evg1lq: Moved (server-side) to: potato3.rcc.023 sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1' -> Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8' 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1ig: Moved (server-side) to: empty space 2021/03/23 05:36:35 INFO : potato3.rcc.001_evg1xx: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:35 INFO : potato3.rcc.002_evg1xx: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:35 INFO : potato3.rcc.003_evg1xx: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:35 INFO : potato3.rcc.004_evg1xx: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:35 INFO : potato3.rcc.005_evg1xx: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:35 INFO : potato3.rcc.006_evg1xx: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:35 INFO : potato3.rcc.007_evg1xx: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:35 INFO : potato3.rcc.008_evg1xx: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:35 INFO : potato3.rcc.009_evg1xx: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:35 INFO : potato3.rcc.010_evg1xx: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:35 INFO : potato3.rcc.011_evg1xx: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:35 INFO : potato3.rcc.012_evg1xx: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:35 INFO : potato3.rcc.013_evg1xx: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:35 INFO : potato3.rcc.014_evg1xx: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:35 INFO : potato3.rcc.015_evg1xx: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:35 INFO : potato3.rcc.016_evg1xx: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:35 INFO : potato3.rcc.017_evg1xx: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:35 INFO : potato3.rcc.018_evg1xx: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:35 INFO : potato3.rcc.019_evg1xx: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:35 INFO : potato3.rcc.020_evg1xx: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8': Using server-side directory move 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/03/23 05:36:35 DEBUG : potato2: move 20 data chunks... 2021/03/23 05:36:35 INFO : potato2.rcc.001: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.004: Moved (server-side) 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:36:35 INFO : potato2.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Deleted 2021/03/23 05:36:35 DEBUG : potato3: move 23 data chunks... 2021/03/23 05:36:35 INFO : potato2.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.012: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.013: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.014: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.015: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.016: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.001: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.017: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.018: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.019: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.020: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.004: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.012: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.013: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2: Moved (server-side) 2021/03/23 05:36:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:35 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:35 INFO : empty space: Deleted 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : potato3.rcc.014: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.015: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.016: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.017: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.018: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.019: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.020: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.021: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.022: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.023: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Moved (server-side) 2021/03/23 05:36:35 INFO : There was nothing to transfer 2021/03/23 05:36:35 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-voquqof6togefop1madijal0" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-voquqof6togefop1madijal0" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/ileq8kejeks4kgg8je2lr4kj6pkf75u9ubrhfbdlr6tgs57dhqh6pgopfb4ns92i4s0nndv3qfeui" 2021/03/23 05:36:35 DEBUG : Reset feature "ListR" 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-voquqof6togefop1madijal0': Using server-side directory move 2021/03/23 05:36:35 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-voquqof6togefop1madijal0': Server side directory move succeeded 2021/03/23 05:36:35 ERROR : : error listing: directory not found 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-voquqof6togefop1madijal0': Purge remote 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vanafuf4cahevel1rusizes8': Purge remote 2021/03/23 05:36:35 purge failed: directory not found --- PASS: TestServerSideMove (0.45s) === RUN TestServerSideMoveWithFilter run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/8f5vq3828h21c8rnbab3i8ak7kr4jec6v5au2jmddg5bbqvtuo5nvt1pqr575no1dvigal6b1gl1g" 2021/03/23 05:36:35 DEBUG : Reset feature "ListR" 2021/03/23 05:36:35 INFO : potato2.rcc.001_evg1t8: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:35 INFO : potato2.rcc.002_evg1t8: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:35 INFO : potato2.rcc.003_evg1t8: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:35 INFO : potato2.rcc.004_evg1t8: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:35 INFO : potato2.rcc.005_evg1t8: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:35 INFO : potato2.rcc.006_evg1t8: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:35 INFO : potato2.rcc.007_evg1t8: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:35 INFO : potato2.rcc.008_evg1t8: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:35 INFO : potato2.rcc.009_evg1t8: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:35 INFO : potato2.rcc.010_evg1t8: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:35 INFO : potato2.rcc.011_evg1t8: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:35 INFO : potato2.rcc.012_evg1t8: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:35 INFO : potato2.rcc.013_evg1t8: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:35 INFO : potato2.rcc.014_evg1t8: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:35 INFO : potato2.rcc.015_evg1t8: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:35 INFO : potato2.rcc.016_evg1t8: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:35 INFO : potato2.rcc.017_evg1t8: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:35 INFO : potato2.rcc.018_evg1t8: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:35 INFO : potato2.rcc.019_evg1t8: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:35 INFO : potato2.rcc.020_evg1t8: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg182: Moved (server-side) to: empty space 2021/03/23 05:36:35 INFO : potato3.rcc.001_evg1u0: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:35 INFO : potato3.rcc.002_evg1u0: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:35 INFO : potato3.rcc.003_evg1u0: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:35 INFO : potato3.rcc.004_evg1u0: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:35 INFO : potato3.rcc.005_evg1u0: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:35 INFO : potato3.rcc.006_evg1u0: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:35 INFO : potato3.rcc.007_evg1u0: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:35 INFO : potato3.rcc.008_evg1u0: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:35 INFO : potato3.rcc.009_evg1u0: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:35 INFO : potato3.rcc.010_evg1u0: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:35 INFO : potato3.rcc.011_evg1u0: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:35 INFO : potato3.rcc.012_evg1u0: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:35 INFO : potato3.rcc.013_evg1u0: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:35 INFO : potato3.rcc.014_evg1u0: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:35 INFO : potato3.rcc.015_evg1u0: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:35 INFO : potato3.rcc.016_evg1u0: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:35 INFO : potato3.rcc.017_evg1u0: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:35 INFO : potato3.rcc.018_evg1u0: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:35 INFO : potato3.rcc.019_evg1u0: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:35 INFO : potato3.rcc.020_evg1u0: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:35 INFO : potato3.rcc.021_evg1u0: Moved (server-side) to: potato3.rcc.021 2021/03/23 05:36:35 INFO : potato3.rcc.022_evg1u0: Moved (server-side) to: potato3.rcc.022 2021/03/23 05:36:35 INFO : potato3.rcc.023_evg1u0: Moved (server-side) to: potato3.rcc.023 sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1' -> Chunked 'TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8' 2021/03/23 05:36:35 INFO : empty space.rcc.001_evg1di: Moved (server-side) to: empty space 2021/03/23 05:36:35 INFO : potato3.rcc.001_evg1fj: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:35 INFO : potato3.rcc.002_evg1fj: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:35 INFO : potato3.rcc.003_evg1fj: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:35 INFO : potato3.rcc.004_evg1fj: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:35 INFO : potato3.rcc.005_evg1fj: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:35 INFO : potato3.rcc.006_evg1fj: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:35 INFO : potato3.rcc.007_evg1fj: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:35 INFO : potato3.rcc.008_evg1fj: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:35 INFO : potato3.rcc.009_evg1fj: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:35 INFO : potato3.rcc.010_evg1fj: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:35 INFO : potato3.rcc.011_evg1fj: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:35 INFO : potato3.rcc.012_evg1fj: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:35 INFO : potato3.rcc.013_evg1fj: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:35 INFO : potato3.rcc.014_evg1fj: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:35 INFO : potato3.rcc.015_evg1fj: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:35 INFO : potato3.rcc.016_evg1fj: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:35 INFO : potato3.rcc.017_evg1fj: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:35 INFO : potato3.rcc.018_evg1fj: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:35 INFO : potato3.rcc.019_evg1fj: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:35 INFO : potato3.rcc.020_evg1fj: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:35 DEBUG : empty space: Excluded 2021/03/23 05:36:35 DEBUG : empty space: Excluded 2021/03/23 05:36:35 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:36:35 DEBUG : potato2: move 20 data chunks... 2021/03/23 05:36:35 INFO : potato2.rcc.001: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.004: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.012: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.013: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.014: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.015: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.016: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.017: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.018: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.019: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.020: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Deleted 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8': Waiting for transfers to finish 2021/03/23 05:36:35 INFO : potato2: Moved (server-side) 2021/03/23 05:36:35 DEBUG : potato3: move 23 data chunks... 2021/03/23 05:36:35 INFO : potato3.rcc.001: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.004: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.012: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.013: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.014: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.015: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.016: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.017: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.018: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.019: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.020: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.021: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.022: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.023: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3: Moved (server-side) 2021/03/23 05:36:35 INFO : There was nothing to transfer 2021/03/23 05:36:35 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-fasovok0tujawuy3riqeqok1" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-fasovok0tujawuy3riqeqok1" 2021/03/23 05:36:35 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/h5eqek2kamfgi5t0nrnu67stv6fknqoujq1jsckbe1gfm0cf92s098vjig31nlf1g5fh50lug4h44" 2021/03/23 05:36:35 DEBUG : Reset feature "ListR" 2021/03/23 05:36:35 DEBUG : empty space: Excluded 2021/03/23 05:36:35 DEBUG : potato2: move 20 data chunks... 2021/03/23 05:36:35 INFO : potato2.rcc.001: Moved (server-side) 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-fasovok0tujawuy3riqeqok1': Waiting for checks to finish 2021/03/23 05:36:35 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-fasovok0tujawuy3riqeqok1': Waiting for transfers to finish 2021/03/23 05:36:35 DEBUG : potato3: move 23 data chunks... 2021/03/23 05:36:35 INFO : potato2.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.001: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.002: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.004: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.003: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.004: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.005: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.006: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.012: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.013: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.007: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.014: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.008: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.015: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.016: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.017: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.018: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.009: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.019: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.010: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.020: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.021: Moved (server-side) 2021/03/23 05:36:35 INFO : potato2.rcc.011: Moved (server-side) 2021/03/23 05:36:35 INFO : potato3.rcc.022: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.012: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.023: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.013: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.014: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.015: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.016: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.017: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.018: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.019: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.020: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2: Moved (server-side) 2021/03/23 05:36:36 INFO : There was nothing to transfer 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-fasovok0tujawuy3riqeqok1': Purge remote 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-zuquwem5cihiruz7yabazuj8': Purge remote --- PASS: TestServerSideMoveWithFilter (0.23s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/bpi7jmqt6kkbahs8r8837mev7dh0qjiugp9jqecd3ku3iik1m5vg907l95mh6mtontiajindf0lec" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 INFO : potato2.rcc.001_evg2r3: Moved (server-side) to: potato2.rcc.001 2021/03/23 05:36:36 INFO : potato2.rcc.002_evg2r3: Moved (server-side) to: potato2.rcc.002 2021/03/23 05:36:36 INFO : potato2.rcc.003_evg2r3: Moved (server-side) to: potato2.rcc.003 2021/03/23 05:36:36 INFO : potato2.rcc.004_evg2r3: Moved (server-side) to: potato2.rcc.004 2021/03/23 05:36:36 INFO : potato2.rcc.005_evg2r3: Moved (server-side) to: potato2.rcc.005 2021/03/23 05:36:36 INFO : potato2.rcc.006_evg2r3: Moved (server-side) to: potato2.rcc.006 2021/03/23 05:36:36 INFO : potato2.rcc.007_evg2r3: Moved (server-side) to: potato2.rcc.007 2021/03/23 05:36:36 INFO : potato2.rcc.008_evg2r3: Moved (server-side) to: potato2.rcc.008 2021/03/23 05:36:36 INFO : potato2.rcc.009_evg2r3: Moved (server-side) to: potato2.rcc.009 2021/03/23 05:36:36 INFO : potato2.rcc.010_evg2r3: Moved (server-side) to: potato2.rcc.010 2021/03/23 05:36:36 INFO : potato2.rcc.011_evg2r3: Moved (server-side) to: potato2.rcc.011 2021/03/23 05:36:36 INFO : potato2.rcc.012_evg2r3: Moved (server-side) to: potato2.rcc.012 2021/03/23 05:36:36 INFO : potato2.rcc.013_evg2r3: Moved (server-side) to: potato2.rcc.013 2021/03/23 05:36:36 INFO : potato2.rcc.014_evg2r3: Moved (server-side) to: potato2.rcc.014 2021/03/23 05:36:36 INFO : potato2.rcc.015_evg2r3: Moved (server-side) to: potato2.rcc.015 2021/03/23 05:36:36 INFO : potato2.rcc.016_evg2r3: Moved (server-side) to: potato2.rcc.016 2021/03/23 05:36:36 INFO : potato2.rcc.017_evg2r3: Moved (server-side) to: potato2.rcc.017 2021/03/23 05:36:36 INFO : potato2.rcc.018_evg2r3: Moved (server-side) to: potato2.rcc.018 2021/03/23 05:36:36 INFO : potato2.rcc.019_evg2r3: Moved (server-side) to: potato2.rcc.019 2021/03/23 05:36:36 INFO : potato2.rcc.020_evg2r3: Moved (server-side) to: potato2.rcc.020 2021/03/23 05:36:36 INFO : empty space.rcc.001_evg2eo: Moved (server-side) to: empty space 2021/03/23 05:36:36 INFO : potato3.rcc.001_evg24i: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:36 INFO : potato3.rcc.002_evg24i: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:36 INFO : potato3.rcc.003_evg24i: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:36 INFO : potato3.rcc.004_evg24i: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:36 INFO : potato3.rcc.005_evg24i: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:36 INFO : potato3.rcc.006_evg24i: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:36 INFO : potato3.rcc.007_evg24i: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:36 INFO : potato3.rcc.008_evg24i: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:36 INFO : potato3.rcc.009_evg24i: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:36 INFO : potato3.rcc.010_evg24i: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:36 INFO : potato3.rcc.011_evg24i: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:36 INFO : potato3.rcc.012_evg24i: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:36 INFO : potato3.rcc.013_evg24i: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:36 INFO : potato3.rcc.014_evg24i: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:36 INFO : potato3.rcc.015_evg24i: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:36 INFO : potato3.rcc.016_evg24i: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:36 INFO : potato3.rcc.017_evg24i: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:36 INFO : potato3.rcc.018_evg24i: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:36 INFO : potato3.rcc.019_evg24i: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:36 INFO : potato3.rcc.020_evg24i: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:36 INFO : potato3.rcc.021_evg24i: Moved (server-side) to: potato3.rcc.021 2021/03/23 05:36:36 INFO : potato3.rcc.022_evg24i: Moved (server-side) to: potato3.rcc.022 2021/03/23 05:36:36 INFO : potato3.rcc.023_evg24i: Moved (server-side) to: potato3.rcc.023 2021/03/23 05:36:36 DEBUG : tomatoDir: Making directory sync_test.go:1245: Server side move (if possible) Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1' -> Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3' 2021/03/23 05:36:36 INFO : empty space.rcc.001_evg2fi: Moved (server-side) to: empty space 2021/03/23 05:36:36 INFO : potato3.rcc.001_evg2kk: Moved (server-side) to: potato3.rcc.001 2021/03/23 05:36:36 INFO : potato3.rcc.002_evg2kk: Moved (server-side) to: potato3.rcc.002 2021/03/23 05:36:36 INFO : potato3.rcc.003_evg2kk: Moved (server-side) to: potato3.rcc.003 2021/03/23 05:36:36 INFO : potato3.rcc.004_evg2kk: Moved (server-side) to: potato3.rcc.004 2021/03/23 05:36:36 INFO : potato3.rcc.005_evg2kk: Moved (server-side) to: potato3.rcc.005 2021/03/23 05:36:36 INFO : potato3.rcc.006_evg2kk: Moved (server-side) to: potato3.rcc.006 2021/03/23 05:36:36 INFO : potato3.rcc.007_evg2kk: Moved (server-side) to: potato3.rcc.007 2021/03/23 05:36:36 INFO : potato3.rcc.008_evg2kk: Moved (server-side) to: potato3.rcc.008 2021/03/23 05:36:36 INFO : potato3.rcc.009_evg2kk: Moved (server-side) to: potato3.rcc.009 2021/03/23 05:36:36 INFO : potato3.rcc.010_evg2kk: Moved (server-side) to: potato3.rcc.010 2021/03/23 05:36:36 INFO : potato3.rcc.011_evg2kk: Moved (server-side) to: potato3.rcc.011 2021/03/23 05:36:36 INFO : potato3.rcc.012_evg2kk: Moved (server-side) to: potato3.rcc.012 2021/03/23 05:36:36 INFO : potato3.rcc.013_evg2kk: Moved (server-side) to: potato3.rcc.013 2021/03/23 05:36:36 INFO : potato3.rcc.014_evg2kk: Moved (server-side) to: potato3.rcc.014 2021/03/23 05:36:36 INFO : potato3.rcc.015_evg2kk: Moved (server-side) to: potato3.rcc.015 2021/03/23 05:36:36 INFO : potato3.rcc.016_evg2kk: Moved (server-side) to: potato3.rcc.016 2021/03/23 05:36:36 INFO : potato3.rcc.017_evg2kk: Moved (server-side) to: potato3.rcc.017 2021/03/23 05:36:36 INFO : potato3.rcc.018_evg2kk: Moved (server-side) to: potato3.rcc.018 2021/03/23 05:36:36 INFO : potato3.rcc.019_evg2kk: Moved (server-side) to: potato3.rcc.019 2021/03/23 05:36:36 INFO : potato3.rcc.020_evg2kk: Moved (server-side) to: potato3.rcc.020 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3': Using server-side directory move 2021/03/23 05:36:36 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3': Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2021/03/23 05:36:36 DEBUG : potato2: move 20 data chunks... 2021/03/23 05:36:36 INFO : potato2.rcc.001: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.002: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.003: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.004: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.005: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.006: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.007: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.008: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.009: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.010: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.011: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.012: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.013: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.014: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.015: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.016: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.017: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.018: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.019: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2.rcc.020: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2: Moved (server-side) 2021/03/23 05:36:36 INFO : potato2: Moved (server-side) 2021/03/23 05:36:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : empty space: Unchanged skipping 2021/03/23 05:36:36 INFO : empty space: Deleted 2021/03/23 05:36:36 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2021/03/23 05:36:36 INFO : potato3: Deleted 2021/03/23 05:36:36 DEBUG : potato3: move 23 data chunks... 2021/03/23 05:36:36 INFO : potato3.rcc.001: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.002: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.003: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.004: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.005: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.006: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.007: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.008: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.009: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.010: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.011: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.012: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.013: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.014: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.015: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.016: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.017: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.018: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.019: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.020: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.021: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.022: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3.rcc.023: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3: Moved (server-side) 2021/03/23 05:36:36 INFO : potato3: Moved (server-side) 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3': Waiting for transfers to finish 2021/03/23 05:36:36 DEBUG : tomatoDir: Removing directory 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': deleted 1 directories 2021/03/23 05:36:36 INFO : There was nothing to transfer 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-vejakeg1zepogoj4filutil4" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-vejakeg1zepogoj4filutil4" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/ncta8s01rfbnsll12u7ndl819334qrm3ni48h0mrpj6j7i8cpe49dksed0js48qo0n45fcjq8b12i" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : tomatoDir: Making directory 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vejakeg1zepogoj4filutil4': Using server-side directory move 2021/03/23 05:36:36 INFO : Chunked 'TestChunkerOverCrypt:rclone-test-vejakeg1zepogoj4filutil4': Server side directory move succeeded 2021/03/23 05:36:36 ERROR : : error listing: directory not found 2021/03/23 05:36:36 ERROR : : error listing: directory not found 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-vejakeg1zepogoj4filutil4': Purge remote 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-kisigas9nukuheg6qixifiz3': Purge remote 2021/03/23 05:36:36 purge failed: directory not found --- PASS: TestServerSideMoveDeleteEmptySourceDirs (0.27s) === RUN TestServerSideMoveOverlap run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1382: Skipping test as remote supports DirMove --- SKIP: TestServerSideMoveOverlap (0.00s) === RUN TestSyncOverlap run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/rclone-sync-test" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/rclone-sync-test" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/0kji1l44g89hp20dijrue5bugvdmdnsgj3kfrnt75fkt5gqolp9g" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" --- PASS: TestSyncOverlap (0.08s) === RUN TestSyncCompareDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/CompareDest" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/CompareDest" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/fcu437f1ephh64b8cv237u29t4" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 INFO : one.rcc.001_evg27y: Moved (server-side) to: one 2021/03/23 05:36:36 INFO : one: Copied (new) 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2021/03/23 05:36:36 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 INFO : one.rcc.001_evg2uw: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:36 INFO : one.rcc.002_evg2uw: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:36 INFO : one: Copied (replaced existing) 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:36 INFO : dst/one: Deleted 2021/03/23 05:36:36 INFO : dst/one.rcc.001_evg290: Moved (server-side) to: dst/one 2021/03/23 05:36:36 INFO : CompareDest/one.rcc.001_evg2rs: Moved (server-side) to: CompareDest/one.rcc.001 2021/03/23 05:36:36 INFO : CompareDest/one.rcc.002_evg2rs: Moved (server-side) to: CompareDest/one.rcc.002 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:36 INFO : There was nothing to transfer 2021/03/23 05:36:36 INFO : CompareDest/two.rcc.001_evg2vc: Moved (server-side) to: CompareDest/two 2021/03/23 05:36:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:36 INFO : There was nothing to transfer 2021/03/23 05:36:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : two: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:36 INFO : There was nothing to transfer 2021/03/23 05:36:36 DEBUG : two: Sizes differ (src 5 vs dst 3) 2021/03/23 05:36:36 DEBUG : two: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : one: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:36 INFO : two.rcc.001_evg24b: Moved (server-side) to: two.rcc.001 2021/03/23 05:36:36 INFO : two.rcc.002_evg24b: Moved (server-side) to: two.rcc.002 2021/03/23 05:36:36 INFO : two: Copied (new) 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (0.25s) === RUN TestSyncMultipleCompareDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:36 INFO : pre-dest1/1.rcc.001_evg2pv: Moved (server-side) to: pre-dest1/1 2021/03/23 05:36:36 INFO : pre-dest2/2.rcc.001_evg2z5: Moved (server-side) to: pre-dest2/2 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dest" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dest" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/0na9j9v8qd02gospsl4qad4d2c" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/pre-dest1" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/pre-dest1" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/puq6ljvda69ueq7v3a16pqbqgo" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/pre-dest2" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/pre-dest2" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/0v83beu90ap6a4em88hf7dufv0" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : 1: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:36 DEBUG : 2: Destination found in --compare-dest, skipping 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dest': Waiting for checks to finish 2021/03/23 05:36:36 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dest': Waiting for transfers to finish 2021/03/23 05:36:36 DEBUG : 3: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:36 INFO : 3.rcc.001_evg266: Moved (server-side) to: 3 2021/03/23 05:36:36 INFO : 3: Copied (new) 2021/03/23 05:36:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (0.29s) === RUN TestSyncCopyDest run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1553: Skipping test as remote does not support server-side copy --- SKIP: TestSyncCopyDest (0.00s) === RUN TestSyncBackupDir run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:36 INFO : dst/one.rcc.001_evg2zs: Moved (server-side) to: dst/one 2021/03/23 05:36:36 INFO : dst/two.rcc.001_evg2dj: Moved (server-side) to: dst/two 2021/03/23 05:36:36 INFO : dst/three.txt.rcc.001_evg2sd: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:36 INFO : dst/three.txt.rcc.002_evg2sd: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:36 DEBUG : Reset feature "ListR" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:36 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one: Moved (server-side) 2021/03/23 05:36:37 INFO : one: Moved (server-side) 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3o9: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3o9: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3pz: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3pz: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) 2021/03/23 05:36:37 INFO : one: Moved (server-side) 2021/03/23 05:36:37 INFO : one: Moved (server-side) 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3ka: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3ka: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 INFO : three.txt: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (0.18s) === RUN TestSyncBackupDirWithSuffix run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : dst/one.rcc.001_evg33y: Moved (server-side) to: dst/one 2021/03/23 05:36:37 INFO : dst/two.rcc.001_evg3ag: Moved (server-side) to: dst/two 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg339: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg339: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3kb: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3kb: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg378: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg378: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one.bak: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3r8: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3r8: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 INFO : three.txt.bak: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (0.19s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : dst/one.rcc.001_evg3b8: Moved (server-side) to: dst/one 2021/03/23 05:36:37 INFO : dst/two.rcc.001_evg3mw: Moved (server-side) to: dst/two 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3da: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3da: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/backup" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/nb8t2lplr4nd5op5pmtnoafv18" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg37f: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg37f: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3h4: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3h4: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one-2019-01-01: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) to: one-2019-01-01.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) to: one-2019-01-01.rcc.002 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3r5: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3r5: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 INFO : three-2019-01-01.txt: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (0.22s) === RUN TestSyncBackupDirSuffixOnly run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : dst/one.rcc.001_evg3ke: Moved (server-side) to: dst/one 2021/03/23 05:36:37 INFO : dst/two.rcc.001_evg31z: Moved (server-side) to: dst/two 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3k5: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3k5: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg38z: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg38z: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3h5: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3h5: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : one.bak: Excluded 2021/03/23 05:36:37 DEBUG : three.txt.bak: Excluded 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for checks to finish 2021/03/23 05:36:37 INFO : one.bak: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3dq: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3dq: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 INFO : three.txt.bak: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (0.11s) === RUN TestSyncSuffix run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : dst/one.rcc.001_evg30w: Moved (server-side) to: dst/one 2021/03/23 05:36:37 INFO : dst/two.rcc.001_evg3gr: Moved (server-side) to: dst/two 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3es: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3es: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3gn: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3gn: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : three.txt.rcc.001_evg3c2: Moved (server-side) to: three.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002_evg3c2: Moved (server-side) to: three.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Copied (new) 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 INFO : one.bak: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) to: one.bak.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) to: one.bak.rcc.002 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one.bak 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3je: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3je: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 05:36:37 INFO : three.txt.bak: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three.txt.bak.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three.txt.bak.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three.txt.bak 2021/03/23 05:36:37 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : three.txt.rcc.001_evg3yh: Moved (server-side) to: three.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002_evg3yh: Moved (server-side) to: three.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt.rcc.003_evg3yh: Moved (server-side) to: three.txt.rcc.003 2021/03/23 05:36:37 INFO : three.txt.rcc.004_evg3yh: Moved (server-side) to: three.txt.rcc.004 2021/03/23 05:36:37 INFO : three.txt.rcc.005_evg3yh: Moved (server-side) to: three.txt.rcc.005 2021/03/23 05:36:37 INFO : three.txt.rcc.006_evg3yh: Moved (server-side) to: three.txt.rcc.006 2021/03/23 05:36:37 INFO : three.txt.rcc.007_evg3yh: Moved (server-side) to: three.txt.rcc.007 2021/03/23 05:36:37 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (0.12s) === RUN TestSyncSuffixKeepExtension run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : dst/one.rcc.001_evg3xy: Moved (server-side) to: dst/one 2021/03/23 05:36:37 INFO : dst/two.rcc.001_evg38w: Moved (server-side) to: dst/two 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.001_evg3kk: Moved (server-side) to: dst/three.txt.rcc.001 2021/03/23 05:36:37 INFO : dst/three.txt.rcc.002_evg3kk: Moved (server-side) to: dst/three.txt.rcc.002 2021/03/23 05:36:37 DEBUG : Creating backend with remote "TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "__TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1/dst" 2021/03/23 05:36:37 DEBUG : Creating backend with remote "/tmp/test_chunker_over_crypt/so0t48r60naas5hj5s82smns9jh1j50m0qvuehapqa67buuirgd2a353fj0khslc5ci2bra0r47cg/mo3p16vesn7ioqbi95iir4gt5g" 2021/03/23 05:36:37 DEBUG : Reset feature "ListR" 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2021/03/23 05:36:37 DEBUG : one: move non-chunked object... 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3il: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3il: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : three.txt.rcc.001_evg3pi: Moved (server-side) to: three.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002_evg3pi: Moved (server-side) to: three.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Copied (new) 2021/03/23 05:36:37 DEBUG : one: Sizes differ (src 5 vs dst 4) 2021/03/23 05:36:37 INFO : one-2019-01-01: Deleted 2021/03/23 05:36:37 DEBUG : one: move 2 data chunks... 2021/03/23 05:36:37 INFO : one.rcc.001: Moved (server-side) to: one-2019-01-01.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002: Moved (server-side) to: one-2019-01-01.rcc.002 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 INFO : one: Moved (server-side) to: one-2019-01-01 2021/03/23 05:36:37 DEBUG : one: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : one.rcc.001_evg3gv: Moved (server-side) to: one.rcc.001 2021/03/23 05:36:37 INFO : one.rcc.002_evg3gv: Moved (server-side) to: one.rcc.002 2021/03/23 05:36:37 INFO : one: Copied (new) 2021/03/23 05:36:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : two: Unchanged skipping 2021/03/23 05:36:37 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2021/03/23 05:36:37 INFO : three-2019-01-01.txt: Deleted 2021/03/23 05:36:37 DEBUG : three.txt: move 2 data chunks... 2021/03/23 05:36:37 INFO : three.txt.rcc.001: Moved (server-side) to: three-2019-01-01.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002: Moved (server-side) to: three-2019-01-01.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 INFO : three.txt: Moved (server-side) to: three-2019-01-01.txt 2021/03/23 05:36:37 DEBUG : three.txt: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : three.txt.rcc.001_evg3lz: Moved (server-side) to: three.txt.rcc.001 2021/03/23 05:36:37 INFO : three.txt.rcc.002_evg3lz: Moved (server-side) to: three.txt.rcc.002 2021/03/23 05:36:37 INFO : three.txt.rcc.003_evg3lz: Moved (server-side) to: three.txt.rcc.003 2021/03/23 05:36:37 INFO : three.txt.rcc.004_evg3lz: Moved (server-side) to: three.txt.rcc.004 2021/03/23 05:36:37 INFO : three.txt.rcc.005_evg3lz: Moved (server-side) to: three.txt.rcc.005 2021/03/23 05:36:37 INFO : three.txt.rcc.006_evg3lz: Moved (server-side) to: three.txt.rcc.006 2021/03/23 05:36:37 INFO : three.txt.rcc.007_evg3lz: Moved (server-side) to: three.txt.rcc.007 2021/03/23 05:36:37 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (0.14s) === RUN TestSyncUTFNorm run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : Testêé.rcc.001_evg3r5: Moved (server-side) to: Testêé.rcc.001 2021/03/23 05:36:37 INFO : Testêé.rcc.002_evg3r5: Moved (server-side) to: Testêé.rcc.002 2021/03/23 05:36:37 INFO : Testêé.rcc.003_evg3r5: Moved (server-side) to: Testêé.rcc.003 2021/03/23 05:36:37 INFO : Testêé.rcc.004_evg3r5: Moved (server-side) to: Testêé.rcc.004 2021/03/23 05:36:37 INFO : Testêé.rcc.005_evg3r5: Moved (server-side) to: Testêé.rcc.005 2021/03/23 05:36:37 INFO : Testêé.rcc.006_evg3r5: Moved (server-side) to: Testêé.rcc.006 2021/03/23 05:36:37 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2021/03/23 05:36:37 DEBUG : Testêé: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:37 INFO : Testêé.rcc.001_evg3y4: Moved (server-side) to: Testêé.rcc.001 2021/03/23 05:36:37 INFO : Testêé.rcc.002_evg3y4: Moved (server-side) to: Testêé.rcc.002 2021/03/23 05:36:37 INFO : Testêé.rcc.003_evg3y4: Moved (server-side) to: Testêé.rcc.003 2021/03/23 05:36:37 INFO : Testêé.rcc.004_evg3y4: Moved (server-side) to: Testêé.rcc.004 2021/03/23 05:36:37 INFO : Testêé.rcc.005_evg3y4: Moved (server-side) to: Testêé.rcc.005 2021/03/23 05:36:37 INFO : Testêé: Copied (replaced existing) to: Testêé 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (0.01s) === RUN TestSyncImmutable run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : existing: skip slow SHA1 on source file, hashing in-transit 2021/03/23 05:36:37 INFO : existing.rcc.001_evg34v: Moved (server-side) to: existing.rcc.001 2021/03/23 05:36:37 INFO : existing.rcc.002_evg34v: Moved (server-side) to: existing.rcc.002 2021/03/23 05:36:37 INFO : existing: Copied (new) 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2021/03/23 05:36:37 ERROR : existing: Source and destination exist but do not match: immutable file modified 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:37 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': not deleting files as there were IO errors 2021/03/23 05:36:37 ERROR : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (0.02s) === RUN TestSyncIgnoreCase run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" 2021/03/23 05:36:37 INFO : EXISTING.rcc.001_evg3yk: Moved (server-side) to: EXISTING.rcc.001 2021/03/23 05:36:37 INFO : EXISTING.rcc.002_evg3yk: Moved (server-side) to: EXISTING.rcc.002 2021/03/23 05:36:37 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2021/03/23 05:36:37 DEBUG : existing: Unchanged skipping 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for checks to finish 2021/03/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Waiting for transfers to finish 2021/03/23 05:36:37 DEBUG : Waiting for deletions to finish 2021/03/23 05:36:37 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (0.01s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Soft run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1941: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:176: Remote "Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1'", Local "Local file system at /tmp/rclone944960434", Modify Window "1ns" sync_test.go:1941: 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/23 05:36:37 DEBUG : Chunked 'TestChunkerOverCrypt:rclone-test-momizaf7bupeyiy1yegoxit1': Purge remote "./sync.test -test.v -test.timeout 1h0m0s -remote TestChunkerOverCrypt: -verbose -fast-list -size-limit 6144" - Finished OK in 3.635906134s (try 1/5)