"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2024/04/12 03:30:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7" 2024/04/12 03:30:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/12 03:30:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7.bin" 2024/04/12 03:30:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7" 2024/04/12 03:30:22 DEBUG : Creating backend with remote "/tmp/rclone2782105322" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.07s) === 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.02s) --- 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.02s) --- 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:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:23 NOTICE: sub dir: Skipped make directory as --dry-run is set 2024/04/12 03:30:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:23 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/04/12 03:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.59s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:23 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/12 03:30:23 DEBUG : sub dir: Making directory 2024/04/12 03:30:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:23 DEBUG : sub dir/hello world: md5 = 3d10ed4f0f23266077e65f662ef48f94 OK 2024/04/12 03:30:23 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.09s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.10s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:24 DEBUG : Creating backend with remote "/non-existing" 2024/04/12 03:30:24 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/04/12 03:30:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.37s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:25 DEBUG : sub dir: Making directory 2024/04/12 03:30:25 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:25 DEBUG : sub dir/hello world: md5 = 7645cafd89976a348fde0fcf8e53ffa5 OK 2024/04/12 03:30:25 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyNoTraverse (1.04s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Running all checks before starting transfers 2024/04/12 03:30:26 DEBUG : sub dir: Making directory 2024/04/12 03:30:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Checks finished, now starting transfers 2024/04/12 03:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:26 DEBUG : sub dir/hello world: md5 = 663d1c62db98001dcb1a0cc2aef9718b OK 2024/04/12 03:30:26 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyCheckFirst (1.12s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:27 ERROR : Ignoring --no-traverse with sync 2024/04/12 03:30:27 DEBUG : sub dir: Making directory 2024/04/12 03:30:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:27 DEBUG : sub dir/hello world: md5 = beedb765d3104e70d84ef4fe78d9940c OK 2024/04/12 03:30:27 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:30:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncNoTraverse (0.95s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:28 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/04/12 03:30:28 DEBUG : sub dir: Making directory 2024/04/12 03:30:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:28 DEBUG : hello world2: md5 = 2b321f536d39893e0508cb427f7d4cef OK 2024/04/12 03:30:28 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyWithDepth (1.27s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:29 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:29 DEBUG : potato2: md5 = a3955f55f1a848bbc23ff169b0b4690a OK 2024/04/12 03:30:29 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.07s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:30 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:30:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:30 DEBUG : potato2: md5 = 2fcdb3097a85fbc660470c23aab0c3b7 OK 2024/04/12 03:30:30 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (1.37s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:31 DEBUG : sub dir2: Making directory with metadata 2024/04/12 03:30:31 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/12 03:30:31 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/12 03:30:32 DEBUG : sub dir: Making directory 2024/04/12 03:30:32 DEBUG : sub dir2: Making directory 2024/04/12 03:30:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:32 DEBUG : sub dir/hello world: md5 = 166722b94222f7c43f6399a455b22f74 OK 2024/04/12 03:30:32 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:30:32 DEBUG : sub dir2: Making directory 2024/04/12 03:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': copied 1 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/12 03:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.09s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:33 DEBUG : sub dir2: Making directory with metadata 2024/04/12 03:30:33 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/12 03:30:33 DEBUG : sub dir: Making directory 2024/04/12 03:30:33 DEBUG : sub dir2: Making directory 2024/04/12 03:30:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:33 DEBUG : sub dir/hello world: md5 = f2302aad8b442ade83ff176308fac910 OK 2024/04/12 03:30:33 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:30:33 INFO : sub dir/hello world: Deleted 2024/04/12 03:30:33 DEBUG : sub dir: Making directory 2024/04/12 03:30:33 DEBUG : sub dir2: Making directory 2024/04/12 03:30:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': copied 2 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/12 03:30:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/04/12 03:30:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.07s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:351: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.17s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:34 DEBUG : sub dir2: Making directory with metadata 2024/04/12 03:30:34 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/12 03:30:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/12 03:30:34 DEBUG : sub dir: Making directory 2024/04/12 03:30:34 DEBUG : sub dir2: Making directory 2024/04/12 03:30:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:34 DEBUG : sub dir/hello world: md5 = d7f59a76ab2c62d4bb22fe8c8ffd1be5 OK 2024/04/12 03:30:34 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:30:34 DEBUG : sub dir2: Making directory 2024/04/12 03:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': copied 1 directories 2024/04/12 03:30:34 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/12 03:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.14s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.17s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:35 DEBUG : sub dir/hello world: md5 = 8ed7c43270c4aca22c82f3c6ff37bce7 OK 2024/04/12 03:30:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yexalec0walolon4xekozoz0" 2024/04/12 03:30:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yexalec0walolon4xekozoz0.bin" 2024/04/12 03:30:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yexalec0walolon4xekozoz0" sync_test.go:487: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7' -> Encrypted drive 'TestCryptSwift:rclone-test-yexalec0walolon4xekozoz0' 2024/04/12 03:30:37 DEBUG : sub dir: Making directory 2024/04/12 03:30:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yexalec0walolon4xekozoz0': Waiting for checks to finish 2024/04/12 03:30:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yexalec0walolon4xekozoz0': Waiting for transfers to finish 2024/04/12 03:30:38 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yexalec0walolon4xekozoz0': Purge remote 2024/04/12 03:30:38 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:38 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (3.84s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:39 DEBUG : sub dir/hello world: md5 = 3d988d8c1990ac8a40f17df7e5213d6a OK 2024/04/12 03:30:39 ERROR : : error listing: directory not found 2024/04/12 03:30:40 DEBUG : Local file system at /tmp/rclone2782105322: Making directory 2024/04/12 03:30:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyAfterDelete (1.88s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:41 DEBUG : sub dir/hello world: md5 = 72d4d2d0bf023590d9304e53c691e5b6 OK 2024/04/12 03:30:41 DEBUG : sub dir: Making directory 2024/04/12 03:30:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:30:41 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for checks to finish 2024/04/12 03:30:41 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for transfers to finish 2024/04/12 03:30:41 DEBUG : sub dir/hello world.kuvidid8.partial: renamed to: sub dir/hello world 2024/04/12 03:30:41 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyRedownload (1.10s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:42 DEBUG : check sum: Need to transfer - File not found at Destination 2024/04/12 03:30:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:42 DEBUG : check sum: md5 = fefbc924c0a6b112f93e61353c04fb1b OK 2024/04/12 03:30:42 INFO : check sum: Copied (new) 2024/04/12 03:30:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:43 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/04/12 03:30:43 DEBUG : check sum: Size of src and dst objects identical 2024/04/12 03:30:43 DEBUG : check sum: Unchanged skipping 2024/04/12 03:30:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:43 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:43 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (1.65s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:44 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/04/12 03:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:44 DEBUG : sizeonly: md5 = 2aae2571ff7533bb4990501f00e97cc5 OK 2024/04/12 03:30:44 INFO : sizeonly: Copied (new) 2024/04/12 03:30:44 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:44 DEBUG : sizeonly: Sizes identical 2024/04/12 03:30:44 DEBUG : sizeonly: Unchanged skipping 2024/04/12 03:30:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:44 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:44 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncSizeOnly (1.80s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:46 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/04/12 03:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:46 DEBUG : ignore-size: md5 = a77e96df31d400cffd2ce4b149d938b7 OK 2024/04/12 03:30:46 INFO : ignore-size: Copied (new) 2024/04/12 03:30:46 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:46 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:30:46 DEBUG : ignore-size: Unchanged skipping 2024/04/12 03:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:46 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:46 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.82s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:48 DEBUG : existing: md5 = f5ad552c38b6a9cb9dc0b9906c868b20 OK 2024/04/12 03:30:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:48 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:30:48 DEBUG : existing: Unchanged skipping 2024/04/12 03:30:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:48 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:48 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:48 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/04/12 03:30:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:48 DEBUG : existing: md5 = cd6679e5be03afe97470fb778f5af162 OK 2024/04/12 03:30:48 INFO : existing: Copied (replaced existing) 2024/04/12 03:30:48 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (1.84s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:49 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/12 03:30:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:49 DEBUG : existing: md5 = efbd5de57d3d1c8cc36d85bd55f84cb9 OK 2024/04/12 03:30:49 INFO : existing: Copied (new) 2024/04/12 03:30:49 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:50 DEBUG : existing: Destination exists, skipping 2024/04/12 03:30:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:50 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:50 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.93s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:51 DEBUG : b/potato: md5 = 21173bd467ab1d306e32469588cb8792 OK 2024/04/12 03:30:51 DEBUG : c/non empty space: md5 = 843766b014dc58f99fe7077799403657 OK 2024/04/12 03:30:51 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/12 03:30:52 DEBUG : a: Making directory 2024/04/12 03:30:52 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/12 03:30:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:52 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:30:52 DEBUG : c/non empty space: Unchanged skipping 2024/04/12 03:30:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:52 DEBUG : a/potato2: md5 = ed530f1969d196ce3274d1a1e568fb2c OK 2024/04/12 03:30:52 INFO : a/potato2: Copied (new) 2024/04/12 03:30:52 DEBUG : Waiting for deletions to finish 2024/04/12 03:30:53 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (2.48s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:54 DEBUG : empty space: md5 = a61b61ea658ca07ff269c5c6c4a5d146 OK 2024/04/12 03:30:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:54 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 2024/04/12 03:30:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:54 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/04/12 03:30:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:54 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 2024/04/12 03:30:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:55 DEBUG : empty space: md5 = 87d2ad3ab75175edca61de527e97ce27 OK 2024/04/12 03:30:55 INFO : empty space: Copied (replaced existing) 2024/04/12 03:30:55 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (2.27s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:811: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.10s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:56 DEBUG : foo: md5 = ae0594b04e23cf962a189964811389d2 OK 2024/04/12 03:30:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:57 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 2024/04/12 03:30:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:57 DEBUG : foo: md5 = ac1778caa16c51ac3d8065edba26f0aa OK 2024/04/12 03:30:57 INFO : foo: Copied (replaced existing) 2024/04/12 03:30:57 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (2.01s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:30:58 DEBUG : empty space: md5 = 5b706132a79810f58a19dc7aebe2d4d8 OK 2024/04/12 03:30:58 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/12 03:30:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:30:58 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:30:58 DEBUG : empty space: Unchanged skipping 2024/04/12 03:30:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:30:58 DEBUG : potato: md5 = e088d92e9ddc436b3642be353dc117ed OK 2024/04/12 03:30:58 INFO : potato: Copied (new) 2024/04/12 03:30:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (1.57s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:00 DEBUG : potato: md5 = 2811ae9da11d99113cae2581308550b1 OK 2024/04/12 03:31:00 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/04/12 03:31:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:00 DEBUG : potato: md5 = 432612f949a59301919e034f02ccb3f9 OK 2024/04/12 03:31:00 INFO : potato: Copied (replaced existing) 2024/04/12 03:31:00 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (1.69s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:01 DEBUG : potato: md5 = 5dd1554c86a41ae755ccd1d9025b540b OK 2024/04/12 03:31:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:02 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 2024/04/12 03:31:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:02 DEBUG : potato: md5 = 266909e18d6779d6e85ac4f8ce42d980 OK 2024/04/12 03:31:02 INFO : potato: Copied (replaced existing) 2024/04/12 03:31:02 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.55s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:03 DEBUG : potato: md5 = a193d2f4a55e7e6b88b84048f6c645f3 OK 2024/04/12 03:31:03 DEBUG : empty space: md5 = 0b825a6039992ea4ecff4361168e4830 OK 2024/04/12 03:31:03 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:03 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/04/12 03:31:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:03 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:03 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:03 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.35s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:05 DEBUG : potato: md5 = e8b6e04a58ecdf274bec732da4f73e41 OK 2024/04/12 03:31:05 DEBUG : empty space: md5 = f1edbc1d54057ed3ea892d8c2705b7d7 OK 2024/04/12 03:31:06 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:06 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:06 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:06 DEBUG : potato2: md5 = 5dedc65c26510a20d6869fc5cefdcf16 OK 2024/04/12 03:31:06 INFO : potato2: Copied (new) 2024/04/12 03:31:06 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:06 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.24s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:07 DEBUG : b/potato: md5 = 2edfb0b7b8da0e20caec8426ac339c4c OK 2024/04/12 03:31:08 DEBUG : c/non empty space: md5 = de04a2a9002d7c466def57a3baa4ab74 OK 2024/04/12 03:31:08 DEBUG : d: Making directory 2024/04/12 03:31:08 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/04/12 03:31:08 DEBUG : a: Making directory 2024/04/12 03:31:08 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:08 DEBUG : a/potato2: md5 = 34e62a58aee9e9aa31eab2d006d97a6b OK 2024/04/12 03:31:08 INFO : a/potato2: Copied (new) 2024/04/12 03:31:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:08 DEBUG : c/non empty space: Unchanged skipping 2024/04/12 03:31:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:08 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:09 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.31s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:10 DEBUG : b/potato: md5 = 3460df95ce67e0e133273d0e32b5b784 OK 2024/04/12 03:31:10 DEBUG : c/non empty space: md5 = c9f0b8fb34cf005ec1d8a347dbbe3dcf OK 2024/04/12 03:31:10 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/12 03:31:10 DEBUG : a: Making directory 2024/04/12 03:31:10 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:10 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:10 DEBUG : c/non empty space: Unchanged skipping 2024/04/12 03:31:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:10 DEBUG : a/potato2: md5 = ad647b451b3c91b6f3c5426d2dba24b9 OK 2024/04/12 03:31:10 INFO : a/potato2: Copied (new) 2024/04/12 03:31:10 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': not deleting files as there were IO errors 2024/04/12 03:31:10 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (1.97s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:12 DEBUG : potato: md5 = b1e18b837e08bcb9a15a47b86e9f0556 OK 2024/04/12 03:31:12 DEBUG : empty space: md5 = 442e60ace9ba5701d43b12ccde8359f8 OK 2024/04/12 03:31:12 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:12 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:12 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:13 INFO : potato: Deleted 2024/04/12 03:31:13 DEBUG : potato2: md5 = 15dbd5aa04ee113c071cc23160d8a4b5 OK 2024/04/12 03:31:13 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (2.29s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:14 DEBUG : potato: md5 = d1eb042cceb7cf70371ab654efc02e13 OK 2024/04/12 03:31:14 DEBUG : empty space: md5 = 6d69b8108d4291fdbab9976300b23c6c OK 2024/04/12 03:31:15 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:15 INFO : potato: Deleted 2024/04/12 03:31:15 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:15 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:15 DEBUG : potato2: md5 = 4b5f8f254aed85455c28e1ec8f09a4ae OK 2024/04/12 03:31:15 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (2.57s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:17 DEBUG : potato: md5 = 71c4ee71961b393a38884095c7266835 OK 2024/04/12 03:31:17 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:17 DEBUG : potato2: md5 = 183d7bd944834516453f53097570f17e OK 2024/04/12 03:31:17 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.75s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:18 DEBUG : potato2: md5 = 5160c8f3e555851afc32c14e5024d9a9 OK 2024/04/12 03:31:19 DEBUG : empty space: md5 = 8be4bc10f3c5d698b2a9d4fdfed2ff17 OK 2024/04/12 03:31:19 DEBUG : enormous: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : enormous: Excluded 2024/04/12 03:31:19 DEBUG : potato2: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : potato2: Excluded 2024/04/12 03:31:19 DEBUG : potato2: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : potato2: Excluded 2024/04/12 03:31:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:19 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:19 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:19 DEBUG : enormous: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : enormous: Excluded 2024/04/12 03:31:19 DEBUG : potato2: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : potato2: Excluded 2024/04/12 03:31:19 DEBUG : potato2: Excluded (Size Filter) 2024/04/12 03:31:19 DEBUG : potato2: Excluded 2024/04/12 03:31:19 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for checks to finish 2024/04/12 03:31:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:19 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:19 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for transfers to finish 2024/04/12 03:31:19 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:19 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncWithExclude (2.11s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:20 DEBUG : potato2: md5 = f69a7c2ef7008d282c1a21e0c6f74115 OK 2024/04/12 03:31:21 DEBUG : empty space: md5 = dcd74d55dab6706cb984d8641b4d0abc OK 2024/04/12 03:31:21 DEBUG : enormous: md5 = 32cfac39d247e68036fa477d40318f79 OK 2024/04/12 03:31:21 DEBUG : enormous: Excluded (Size Filter) 2024/04/12 03:31:21 DEBUG : enormous: Excluded 2024/04/12 03:31:21 DEBUG : potato2: Excluded (Size Filter) 2024/04/12 03:31:21 DEBUG : potato2: Excluded 2024/04/12 03:31:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:21 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:21 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:21 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:21 INFO : enormous: Deleted 2024/04/12 03:31:21 INFO : potato2: Deleted 2024/04/12 03:31:21 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:22 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for checks to finish 2024/04/12 03:31:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:22 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:22 DEBUG : Local file system at /tmp/rclone2782105322: Waiting for transfers to finish 2024/04/12 03:31:22 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:22 INFO : enormous: Deleted 2024/04/12 03:31:22 INFO : potato2: Deleted 2024/04/12 03:31:22 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.36s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:23 DEBUG : one: md5 = 5b2e14019d3b41431bea4ad1d75da22e OK 2024/04/12 03:31:23 DEBUG : two: md5 = ed04377803a89feae8b88410468b4b2b OK 2024/04/12 03:31:23 DEBUG : three: md5 = c104e6b9fd72e0163930896571c89b46 OK 2024/04/12 03:31:24 DEBUG : four: md5 = efe1e07fef8c5e0c6698727c4512d325 OK 2024/04/12 03:31:24 DEBUG : five: Need to transfer - File not found at Destination 2024/04/12 03:31:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:24 DEBUG : one: Destination is newer than source, skipping 2024/04/12 03:31:24 DEBUG : three: Sizes identical 2024/04/12 03:31:24 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/04/12 03:31:24 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/04/12 03:31:24 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/04/12 03:31:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:24 DEBUG : four: md5 = 42238bab9c22bb9ba080d46bab26d24a OK 2024/04/12 03:31:24 INFO : four: Copied (replaced existing) 2024/04/12 03:31:24 DEBUG : two: md5 = 7df09ceb18eac17a201ca8befdc9cc43 OK 2024/04/12 03:31:24 INFO : two: Copied (replaced existing) 2024/04/12 03:31:24 DEBUG : five: md5 = 058b6988171e68ef76200e22042d7695 OK 2024/04/12 03:31:24 INFO : five: Copied (new) 2024/04/12 03:31:24 DEBUG : Waiting for deletions to finish sync_test.go:1244: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (3.35s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1261: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1261: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:1327: Can track renames: false 2024/04/12 03:31:26 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/12 03:31:26 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/12 03:31:26 DEBUG : yam: Need to transfer - File not found at Destination 2024/04/12 03:31:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:26 DEBUG : potato: md5 = cb3d548bcb772f89fcb491f0b7028385 OK 2024/04/12 03:31:26 INFO : potato: Copied (new) 2024/04/12 03:31:27 DEBUG : yam: md5 = d134b4a868a3d6ded25c579c4cb796da OK 2024/04/12 03:31:27 INFO : yam: Copied (new) 2024/04/12 03:31:27 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:27 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/12 03:31:27 DEBUG : yaml: Need to transfer - File not found at Destination 2024/04/12 03:31:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:27 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:27 DEBUG : potato: Unchanged skipping 2024/04/12 03:31:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:27 DEBUG : yaml: md5 = fca67e2964e91208ec2622e2329e2bdc OK 2024/04/12 03:31:27 INFO : yaml: Copied (new) 2024/04/12 03:31:27 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:28 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (3.02s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:1399: Can track renames: true 2024/04/12 03:31:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Making map for --track-renames 2024/04/12 03:31:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Finished making map for --track-renames 2024/04/12 03:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:29 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/12 03:31:29 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/04/12 03:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for renames to finish 2024/04/12 03:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:29 DEBUG : potato: md5 = 7f1dc01d9a9bfcf35714e13331ac3db2 OK 2024/04/12 03:31:29 INFO : potato: Copied (new) 2024/04/12 03:31:29 DEBUG : yam: md5 = 594a46a277f3db3c5dd6cf581f3ff1c3 OK 2024/04/12 03:31:29 INFO : yam: Copied (new) 2024/04/12 03:31:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Making map for --track-renames 2024/04/12 03:31:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Finished making map for --track-renames 2024/04/12 03:31:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:30 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:30 DEBUG : potato: Unchanged skipping 2024/04/12 03:31:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for renames to finish 2024/04/12 03:31:30 INFO : yam: Copied (server-side copy) to: yaml 2024/04/12 03:31:30 INFO : yam: Deleted 2024/04/12 03:31:30 INFO : yaml: Renamed from "yam" 2024/04/12 03:31:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:30 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (1.95s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:1438: Can track renames: true 2024/04/12 03:31:31 DEBUG : sub: Making directory 2024/04/12 03:31:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Making map for --track-renames 2024/04/12 03:31:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Finished making map for --track-renames 2024/04/12 03:31:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:31 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/12 03:31:31 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/04/12 03:31:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for renames to finish 2024/04/12 03:31:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:31 DEBUG : potato: md5 = 3635f63f08a9d35c7f5cd1ebf6e6e6cb OK 2024/04/12 03:31:31 INFO : potato: Copied (new) 2024/04/12 03:31:31 DEBUG : sub/yam: md5 = fdcf60da3b77c8e7904efe019ef98e18 OK 2024/04/12 03:31:31 INFO : sub/yam: Copied (new) 2024/04/12 03:31:31 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:32 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:32 DEBUG : potato: Unchanged skipping 2024/04/12 03:31:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Making map for --track-renames 2024/04/12 03:31:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Finished making map for --track-renames 2024/04/12 03:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for renames to finish 2024/04/12 03:31:32 INFO : sub/yam: Copied (server-side copy) to: yam 2024/04/12 03:31:32 INFO : sub/yam: Deleted 2024/04/12 03:31:32 INFO : yam: Renamed from "sub/yam" 2024/04/12 03:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:32 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.56s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:33 DEBUG : Creating backend with remote "/tmp/rclone2782105322/dir1" 2024/04/12 03:31:33 DEBUG : Creating backend with remote "/tmp/rclone2782105322/dir2" 2024/04/12 03:31:33 DEBUG : Local file system at /tmp/rclone2782105322/dir2: Using server-side directory move 2024/04/12 03:31:33 INFO : Local file system at /tmp/rclone2782105322/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/12 03:31:33 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/12 03:31:33 INFO : file1.txt: Moved (server-side) 2024/04/12 03:31:33 DEBUG : Local file system at /tmp/rclone2782105322/dir2: Waiting for checks to finish 2024/04/12 03:31:33 DEBUG : Local file system at /tmp/rclone2782105322/dir2: Waiting for transfers to finish --- PASS: TestServerSideMoveLocal (0.11s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:34 DEBUG : nested: Making directory 2024/04/12 03:31:34 DEBUG : sub dir: Making directory 2024/04/12 03:31:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:31:34 DEBUG : nested/sub dir: Making directory 2024/04/12 03:31:34 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/12 03:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:34 DEBUG : sub dir/hello world: md5 = 48c062eba986f6472c653dcca9a19414 OK 2024/04/12 03:31:34 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:31:34 INFO : sub dir/hello world: Deleted 2024/04/12 03:31:34 DEBUG : nested/sub dir/file: md5 = 6b859d875f20102c2664da915ce99aec OK 2024/04/12 03:31:34 INFO : nested/sub dir/file: Copied (new) 2024/04/12 03:31:34 INFO : nested/sub dir/file: Deleted 2024/04/12 03:31:34 INFO : sub dir: Removing directory 2024/04/12 03:31:34 INFO : nested/sub dir: Removing directory 2024/04/12 03:31:34 INFO : nested: Removing directory 2024/04/12 03:31:34 DEBUG : Local file system at /tmp/rclone2782105322: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (1.31s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:35 DEBUG : nested: Making directory 2024/04/12 03:31:35 DEBUG : sub dir: Making directory 2024/04/12 03:31:35 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/12 03:31:35 DEBUG : nested/sub dir: Making directory 2024/04/12 03:31:35 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/12 03:31:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:35 DEBUG : sub dir/hello world: md5 = aeb57f91747e3acdba427b18f3306eec OK 2024/04/12 03:31:35 INFO : sub dir/hello world: Copied (new) 2024/04/12 03:31:35 INFO : sub dir/hello world: Deleted 2024/04/12 03:31:35 DEBUG : nested/sub dir/file: md5 = ad3fe6cc1ab60b07cfe59929d855a716 OK 2024/04/12 03:31:35 INFO : nested/sub dir/file: Copied (new) 2024/04/12 03:31:35 INFO : nested/sub dir/file: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.62s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:37 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/12 03:31:37 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/04/12 03:31:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:37 DEBUG : existing: md5 = d4a31699949b233e27ad653fe6241783 OK 2024/04/12 03:31:37 INFO : existing: Copied (new) 2024/04/12 03:31:37 INFO : existing: Deleted 2024/04/12 03:31:37 DEBUG : existing-b: md5 = 79b5fdddefe583b1cd26d1e8dda63045 OK 2024/04/12 03:31:37 INFO : existing-b: Copied (new) 2024/04/12 03:31:37 INFO : existing-b: Deleted 2024/04/12 03:31:37 DEBUG : existing: Destination exists, skipping 2024/04/12 03:31:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:31:37 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/04/12 03:31:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:31:37 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (2.04s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hoxolig7jidinav3locidip6" 2024/04/12 03:31:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hoxolig7jidinav3locidip6.bin" 2024/04/12 03:31:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hoxolig7jidinav3locidip6" 2024/04/12 03:31:40 DEBUG : potato2: md5 = 82d4a037a16c90877b32ffae8bc967b3 OK 2024/04/12 03:31:40 DEBUG : empty space: md5 = 2b4bb25ae92370216cafd893114cb0ba OK 2024/04/12 03:31:40 DEBUG : potato3: md5 = deacd32272cf09c9a961e61d979f5288 OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7' -> Encrypted drive 'TestCryptSwift:rclone-test-hoxolig7jidinav3locidip6' 2024/04/12 03:31:41 DEBUG : empty space: md5 = ccfcbf421aca4ce6d8d1c55453eaa4bc OK 2024/04/12 03:31:41 DEBUG : potato3: md5 = 7e9e70bfa7148906a3d257b19460c3a6 OK 2024/04/12 03:31:42 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/12 03:31:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hoxolig7jidinav3locidip6': Waiting for checks to finish 2024/04/12 03:31:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:42 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:42 INFO : empty space: Deleted 2024/04/12 03:31:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hoxolig7jidinav3locidip6': Waiting for transfers to finish 2024/04/12 03:31:42 INFO : potato2: Copied (server-side copy) 2024/04/12 03:31:42 INFO : potato3: Copied (server-side copy) 2024/04/12 03:31:42 INFO : potato2: Deleted 2024/04/12 03:31:42 INFO : potato3: Deleted 2024/04/12 03:31:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dowalir5hinahid1jikofet3" 2024/04/12 03:31:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dowalir5hinahid1jikofet3.bin" 2024/04/12 03:31:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dowalir5hinahid1jikofet3" 2024/04/12 03:31:44 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/12 03:31:44 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:44 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/12 03:31:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowalir5hinahid1jikofet3': Waiting for checks to finish 2024/04/12 03:31:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowalir5hinahid1jikofet3': Waiting for transfers to finish 2024/04/12 03:31:45 INFO : potato2: Copied (server-side copy) 2024/04/12 03:31:45 INFO : potato3: Copied (server-side copy) 2024/04/12 03:31:45 INFO : potato2: Deleted 2024/04/12 03:31:45 INFO : empty space: Copied (server-side copy) 2024/04/12 03:31:45 INFO : empty space: Deleted 2024/04/12 03:31:45 INFO : potato3: Deleted 2024/04/12 03:31:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowalir5hinahid1jikofet3': Purge remote 2024/04/12 03:31:46 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:46 INFO : potato3.bin: Deleted 2024/04/12 03:31:46 INFO : potato2.bin: Deleted 2024/04/12 03:31:46 INFO : empty space.bin: Deleted 2024/04/12 03:31:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hoxolig7jidinav3locidip6': Purge remote 2024/04/12 03:31:46 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (7.75s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jopidiy5baqazov8riticuz7" 2024/04/12 03:31:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jopidiy5baqazov8riticuz7.bin" 2024/04/12 03:31:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jopidiy5baqazov8riticuz7" 2024/04/12 03:31:48 DEBUG : potato2: md5 = 9448109580cc00226cdd307db851b0a7 OK 2024/04/12 03:31:48 DEBUG : empty space: md5 = f0b15c69a5cdd54be131a0546d5aef89 OK 2024/04/12 03:31:48 DEBUG : potato3: md5 = 8cd7a62fa9549f09f7fb6249ed57e7c4 OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7' -> Encrypted drive 'TestCryptSwift:rclone-test-jopidiy5baqazov8riticuz7' 2024/04/12 03:31:49 DEBUG : empty space: md5 = 9489a1c6276b76ef1954d364569a04ec OK 2024/04/12 03:31:49 DEBUG : potato3: md5 = ebf2bb2c9645bdf9d3a2a20b89135ad2 OK 2024/04/12 03:31:49 DEBUG : empty space: Excluded (Size Filter) 2024/04/12 03:31:49 DEBUG : empty space: Excluded 2024/04/12 03:31:49 DEBUG : empty space: Excluded (Size Filter) 2024/04/12 03:31:49 DEBUG : empty space: Excluded 2024/04/12 03:31:49 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:49 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/12 03:31:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jopidiy5baqazov8riticuz7': Waiting for checks to finish 2024/04/12 03:31:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jopidiy5baqazov8riticuz7': Waiting for transfers to finish 2024/04/12 03:31:50 INFO : potato2: Copied (server-side copy) 2024/04/12 03:31:50 INFO : potato2: Deleted 2024/04/12 03:31:50 INFO : potato3: Copied (server-side copy) 2024/04/12 03:31:50 INFO : potato3: Deleted 2024/04/12 03:31:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fugixer1tagodoc6dakicic8" 2024/04/12 03:31:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fugixer1tagodoc6dakicic8.bin" 2024/04/12 03:31:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fugixer1tagodoc6dakicic8" 2024/04/12 03:31:52 DEBUG : empty space: Excluded (Size Filter) 2024/04/12 03:31:52 DEBUG : empty space: Excluded 2024/04/12 03:31:52 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:52 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/12 03:31:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fugixer1tagodoc6dakicic8': Waiting for checks to finish 2024/04/12 03:31:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fugixer1tagodoc6dakicic8': Waiting for transfers to finish 2024/04/12 03:31:52 INFO : potato2: Copied (server-side copy) 2024/04/12 03:31:52 INFO : potato3: Copied (server-side copy) 2024/04/12 03:31:52 INFO : potato2: Deleted 2024/04/12 03:31:52 INFO : potato3: Deleted 2024/04/12 03:31:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fugixer1tagodoc6dakicic8': Purge remote 2024/04/12 03:31:53 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:53 INFO : potato2.bin: Deleted 2024/04/12 03:31:53 INFO : potato3.bin: Deleted 2024/04/12 03:31:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jopidiy5baqazov8riticuz7': Purge remote 2024/04/12 03:31:53 DEBUG : Waiting for deletions to finish 2024/04/12 03:31:53 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (7.72s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:31:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zehukif2kulecun1rirokaz3" 2024/04/12 03:31:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zehukif2kulecun1rirokaz3.bin" 2024/04/12 03:31:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zehukif2kulecun1rirokaz3" 2024/04/12 03:31:56 DEBUG : potato2: md5 = 50c8fdd7cd13ad2a1fc4f73988ee9765 OK 2024/04/12 03:31:56 DEBUG : empty space: md5 = 46195a3bfa57f15f9cd0b6a513e13d58 OK 2024/04/12 03:31:56 DEBUG : potato3: md5 = e506c322031d2ab84fe304cdf16510c1 OK 2024/04/12 03:31:56 DEBUG : tomatoDir: Making directory sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7' -> Encrypted drive 'TestCryptSwift:rclone-test-zehukif2kulecun1rirokaz3' 2024/04/12 03:31:57 DEBUG : empty space: md5 = 3e8eba43bd7b051e064d84901b18f08f OK 2024/04/12 03:31:57 DEBUG : potato3: md5 = 1e541103ec982453175de25763f41cc7 OK 2024/04/12 03:31:57 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:31:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/12 03:31:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zehukif2kulecun1rirokaz3': Waiting for checks to finish 2024/04/12 03:31:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:31:57 DEBUG : empty space: Unchanged skipping 2024/04/12 03:31:58 INFO : potato2: Copied (server-side copy) 2024/04/12 03:31:58 INFO : potato3: Copied (server-side copy) 2024/04/12 03:31:58 INFO : potato2: Deleted 2024/04/12 03:31:58 INFO : empty space: Deleted 2024/04/12 03:31:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zehukif2kulecun1rirokaz3': Waiting for transfers to finish 2024/04/12 03:31:58 INFO : potato3: Deleted 2024/04/12 03:31:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bunuyey0sevikof9boqecop1" 2024/04/12 03:31:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bunuyey0sevikof9boqecop1.bin" 2024/04/12 03:31:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bunuyey0sevikof9boqecop1" 2024/04/12 03:32:00 DEBUG : tomatoDir: Making directory 2024/04/12 03:32:00 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/12 03:32:00 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/12 03:32:00 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/12 03:32:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bunuyey0sevikof9boqecop1': Waiting for checks to finish 2024/04/12 03:32:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bunuyey0sevikof9boqecop1': Waiting for transfers to finish 2024/04/12 03:32:01 INFO : empty space: Copied (server-side copy) 2024/04/12 03:32:01 INFO : potato3: Copied (server-side copy) 2024/04/12 03:32:01 INFO : potato2: Copied (server-side copy) 2024/04/12 03:32:01 INFO : empty space: Deleted 2024/04/12 03:32:01 INFO : potato3: Deleted 2024/04/12 03:32:01 INFO : potato2: Deleted 2024/04/12 03:32:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bunuyey0sevikof9boqecop1': Purge remote 2024/04/12 03:32:02 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:02 INFO : potato2.bin: Deleted 2024/04/12 03:32:02 INFO : potato3.bin: Deleted 2024/04/12 03:32:02 INFO : empty space.bin: Deleted 2024/04/12 03:32:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zehukif2kulecun1rirokaz3': Purge remote 2024/04/12 03:32:02 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (8.67s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-move-test" 2024/04/12 03:32:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-move-test.bin" 2024/04/12 03:32:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-move-test" 2024/04/12 03:32:04 DEBUG : potato2: md5 = 7582589db8f65c27b2a655b49e0b0928 OK --- PASS: TestServerSideMoveOverlap (2.03s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test" 2024/04/12 03:32:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test.bin" 2024/04/12 03:32:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncOverlap (2.15s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test" 2024/04/12 03:32:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test.bin" 2024/04/12 03:32:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test" 2024/04/12 03:32:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-include/layer2" 2024/04/12 03:32:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test-include/layer2.bin" 2024/04/12 03:32:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test-include/layer2" 2024/04/12 03:32:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-ignore-file" 2024/04/12 03:32:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test-ignore-file.bin" 2024/04/12 03:32:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/rclone-sync-test-ignore-file" 2024/04/12 03:32:11 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 1990cb5aec9ff565eff1e6ff47099eda OK 2024/04/12 03:32:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/12 03:32:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test': Waiting for checks to finish 2024/04/12 03:32:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test': Waiting for transfers to finish 2024/04/12 03:32:11 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:11 INFO : There was nothing to transfer 2024/04/12 03:32:11 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/12 03:32:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:32:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:32:11 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:11 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/04/12 03:32:12 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:32:12 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:12 DEBUG : : Excluded 2024/04/12 03:32:12 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/04/12 03:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/04/12 03:32:12 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:12 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (8.48s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:32:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/CompareDest" 2024/04/12 03:32:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/CompareDest.bin" 2024/04/12 03:32:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/CompareDest" 2024/04/12 03:32:18 DEBUG : one: Need to transfer - File not found at Destination 2024/04/12 03:32:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:18 DEBUG : one: md5 = 957f8c6dc57d730ec4fce69ba818773a OK 2024/04/12 03:32:18 INFO : one: Copied (new) 2024/04/12 03:32:18 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:19 DEBUG : one: md5 = 12fff5a8e9f32e5739ed7a461e770125 OK 2024/04/12 03:32:19 INFO : one: Copied (replaced existing) 2024/04/12 03:32:19 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:20 DEBUG : dst/one: md5 = 258f39750c6324531c99d3229dc79fe1 OK 2024/04/12 03:32:20 DEBUG : CompareDest/one: md5 = 648e765b9ba066dfc91543a3cf0a4b50 OK 2024/04/12 03:32:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:20 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:20 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:20 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/12 03:32:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:20 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:20 INFO : There was nothing to transfer 2024/04/12 03:32:21 DEBUG : CompareDest/two: md5 = 03f550c1b8e21182c74c9f23cf416683 OK 2024/04/12 03:32:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:21 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/12 03:32:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:21 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/12 03:32:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:21 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:21 INFO : There was nothing to transfer 2024/04/12 03:32:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:22 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/12 03:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:22 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/12 03:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:22 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:22 INFO : There was nothing to transfer sync_test.go:1964: No hash on uploaded file so skipping compare timestamp test 2024/04/12 03:32:22 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:22 DEBUG : two: Need to transfer - File not found at Destination 2024/04/12 03:32:22 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:22 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/12 03:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:23 DEBUG : two: md5 = cd89c854eab3938c769ba4d8e89d5e06 OK 2024/04/12 03:32:23 INFO : two: Copied (new) 2024/04/12 03:32:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (8.61s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:24 DEBUG : pre-dest1/1: md5 = 958b0ffb0e20962327cafe9679bdcb2e OK 2024/04/12 03:32:24 DEBUG : pre-dest2/2: md5 = 87960841c99073d7f734c5a10a0b8383 OK 2024/04/12 03:32:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dest" 2024/04/12 03:32:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dest.bin" 2024/04/12 03:32:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dest" 2024/04/12 03:32:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/pre-dest1" 2024/04/12 03:32:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/pre-dest1.bin" 2024/04/12 03:32:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/pre-dest1" 2024/04/12 03:32:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/pre-dest2" 2024/04/12 03:32:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/pre-dest2.bin" 2024/04/12 03:32:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/pre-dest2" 2024/04/12 03:32:29 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:29 DEBUG : 1: Destination found in --compare-dest, skipping 2024/04/12 03:32:29 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:29 DEBUG : 2: Destination found in --compare-dest, skipping 2024/04/12 03:32:29 DEBUG : 3: Need to transfer - File not found at Destination 2024/04/12 03:32:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dest': Waiting for checks to finish 2024/04/12 03:32:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dest': Waiting for transfers to finish 2024/04/12 03:32:30 DEBUG : 3: md5 = 175f58ba47701a0e076b519b10b24995 OK 2024/04/12 03:32:30 INFO : 3: Copied (new) 2024/04/12 03:32:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.02s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:32:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/CopyDest" 2024/04/12 03:32:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/CopyDest.bin" 2024/04/12 03:32:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/CopyDest" 2024/04/12 03:32:34 DEBUG : one: Need to transfer - File not found at Destination 2024/04/12 03:32:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:34 DEBUG : one: md5 = a831f6011cbe48fab1ee142d95d7a466 OK 2024/04/12 03:32:34 INFO : one: Copied (new) 2024/04/12 03:32:34 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:34 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:35 DEBUG : one: md5 = 94b73fc94fddb9cd990eaa6b78402268 OK 2024/04/12 03:32:35 INFO : one: Copied (replaced existing) 2024/04/12 03:32:35 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:35 DEBUG : dst/one: md5 = 3b90f9de18d956014da1cf6e869b543b OK 2024/04/12 03:32:36 DEBUG : CopyDest/one: md5 = 98b3531de77115d3d84eb8673261f304 OK 2024/04/12 03:32:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/BackupDir" 2024/04/12 03:32:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/BackupDir.bin" 2024/04/12 03:32:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/BackupDir" 2024/04/12 03:32:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:37 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:38 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/12 03:32:38 INFO : one: Copied (server-side copy) 2024/04/12 03:32:38 INFO : one: Deleted 2024/04/12 03:32:38 INFO : one: Copied (server-side copy) 2024/04/12 03:32:38 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/12 03:32:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:38 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:39 DEBUG : CopyDest/two: md5 = 5ad4947120def2e88abbfc1c59fdef43 OK 2024/04/12 03:32:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:40 INFO : two: Copied (server-side copy) 2024/04/12 03:32:40 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/12 03:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:40 DEBUG : one: Unchanged skipping 2024/04/12 03:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:40 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:40 DEBUG : one: Unchanged skipping 2024/04/12 03:32:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:41 DEBUG : two: Unchanged skipping 2024/04/12 03:32:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:41 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:41 INFO : There was nothing to transfer 2024/04/12 03:32:41 DEBUG : CopyDest/three: md5 = 00a475be2ea781c6b805ca49ba13121e OK 2024/04/12 03:32:42 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/12 03:32:42 DEBUG : three: Destination not found in --copy-dest 2024/04/12 03:32:42 DEBUG : three: Need to transfer - File not found at Destination 2024/04/12 03:32:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:42 DEBUG : one: Unchanged skipping 2024/04/12 03:32:42 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:42 DEBUG : two: Unchanged skipping 2024/04/12 03:32:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:42 DEBUG : three: md5 = cd4d8980f0de2c7871423e7f8ea2ac5c OK 2024/04/12 03:32:42 INFO : three: Copied (new) 2024/04/12 03:32:42 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (13.22s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:44 DEBUG : dst/one: md5 = 9a859b061aa47b508cbbbd5bc0491bc3 OK 2024/04/12 03:32:45 DEBUG : dst/two: md5 = aecb4fa7fc0a8347f494ef27355baf39 OK 2024/04/12 03:32:45 DEBUG : dst/three.txt: md5 = 4db8626eb720b318f3efbc326c38fee8 OK 2024/04/12 03:32:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:32:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:32:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup.bin" 2024/04/12 03:32:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:32:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:32:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:48 DEBUG : two: Unchanged skipping 2024/04/12 03:32:48 INFO : one: Copied (server-side copy) 2024/04/12 03:32:49 INFO : one: Deleted 2024/04/12 03:32:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:49 DEBUG : one: md5 = 35c5a3f04af5a842a14638f1b636dc64 OK 2024/04/12 03:32:49 INFO : one: Copied (new) 2024/04/12 03:32:49 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:49 INFO : three.txt: Copied (server-side copy) 2024/04/12 03:32:49 INFO : three.txt: Deleted 2024/04/12 03:32:49 INFO : three.txt: Moved into backup dir 2024/04/12 03:32:50 DEBUG : dst/three.txt: md5 = 22a10af50c2094d1eaeb04b14ea4b0b7 OK 2024/04/12 03:32:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:32:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:50 DEBUG : two: Unchanged skipping 2024/04/12 03:32:51 INFO : one: Copied (server-side copy) 2024/04/12 03:32:51 INFO : one: Deleted 2024/04/12 03:32:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:51 DEBUG : one: md5 = 855c84cf822a1e0c07f1adfa106b0277 OK 2024/04/12 03:32:51 INFO : one: Copied (new) 2024/04/12 03:32:51 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:52 INFO : three.txt: Copied (server-side copy) 2024/04/12 03:32:52 INFO : three.txt: Deleted 2024/04/12 03:32:52 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (8.67s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:32:53 DEBUG : dst/one: md5 = 436742c1314f2f6facaf782420304a38 OK 2024/04/12 03:32:53 DEBUG : dst/two: md5 = dad9c5f79b85e88922c5c65e5a9bb3f9 OK 2024/04/12 03:32:53 DEBUG : dst/three.txt: md5 = 7be4d9cd6d879aec4fedbc4bcc60e220 OK 2024/04/12 03:32:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:32:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:32:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:32:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup.bin" 2024/04/12 03:32:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:32:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:32:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:56 DEBUG : two: Unchanged skipping 2024/04/12 03:32:57 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:32:57 INFO : one: Deleted 2024/04/12 03:32:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:32:57 DEBUG : one: md5 = f8e24f22d730e0ab4058b2c4661a66fa OK 2024/04/12 03:32:57 INFO : one: Copied (new) 2024/04/12 03:32:57 DEBUG : Waiting for deletions to finish 2024/04/12 03:32:58 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:32:58 INFO : three.txt: Deleted 2024/04/12 03:32:58 INFO : three.txt: Moved into backup dir 2024/04/12 03:32:59 DEBUG : dst/three.txt: md5 = 8ef1fea13c4e5bc56a7863a1b17ad0d8 OK 2024/04/12 03:32:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:32:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:32:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:32:59 DEBUG : two: Unchanged skipping 2024/04/12 03:32:59 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:32:59 INFO : one: Deleted 2024/04/12 03:32:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:33:00 DEBUG : one: md5 = 0c1ec3dec8da828a22cddf19d9b1b392 OK 2024/04/12 03:33:00 INFO : one: Copied (new) 2024/04/12 03:33:00 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:00 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:33:00 INFO : three.txt: Deleted 2024/04/12 03:33:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (8.32s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:01 DEBUG : dst/one: md5 = 1446149815ab3de0fc339ae18d59bbdf OK 2024/04/12 03:33:02 DEBUG : dst/two: md5 = 444c727d97da5d4151f897c2501b36a8 OK 2024/04/12 03:33:02 DEBUG : dst/three.txt: md5 = 3dacb6c173ea39b7539cc9def0811d2e OK 2024/04/12 03:33:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:33:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:33:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup.bin" 2024/04/12 03:33:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/backup" 2024/04/12 03:33:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:33:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:33:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:04 DEBUG : two: Unchanged skipping 2024/04/12 03:33:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/12 03:33:05 INFO : one: Deleted 2024/04/12 03:33:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:33:05 DEBUG : one: md5 = e040cfb2d5089f623a2e33161c28650e OK 2024/04/12 03:33:05 INFO : one: Copied (new) 2024/04/12 03:33:05 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:06 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/12 03:33:06 INFO : three.txt: Deleted 2024/04/12 03:33:06 INFO : three.txt: Moved into backup dir 2024/04/12 03:33:06 DEBUG : dst/three.txt: md5 = 81ebed76c5d6486e080a0ac2aabb1949 OK 2024/04/12 03:33:07 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:33:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:33:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:07 DEBUG : two: Unchanged skipping 2024/04/12 03:33:07 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/12 03:33:07 INFO : one: Deleted 2024/04/12 03:33:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:33:07 DEBUG : one: md5 = 5c062db4d39d9d7231acfd9a35da332b OK 2024/04/12 03:33:07 INFO : one: Copied (new) 2024/04/12 03:33:07 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:08 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/12 03:33:08 INFO : three.txt: Deleted 2024/04/12 03:33:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (8.30s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:10 DEBUG : dst/one: md5 = afbdc7a70ff1622465fc9b2179a0e8ee OK 2024/04/12 03:33:10 DEBUG : dst/two: md5 = 42094ffec2dd6a43066f8e300b6c80c5 OK 2024/04/12 03:33:10 DEBUG : dst/three.txt: md5 = d668b8309e8f5f6cf64f1ce82793e951 OK 2024/04/12 03:33:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:33:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:33:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:33:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:12 DEBUG : two: Unchanged skipping 2024/04/12 03:33:12 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:33:12 INFO : one: Deleted 2024/04/12 03:33:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:33:12 DEBUG : one: md5 = 4293a4f46bea4a260ad09b2e40fd9548 OK 2024/04/12 03:33:12 INFO : one: Copied (new) 2024/04/12 03:33:12 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:13 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:33:13 INFO : three.txt: Deleted 2024/04/12 03:33:13 INFO : three.txt: Moved into backup dir 2024/04/12 03:33:14 DEBUG : dst/three.txt: md5 = 8533fd6425a847cd78390cce63062f4e OK 2024/04/12 03:33:15 DEBUG : one.bak: Excluded (Path Filter) 2024/04/12 03:33:15 DEBUG : one.bak: Excluded 2024/04/12 03:33:15 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/04/12 03:33:15 DEBUG : three.txt.bak: Excluded 2024/04/12 03:33:15 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:33:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for checks to finish 2024/04/12 03:33:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:15 DEBUG : two: Unchanged skipping 2024/04/12 03:33:15 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:33:15 INFO : one: Deleted 2024/04/12 03:33:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst': Waiting for transfers to finish 2024/04/12 03:33:15 DEBUG : one: md5 = d41e2612f8e90924511362a2915682c0 OK 2024/04/12 03:33:15 INFO : one: Copied (new) 2024/04/12 03:33:15 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:33:16 INFO : three.txt: Deleted 2024/04/12 03:33:16 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (7.87s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:18 DEBUG : dst/one: md5 = 5ee14b3cd6aeb6e01db2fbeb145ecb60 OK 2024/04/12 03:33:18 DEBUG : dst/two: md5 = 1a1272cb94fd22a1cea2301158507a0d OK 2024/04/12 03:33:18 DEBUG : dst/three.txt: md5 = 84b989a07b87a9e13fc8542e4e1807fa OK 2024/04/12 03:33:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:33:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:20 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:33:20 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:33:20 INFO : one: Deleted 2024/04/12 03:33:20 DEBUG : one: md5 = 89041fe3efbbd989e2dad4e28b00af80 OK 2024/04/12 03:33:20 INFO : one: Copied (new) 2024/04/12 03:33:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:20 DEBUG : two: Unchanged skipping 2024/04/12 03:33:20 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/12 03:33:21 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:33:21 INFO : three.txt: Deleted 2024/04/12 03:33:21 DEBUG : three.txt: md5 = a73a72eb942f9655b8d7d6d28f1bf630 OK 2024/04/12 03:33:21 INFO : three.txt: Copied (new) 2024/04/12 03:33:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:33:22 INFO : one: Copied (server-side copy) to: one.bak 2024/04/12 03:33:23 INFO : one: Deleted 2024/04/12 03:33:23 DEBUG : one: md5 = 1ad247b30ecd18319b871d843c00c7db OK 2024/04/12 03:33:23 INFO : one: Copied (new) 2024/04/12 03:33:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:23 DEBUG : two: Unchanged skipping 2024/04/12 03:33:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/12 03:33:23 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/12 03:33:23 INFO : three.txt: Deleted 2024/04/12 03:33:23 DEBUG : three.txt: md5 = 2e530f921ce753fbc886fc1d1a6780f5 OK 2024/04/12 03:33:23 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (7.52s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:25 DEBUG : dst/one: md5 = 17fc0e24b4421e979d48d5042a27da20 OK 2024/04/12 03:33:25 DEBUG : dst/two: md5 = 21c14e6d21bd22001ce7885537f16cb2 OK 2024/04/12 03:33:26 DEBUG : dst/three.txt: md5 = aef981fd93cafbbb9375aeee62008ee0 OK 2024/04/12 03:33:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst.bin" 2024/04/12 03:33:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-venigup7qigohal6toximac7/dst" 2024/04/12 03:33:27 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/12 03:33:28 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/12 03:33:28 INFO : one: Deleted 2024/04/12 03:33:28 DEBUG : one: md5 = c43bcbee4f3b0f722136eb6796bc8b64 OK 2024/04/12 03:33:28 INFO : one: Copied (new) 2024/04/12 03:33:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:28 DEBUG : two: Unchanged skipping 2024/04/12 03:33:28 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/12 03:33:28 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/12 03:33:29 INFO : three.txt: Deleted 2024/04/12 03:33:29 DEBUG : three.txt: md5 = 21b0e6d144654005b853137ad52eb39a OK 2024/04/12 03:33:29 INFO : three.txt: Copied (new) 2024/04/12 03:33:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/12 03:33:30 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/12 03:33:30 INFO : one: Deleted 2024/04/12 03:33:31 DEBUG : one: md5 = cc3ee7b3df6fb32b105db9927da51d49 OK 2024/04/12 03:33:31 INFO : one: Copied (new) 2024/04/12 03:33:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:31 DEBUG : two: Unchanged skipping 2024/04/12 03:33:31 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/12 03:33:31 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/12 03:33:31 INFO : three.txt: Deleted 2024/04/12 03:33:31 DEBUG : three.txt: md5 = 2f92d170c27fecb2399721874df15654 OK 2024/04/12 03:33:31 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (7.84s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:33 DEBUG : Testêé: md5 = 94432ff01a4b86937e8e60da0fb2ff12 OK 2024/04/12 03:33:34 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/04/12 03:33:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:33:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:33:34 DEBUG : Testêé: md5 = 90158111b9dc62819c74bf5381216f5e OK 2024/04/12 03:33:34 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/04/12 03:33:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.77s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:35 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/12 03:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:33:35 DEBUG : existing: md5 = 7bd4bfba98581a8432edc12893cccba8 OK 2024/04/12 03:33:35 INFO : existing: Copied (new) 2024/04/12 03:33:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:35 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/04/12 03:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:33:35 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/04/12 03:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:33:35 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': not deleting files as there were IO errors 2024/04/12 03:33:35 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncImmutable (2.01s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:37 DEBUG : EXISTING: md5 = e7a33256856647c92b6306ae193ecc78 OK 2024/04/12 03:33:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:33:37 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:37 DEBUG : existing: Unchanged skipping 2024/04/12 03:33:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:33:37 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:37 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.31s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:2420: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.21s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" sync_test.go:2459: This test only runs on local --- PASS: TestMaxTransfer (0.39s) --- SKIP: TestMaxTransfer/Hard (0.19s) --- SKIP: TestMaxTransfer/Soft (0.10s) --- SKIP: TestMaxTransfer/Cautious (0.10s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:33:39 DEBUG : both0: md5 = d772d08ebcb73392a8cdcfbf176a4c8f OK 2024/04/12 03:33:39 DEBUG : only0: md5 = 141ba3fee44a5c5d5af3723839c8a632 OK 2024/04/12 03:33:39 DEBUG : both1: md5 = 6e958028a958c796da2b68453df20401 OK 2024/04/12 03:33:40 DEBUG : only1: md5 = 862af591ab000293d993da1462f8772c OK 2024/04/12 03:33:40 DEBUG : both2: md5 = 42368450dcdb29405a9473aedcfe80dc OK 2024/04/12 03:33:40 DEBUG : only2: md5 = b2fc65c8fd92bf45775fdc21c25b748a OK 2024/04/12 03:33:40 DEBUG : both3: md5 = 83ed45bfc4d9a39f0d04f82387a18712 OK 2024/04/12 03:33:41 DEBUG : only3: md5 = d7ad09891ec37847cfe2c03588013b63 OK 2024/04/12 03:33:41 DEBUG : both4: md5 = 55822a506fd815b0ca63b48f0b06b380 OK 2024/04/12 03:33:41 DEBUG : only4: md5 = 882f6fb7f6c6420fa9a232c52f8fc485 OK 2024/04/12 03:33:42 DEBUG : both5: md5 = 2f9f1bc7a8dbccec3aeb47cf65a674e5 OK 2024/04/12 03:33:42 DEBUG : only5: md5 = 358bf9e36b15107787fa867c064c7f58 OK 2024/04/12 03:33:42 DEBUG : both6: md5 = 9fb4e0553009c636364c8dbb5644a44c OK 2024/04/12 03:33:43 DEBUG : only6: md5 = afc62b1d723e882953dfdb1011f3341e OK 2024/04/12 03:33:43 DEBUG : both7: md5 = fbd88f759d9ce895a6ee75d79d53712e OK 2024/04/12 03:33:43 DEBUG : only7: md5 = 65bae394b568fc1089bf3f1c006a5826 OK 2024/04/12 03:33:44 DEBUG : both8: md5 = 7d87bc21d7e8622d079277c47998035d OK 2024/04/12 03:33:44 DEBUG : only8: md5 = 521d8627158d99b07e8cbafbf00089f6 OK 2024/04/12 03:33:44 DEBUG : both9: md5 = 46210b55f42b0d004c394799b42764a2 OK 2024/04/12 03:33:44 DEBUG : only9: md5 = 03a0ef986a99e511b4b008029e7f9304 OK 2024/04/12 03:33:45 DEBUG : both10: md5 = 360dbac87f1eb70ab6895b366f7200d3 OK 2024/04/12 03:33:45 DEBUG : only10: md5 = 4f064d599a3e1df53e0f5a6939a0c037 OK 2024/04/12 03:33:45 DEBUG : both11: md5 = 40f23ad9465e4d91778a4345db308cf9 OK 2024/04/12 03:33:46 DEBUG : only11: md5 = 73ed3cb16483471445d361fb007e0c09 OK 2024/04/12 03:33:46 DEBUG : both12: md5 = 7dd1bb040f8eca7a61b2b0304fdf6984 OK 2024/04/12 03:33:46 DEBUG : only12: md5 = 006f7a35327028b42f170a3218e83af5 OK 2024/04/12 03:33:47 DEBUG : both13: md5 = 01fed8e262e56adcaa64813168586655 OK 2024/04/12 03:33:47 DEBUG : only13: md5 = 116ff68a67658490dfe148cb53a7176e OK 2024/04/12 03:33:47 DEBUG : both14: md5 = 1adc6ac2bc11a1f63c7fc6b998676bed OK 2024/04/12 03:33:47 DEBUG : only14: md5 = fba1b3d550213c3b57a42abe056dd6e4 OK 2024/04/12 03:33:48 DEBUG : both15: md5 = 316071c05c441a962fcf1eb93ab16679 OK 2024/04/12 03:33:48 DEBUG : only15: md5 = b91b1759e4ea794b80bc34f82e610ba3 OK 2024/04/12 03:33:49 DEBUG : both16: md5 = edd6c1d4ebdb906ea741be8c8356e983 OK 2024/04/12 03:33:49 DEBUG : only16: md5 = 8059729c42cfa6c8be7c1f63f4b2e7db OK 2024/04/12 03:33:49 DEBUG : both17: md5 = b6fce86699337cc8405029a6d14c1da8 OK 2024/04/12 03:33:49 DEBUG : only17: md5 = c778ec1355d24bc04af61e956734ea54 OK 2024/04/12 03:33:50 DEBUG : both18: md5 = cd52695d84b8d7774a83356f7e155b6a OK 2024/04/12 03:33:50 DEBUG : only18: md5 = dd17c5bb002cf9015e4404714d183051 OK 2024/04/12 03:33:50 DEBUG : both19: md5 = 39eea951349879994e59263abb873fae OK 2024/04/12 03:33:51 DEBUG : only19: md5 = 723a2de31d8dded1e8fd3991f3276922 OK 2024/04/12 03:33:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:33:53 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both0: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both1: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both11: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both10: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both12: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both13: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both14: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both15: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both17: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both18: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both16: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both19: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both2: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both3: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both4: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both5: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both6: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both7: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both9: Unchanged skipping 2024/04/12 03:33:53 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:33:53 DEBUG : both8: Unchanged skipping 2024/04/12 03:33:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:33:53 DEBUG : Waiting for deletions to finish 2024/04/12 03:33:53 INFO : only0: Deleted 2024/04/12 03:33:53 INFO : only13: Deleted 2024/04/12 03:33:53 INFO : only9: Deleted 2024/04/12 03:33:53 INFO : only14: Deleted 2024/04/12 03:33:53 INFO : only6: Deleted 2024/04/12 03:33:53 INFO : only10: Deleted 2024/04/12 03:33:53 INFO : only7: Deleted 2024/04/12 03:33:54 INFO : only4: Deleted 2024/04/12 03:33:54 INFO : only17: Deleted 2024/04/12 03:33:54 INFO : only15: Deleted 2024/04/12 03:33:54 INFO : only19: Deleted 2024/04/12 03:33:54 INFO : only8: Deleted 2024/04/12 03:33:54 INFO : only1: Deleted 2024/04/12 03:33:54 INFO : only12: Deleted 2024/04/12 03:33:54 INFO : only16: Deleted 2024/04/12 03:33:54 INFO : only18: Deleted 2024/04/12 03:33:54 INFO : only2: Deleted 2024/04/12 03:33:54 INFO : only3: Deleted 2024/04/12 03:33:54 INFO : only5: Deleted 2024/04/12 03:33:54 INFO : only11: Deleted 2024/04/12 03:33:54 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (20.96s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:34:00 DEBUG : both0: md5 = ccf3555ca10e8fcf01ff48f23b0d1349 OK 2024/04/12 03:34:00 DEBUG : only0: md5 = 70f863636ecb695fb208d4d082d31811 OK 2024/04/12 03:34:00 DEBUG : both1: md5 = 35fee1a9fec7ffc230a535864842899a OK 2024/04/12 03:34:00 DEBUG : only1: md5 = 6a4a7fcb4df805364fb1d3413ea16092 OK 2024/04/12 03:34:00 DEBUG : both2: md5 = c138145d6f62943b8e8da8f9266f6885 OK 2024/04/12 03:34:00 DEBUG : only2: md5 = bb344b8d1acba5edc4cca130ca74849b OK 2024/04/12 03:34:01 DEBUG : both3: md5 = 1ce59d2a7fda5fa4180a8ac4c60863ec OK 2024/04/12 03:34:01 DEBUG : only3: md5 = c35b81a809239e06b512858dfea38f0a OK 2024/04/12 03:34:01 DEBUG : both4: md5 = b8288a3b5f244d7856d0e14fb985a520 OK 2024/04/12 03:34:01 DEBUG : only4: md5 = 0dda5e0741c8849d3373af2abe73a8e9 OK 2024/04/12 03:34:01 DEBUG : both5: md5 = 847615c559b59c7b8452a50bdd5b5fc9 OK 2024/04/12 03:34:01 DEBUG : only5: md5 = ceddb722981d481585240142f5547a8c OK 2024/04/12 03:34:02 DEBUG : both6: md5 = 32ed3c176970cb811a0b0d249acf1cf1 OK 2024/04/12 03:34:02 DEBUG : only6: md5 = 695c000aa6c491e417cba527f3ae359f OK 2024/04/12 03:34:02 DEBUG : both7: md5 = 2f892a534c7449e47b63cd646f1b56fe OK 2024/04/12 03:34:02 DEBUG : only7: md5 = e513130102bee02313f9f5c12cb090ef OK 2024/04/12 03:34:02 DEBUG : both8: md5 = 29cfa81ed4d9ee98ac3dfbd83c21c7ef OK 2024/04/12 03:34:02 DEBUG : only8: md5 = a7919c73114a7c08798fdfc404fd7e4b OK 2024/04/12 03:34:03 DEBUG : both9: md5 = 52406748ffd08338f361b37a78e371b8 OK 2024/04/12 03:34:03 DEBUG : only9: md5 = 61f430601268f285f38e1d72f9ea9a1b OK 2024/04/12 03:34:03 DEBUG : both10: md5 = 2df543b8700d7cc637205413c44366de OK 2024/04/12 03:34:03 DEBUG : only10: md5 = 2662804cbdcf2bf8792c721476242b9d OK 2024/04/12 03:34:03 DEBUG : both11: md5 = b7bd32fd91e807d36668450c01e13aa4 OK 2024/04/12 03:34:04 DEBUG : only11: md5 = 30fc84988e1889d37a686ad78d310412 OK 2024/04/12 03:34:04 DEBUG : both12: md5 = 61951c410f4d32e98fcb1ba576eb5b3f OK 2024/04/12 03:34:04 DEBUG : only12: md5 = 3f9f1d8dca9bb659c3f25898211ac6ae OK 2024/04/12 03:34:04 DEBUG : both13: md5 = e91272a1782c20a91373a517ec470eb0 OK 2024/04/12 03:34:04 DEBUG : only13: md5 = 287f9b3ba74cb321c7a912eb7ec217ef OK 2024/04/12 03:34:04 DEBUG : both14: md5 = d99f21621b36b40e8e493c572fb26f69 OK 2024/04/12 03:34:05 DEBUG : only14: md5 = 06c5c5070e5a457d0791389f912da697 OK 2024/04/12 03:34:05 DEBUG : both15: md5 = 3100723874786e34fdbc08a385487abd OK 2024/04/12 03:34:05 DEBUG : only15: md5 = 6789b663ee7db8498af6809412bee356 OK 2024/04/12 03:34:05 DEBUG : both16: md5 = 7ad4b72ef7bcecb05dd01756fec11098 OK 2024/04/12 03:34:05 DEBUG : only16: md5 = 3bab4888c74464dbba165f4298a7b5c3 OK 2024/04/12 03:34:05 DEBUG : both17: md5 = 6cb2d679af08ba0acbcdfc041b652ac7 OK 2024/04/12 03:34:06 DEBUG : only17: md5 = c16c65d8fd9a1a1a014091c3d9dc0b5d OK 2024/04/12 03:34:06 DEBUG : both18: md5 = d9531cbfcaa7c19447583122408e56e2 OK 2024/04/12 03:34:06 DEBUG : only18: md5 = 655867003ed4aecb4065c149d9f87ce7 OK 2024/04/12 03:34:06 DEBUG : both19: md5 = 308e0cea9046f51e5d2a617fb7e839e5 OK 2024/04/12 03:34:07 DEBUG : only19: md5 = 629b95f94ec757e123cd3dec9c850c66 OK 2024/04/12 03:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for checks to finish 2024/04/12 03:34:09 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both1: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both0: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both10: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both11: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both12: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both13: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both14: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both15: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both16: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both17: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both18: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both19: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both2: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both3: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both4: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both5: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both6: Unchanged skipping 2024/04/12 03:34:09 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/04/12 03:34:09 DEBUG : both7: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both8: Unchanged skipping 2024/04/12 03:34:09 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/12 03:34:09 DEBUG : both9: Unchanged skipping 2024/04/12 03:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Waiting for transfers to finish 2024/04/12 03:34:09 DEBUG : only10: md5 = 932e5b16231211154cd7cace1fbedfbb OK 2024/04/12 03:34:09 INFO : only10: Copied (replaced existing) 2024/04/12 03:34:09 DEBUG : only1: md5 = b92d04ad021e745faa179ef2d7df082a OK 2024/04/12 03:34:09 INFO : only1: Copied (replaced existing) 2024/04/12 03:34:09 DEBUG : only11: md5 = 9bdd983756cad80ae3c1b7571db0e187 OK 2024/04/12 03:34:09 INFO : only11: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only0: md5 = 2656444cf89658741fd726fbd357248d OK 2024/04/12 03:34:10 INFO : only0: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only12: md5 = 3cac64edd58cd8c6a1b24cfbed304c2a OK 2024/04/12 03:34:10 INFO : only12: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only13: md5 = cd380c85a4fc277d1ae092852da0ec2d OK 2024/04/12 03:34:10 INFO : only13: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only14: md5 = aa189621c0d4f7f94bb107d6b3a92ae1 OK 2024/04/12 03:34:10 INFO : only14: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only15: md5 = a7ea36875bbe7186c3f9895f69e56e3b OK 2024/04/12 03:34:10 INFO : only15: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only16: md5 = fe3cc25eb8893a9969b743b12ca2c1f3 OK 2024/04/12 03:34:10 INFO : only16: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only17: md5 = b3d2b5d4af44acf6935ff6a17e24ceb6 OK 2024/04/12 03:34:10 INFO : only17: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only18: md5 = f7737e98d643504b68ffe153694d8c31 OK 2024/04/12 03:34:10 INFO : only18: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only19: md5 = d5d4c670cf4541788f1cb009fcc09fd4 OK 2024/04/12 03:34:10 INFO : only19: Copied (replaced existing) 2024/04/12 03:34:10 DEBUG : only2: md5 = 7742f44f52620500e9aafcd4cf1066e1 OK 2024/04/12 03:34:10 INFO : only2: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only3: md5 = a0eeef200a69185bf5154d03c6b1d2a8 OK 2024/04/12 03:34:11 INFO : only3: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only5: md5 = afe7dc6877684e43eb04d83e629cbcd1 OK 2024/04/12 03:34:11 INFO : only5: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only4: md5 = 0871224c78a73ac40ad96585b88c2cde OK 2024/04/12 03:34:11 INFO : only4: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only6: md5 = b6a19f19551e85f39a03ff18e7f5cc08 OK 2024/04/12 03:34:11 INFO : only6: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only7: md5 = 635f9687e7eebd983e0b5360f9a8a4a2 OK 2024/04/12 03:34:11 INFO : only7: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only9: md5 = 98212d4a6c2948dd922a4ad4d52f6dd0 OK 2024/04/12 03:34:11 INFO : only9: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : only8: md5 = 5ecd53f7644777ec5de43a4bb551a866 OK 2024/04/12 03:34:11 INFO : only8: Copied (replaced existing) 2024/04/12 03:34:11 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (21.66s) === RUN TestNothingToTransfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" 2024/04/12 03:34:21 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/12 03:34:21 DEBUG : sub dir: Making directory 2024/04/12 03:34:21 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7'", Local "Local file system at /tmp/rclone2782105322", Modify Window "1ns" --- PASS: TestNothingToTransfer (2.39s) PASS 2024/04/12 03:34:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-venigup7qigohal6toximac7': Purge remote 2024/04/12 03:34:23 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m2.524351587s (try 1/5)