"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5)
2022/01/19 06:50:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0"
2022/01/19 06:50:44 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/01/19 06:50:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0.bin"
2022/01/19 06:50:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0"
2022/01/19 06:50:45 DEBUG : Creating backend with remote "/tmp/rclone2954491513"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.03s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:45 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2022/01/19 06:50:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
--- PASS: TestCopyWithDryRun (0.39s)
=== RUN   TestCopy
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:46 DEBUG : sub dir/hello world: md5 = 7be31dd2515677e920ea8cff07d9d567 OK
2022/01/19 06:50:46 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopy (1.21s)
=== RUN   TestCopyMissingDirectory
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:47 DEBUG : Creating backend with remote "/non-existing"
2022/01/19 06:50:47 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2022/01/19 06:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.31s)
=== RUN   TestCopyNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:47 DEBUG : sub dir/hello world: md5 = 545b59df1410ed64656190a1a5f50175 OK
2022/01/19 06:50:47 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (0.95s)
=== RUN   TestCopyCheckFirst
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:48 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Running all checks before starting transfers
2022/01/19 06:50:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:48 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Checks finished, now starting transfers
2022/01/19 06:50:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:48 DEBUG : sub dir/hello world: md5 = 886efcecfa50581b9491850e36e4aa86 OK
2022/01/19 06:50:48 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (0.97s)
=== RUN   TestSyncNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:49 ERROR : Ignoring --no-traverse with sync
2022/01/19 06:50:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:49 DEBUG : sub dir/hello world: md5 = e80577efefeb9219bd4a79dc9328a7df OK
2022/01/19 06:50:49 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:50:49 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (0.97s)
=== RUN   TestCopyWithDepth
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:50 DEBUG : hello world2: md5 = 5d4d177d258647ed96740b23c1e9c8d5 OK
2022/01/19 06:50:50 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (1.07s)
=== RUN   TestCopyWithFilesFrom
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:51 DEBUG : hello world2: Excluded
2022/01/19 06:50:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:52 DEBUG : potato2: md5 = a79582067c5b313517b0d2715a72c14f OK
2022/01/19 06:50:52 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (1.14s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:52 DEBUG : potato2: md5 = 87b693ade95ca8805809551fbaeb6542 OK
2022/01/19 06:50:52 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (0.71s)
=== RUN   TestCopyEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:53 DEBUG : sub dir2: Making directory
2022/01/19 06:50:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:53 DEBUG : sub dir/hello world: md5 = c07136acf9cc2728212661d277c5dfc3 OK
2022/01/19 06:50:53 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:50:53 DEBUG : sub dir2: Making directory
2022/01/19 06:50:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': copied 1 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestCopyEmptyDirectories (1.22s)
=== RUN   TestMoveEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:54 DEBUG : sub dir2: Making directory
2022/01/19 06:50:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:54 DEBUG : sub dir/hello world: md5 = 142b978ddcd870ae8a5ed5b96d20f74f OK
2022/01/19 06:50:54 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:50:54 INFO  : sub dir/hello world: Deleted
2022/01/19 06:50:54 DEBUG : sub dir2: Making directory
2022/01/19 06:50:54 DEBUG : sub dir: Making directory
2022/01/19 06:50:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': copied 2 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestMoveEmptyDirectories (0.86s)
=== RUN   TestSyncEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:55 DEBUG : sub dir2: Making directory
2022/01/19 06:50:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:50:55 DEBUG : sub dir/hello world: md5 = 451b522d9faead5ae3aff9a7ca0839c9 OK
2022/01/19 06:50:55 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:50:55 DEBUG : sub dir2: Making directory
2022/01/19 06:50:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': copied 1 directories
2022/01/19 06:50:55 DEBUG : Waiting for deletions to finish
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestSyncEmptyDirectories (0.96s)
=== RUN   TestServerSideCopy
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:56 DEBUG : sub dir/hello world: md5 = 459185cb5421f8461f7456da27115cdf OK
2022/01/19 06:50:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-levebac3xarapec9xelosub5"
2022/01/19 06:50:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-levebac3xarapec9xelosub5.bin"
2022/01/19 06:50:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-levebac3xarapec9xelosub5"
    sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0' -> Encrypted drive 'TestCryptSwift:rclone-test-levebac3xarapec9xelosub5'
2022/01/19 06:50:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-levebac3xarapec9xelosub5': Waiting for checks to finish
2022/01/19 06:50:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-levebac3xarapec9xelosub5': Waiting for transfers to finish
2022/01/19 06:50:58 INFO  : sub dir/hello world: Copied (server-side copy)
2022/01/19 06:50:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-levebac3xarapec9xelosub5': Purge remote
2022/01/19 06:50:58 DEBUG : Waiting for deletions to finish
2022/01/19 06:50:58 INFO  : sub dir/hello world.bin: Deleted
--- PASS: TestServerSideCopy (2.73s)
=== RUN   TestCopyAfterDelete
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:50:59 DEBUG : sub dir/hello world: md5 = 6f0fd84e054c0da9c1eaab45809ee69d OK
2022/01/19 06:50:59 ERROR : : error listing: directory not found
2022/01/19 06:50:59 DEBUG : Local file system at /tmp/rclone2954491513: Making directory
2022/01/19 06:50:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:50:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
--- PASS: TestCopyAfterDelete (0.94s)
=== RUN   TestCopyRedownload
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:00 DEBUG : sub dir/hello world: md5 = f4afdbf49ab1ff80fe7ca366afd34337 OK
2022/01/19 06:51:00 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for checks to finish
2022/01/19 06:51:00 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for transfers to finish
2022/01/19 06:51:00 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyRedownload (0.96s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:01 DEBUG : check sum: md5 = 1e37e55e52f72fe16a6d8530db8e4dec OK
2022/01/19 06:51:01 INFO  : check sum: Copied (new)
2022/01/19 06:51:01 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:01 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2022/01/19 06:51:01 DEBUG : check sum: Size of src and dst objects identical
2022/01/19 06:51:01 DEBUG : check sum: Unchanged skipping
2022/01/19 06:51:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:01 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:01 INFO  : There was nothing to transfer
--- PASS: TestSyncBasedOnCheckSum (1.05s)
=== RUN   TestSyncSizeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:02 DEBUG : sizeonly: md5 = 75f090c2f70830d203f1691213e066fe OK
2022/01/19 06:51:02 INFO  : sizeonly: Copied (new)
2022/01/19 06:51:02 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:02 DEBUG : sizeonly: Sizes identical
2022/01/19 06:51:02 DEBUG : sizeonly: Unchanged skipping
2022/01/19 06:51:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:02 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:02 INFO  : There was nothing to transfer
--- PASS: TestSyncSizeOnly (1.10s)
=== RUN   TestSyncIgnoreSize
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:03 DEBUG : ignore-size: md5 = 2579c479ed8f05299eb2fdd7a6d2c3bb OK
2022/01/19 06:51:03 INFO  : ignore-size: Copied (new)
2022/01/19 06:51:03 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:03 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:03 DEBUG : ignore-size: Unchanged skipping
2022/01/19 06:51:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:03 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:03 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreSize (1.26s)
=== RUN   TestSyncIgnoreTimes
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:05 DEBUG : existing: md5 = 49d1084b83ac41e7325fe30bb5f5152c OK
2022/01/19 06:51:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:05 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:05 DEBUG : existing: Unchanged skipping
2022/01/19 06:51:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:05 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:05 INFO  : There was nothing to transfer
2022/01/19 06:51:05 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2022/01/19 06:51:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:05 DEBUG : existing: md5 = 2e3680e0fbc90df16b93e24b8dd05e88 OK
2022/01/19 06:51:05 INFO  : existing: Copied (replaced existing)
2022/01/19 06:51:05 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (2.15s)
=== RUN   TestSyncIgnoreExisting
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:06 DEBUG : existing: md5 = d166bca9aa1bc6b5804dfd3ae9e18ab3 OK
2022/01/19 06:51:06 INFO  : existing: Copied (new)
2022/01/19 06:51:06 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:07 DEBUG : existing: Destination exists, skipping
2022/01/19 06:51:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:07 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:07 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (1.16s)
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:08 DEBUG : b/potato: md5 = 963c3ebc33e243f3feb6478a0308ac0b OK
2022/01/19 06:51:08 DEBUG : c/non empty space: md5 = 87aa24481290318eb9d13811348c0084 OK
2022/01/19 06:51:08 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/01/19 06:51:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:08 DEBUG : c/non empty space: Unchanged skipping
2022/01/19 06:51:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:09 DEBUG : a/potato2: md5 = 0ba3ea3ecebb5fe56ad7caf9b9b774b9 OK
2022/01/19 06:51:09 INFO  : a/potato2: Copied (new)
2022/01/19 06:51:09 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:09 INFO  : b/potato: Deleted
--- PASS: TestSyncIgnoreErrors (2.68s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:10 DEBUG : empty space: md5 = 80bb9857b55f53faf25f05c8b682f93f OK
2022/01/19 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:11 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
2022/01/19 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:11 NOTICE: empty space: Skipped copy as --dry-run is set (size 1)
2022/01/19 06:51:11 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:11 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
2022/01/19 06:51:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:11 DEBUG : empty space: md5 = aaaf3549d2a3b47c5f748fea015a804b OK
2022/01/19 06:51:11 INFO  : empty space: Copied (replaced existing)
2022/01/19 06:51:11 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingModtimeOnly (1.87s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:577: Can't check this if no hashes supported
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.26s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:12 DEBUG : foo: md5 = 237a78d06c5cd70bf2001891363255da OK
2022/01/19 06:51:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:13 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
2022/01/19 06:51:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:13 DEBUG : foo: md5 = fbb16c6dc924b2e66ae971fc5df7f8e4 OK
2022/01/19 06:51:13 INFO  : foo: Copied (replaced existing)
2022/01/19 06:51:13 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncDoesntUpdateModtime (1.69s)
=== RUN   TestSyncAfterAddingAFile
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:18 DEBUG : empty space: md5 = 147e2457c515c035613cd93ccc5960e5 OK
2022/01/19 06:51:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:18 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:18 DEBUG : potato: md5 = faee13d7794894f65dfbc83e75c58048 OK
2022/01/19 06:51:18 INFO  : potato: Copied (new)
2022/01/19 06:51:18 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterAddingAFile (5.77s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:20 DEBUG : potato: md5 = 46c74dc373bc40128d9a6eb05489be9e OK
2022/01/19 06:51:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:20 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2022/01/19 06:51:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:20 DEBUG : potato: md5 = 82cbbc1fd08a363828ceae5cd337f8e7 OK
2022/01/19 06:51:20 INFO  : potato: Copied (replaced existing)
2022/01/19 06:51:20 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingFilesSizeOnly (1.31s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:21 DEBUG : potato: md5 = 9bce60b11bad4ce789b4386fc63bf490 OK
2022/01/19 06:51:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:21 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
2022/01/19 06:51:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:22 DEBUG : potato: md5 = 95f97b475be6a932abe8af09f61052b5 OK
2022/01/19 06:51:22 INFO  : potato: Copied (replaced existing)
2022/01/19 06:51:22 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (1.41s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:23 DEBUG : potato: md5 = 29b2d7db71b936bae781d3a9ddb38bce OK
2022/01/19 06:51:23 DEBUG : empty space: md5 = 7cd3d2623bd1ca24a880e3367e6aa303 OK
2022/01/19 06:51:23 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2022/01/19 06:51:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:23 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:23 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:23 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.85s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:25 DEBUG : potato: md5 = 36a579f3d3e64fa13ee760f8b0ef6bd8 OK
2022/01/19 06:51:25 DEBUG : empty space: md5 = a868b3b2cdcbd67419c21dcb2024e8f6 OK
2022/01/19 06:51:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:25 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:25 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:26 DEBUG : potato2: md5 = 58ec1b5bb7c004042307d868ce8bcd9f OK
2022/01/19 06:51:26 INFO  : potato2: Copied (new)
2022/01/19 06:51:26 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:26 INFO  : potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (5.51s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:30 DEBUG : b/potato: md5 = 6fb92b00aa4763c6de908d0eb94b1088 OK
2022/01/19 06:51:31 DEBUG : c/non empty space: md5 = c47e3b084954d22f2bb395152a04b6c9 OK
2022/01/19 06:51:31 DEBUG : d: Making directory
2022/01/19 06:51:31 DEBUG : d/e: Making directory
    fstest.go:251: Filtering empty directory "d"
    fstest.go:251: Filtering empty directory "d/e"
2022/01/19 06:51:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:31 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:31 DEBUG : c/non empty space: Unchanged skipping
2022/01/19 06:51:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:31 DEBUG : a/potato2: md5 = 2a369d05ab692252cf9d03db0f29f34f OK
2022/01/19 06:51:31 INFO  : a/potato2: Copied (new)
2022/01/19 06:51:31 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:32 INFO  : b/potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.25s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:33 DEBUG : b/potato: md5 = 3856e0d08e336dfb38a1c122e4514b18 OK
2022/01/19 06:51:33 DEBUG : c/non empty space: md5 = ca03bf8b616d00374ee590c08ef65272 OK
2022/01/19 06:51:33 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/01/19 06:51:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:34 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:34 DEBUG : c/non empty space: Unchanged skipping
2022/01/19 06:51:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:34 DEBUG : a/potato2: md5 = 5828ac49ca2252fe979c9a8a1748f145 OK
2022/01/19 06:51:34 INFO  : a/potato2: Copied (new)
2022/01/19 06:51:34 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': not deleting files as there were IO errors
2022/01/19 06:51:34 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': not deleting directories as there were IO errors
    fstest.go:251: Filtering empty directory "d"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (4.06s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:37 DEBUG : potato: md5 = df19cebf959ab169b4c20d1337e90a76 OK
2022/01/19 06:51:37 DEBUG : empty space: md5 = d54cfd1bd2970080be0a316d5ef7bed8 OK
2022/01/19 06:51:38 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:38 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:38 INFO  : potato: Deleted
2022/01/19 06:51:38 DEBUG : potato2: md5 = 67a0669bb4cc94a6f842a35653aad968 OK
2022/01/19 06:51:38 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteDuring (1.99s)
=== RUN   TestSyncDeleteBefore
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:39 DEBUG : potato: md5 = d40ef2d0eda2489ed4840d5ed7454084 OK
2022/01/19 06:51:40 DEBUG : empty space: md5 = 408b2b3aa165ff4da498e0f4ec0aa29a OK
2022/01/19 06:51:40 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:40 INFO  : potato: Deleted
2022/01/19 06:51:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:40 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:40 DEBUG : potato2: md5 = ab0bdf036713cb18f2bb50bc9b3ff8eb OK
2022/01/19 06:51:40 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (2.57s)
=== RUN   TestCopyDeleteBefore
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:42 DEBUG : potato: md5 = af97f5ffe78017c2878338ccac74c950 OK
2022/01/19 06:51:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:42 DEBUG : potato2: md5 = 8d985d006ea10971c4bcf68e7e36285d OK
2022/01/19 06:51:42 INFO  : potato2: Copied (new)
--- PASS: TestCopyDeleteBefore (1.87s)
=== RUN   TestSyncWithExclude
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:50 DEBUG : potato2: md5 = da5250e4815a70517cc7ad95062bc551 OK
2022/01/19 06:51:50 DEBUG : empty space: md5 = 8f69942147c93bbe50ff5105a72f05a8 OK
2022/01/19 06:51:50 DEBUG : enormous: Excluded
2022/01/19 06:51:50 DEBUG : potato2: Excluded
2022/01/19 06:51:51 DEBUG : potato2: Excluded
2022/01/19 06:51:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:51 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:51 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:51 INFO  : There was nothing to transfer
2022/01/19 06:51:51 DEBUG : enormous: Excluded
2022/01/19 06:51:51 DEBUG : potato2: Excluded
2022/01/19 06:51:51 DEBUG : potato2: Excluded
2022/01/19 06:51:51 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for checks to finish
2022/01/19 06:51:51 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:51 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:51 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for transfers to finish
2022/01/19 06:51:51 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:51 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExclude (8.53s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:52 DEBUG : potato2: md5 = b7d1ce9ea6a00423210c4a579378550b OK
2022/01/19 06:51:52 DEBUG : empty space: md5 = 6c40339a2938db21dcebde0113304807 OK
2022/01/19 06:51:53 DEBUG : enormous: md5 = edba1ca1976cfe91ff99b775b6bcfebd OK
2022/01/19 06:51:53 DEBUG : enormous: Excluded
2022/01/19 06:51:53 DEBUG : potato2: Excluded
2022/01/19 06:51:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:53 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:53 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:53 INFO  : potato2: Deleted
2022/01/19 06:51:53 INFO  : enormous: Deleted
2022/01/19 06:51:53 INFO  : There was nothing to transfer
2022/01/19 06:51:53 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for checks to finish
2022/01/19 06:51:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:51:53 DEBUG : empty space: Unchanged skipping
2022/01/19 06:51:53 DEBUG : Local file system at /tmp/rclone2954491513: Waiting for transfers to finish
2022/01/19 06:51:53 DEBUG : Waiting for deletions to finish
2022/01/19 06:51:53 INFO  : enormous: Deleted
2022/01/19 06:51:53 INFO  : potato2: Deleted
2022/01/19 06:51:53 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExcludeAndDeleteExcluded (2.03s)
=== RUN   TestSyncWithUpdateOlder
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:51:55 DEBUG : one: md5 = ab30b4acd1f26498f871b32f906a051e OK
2022/01/19 06:51:55 DEBUG : two: md5 = b7d407c33cfe955a08ca8f6f870546a6 OK
2022/01/19 06:51:55 DEBUG : three: md5 = 4b4268e85a40e07af23c9168c1a3eec0 OK
2022/01/19 06:51:56 DEBUG : four: md5 = f34b01b0df8b90cee7dd8f622e8afe65 OK
2022/01/19 06:51:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:56 DEBUG : three: Sizes identical
2022/01/19 06:51:56 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2022/01/19 06:51:56 DEBUG : four: Sizes differ (src 4 vs dst 8)
2022/01/19 06:51:56 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2022/01/19 06:51:56 DEBUG : one: Destination is newer than source, skipping
2022/01/19 06:51:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:57 DEBUG : five: md5 = 2f61119312c6d3950f1d1e8a8acaf136 OK
2022/01/19 06:51:57 INFO  : five: Copied (new)
2022/01/19 06:51:57 DEBUG : four: md5 = b0e55658bf9ff9b824768bdc401f68f1 OK
2022/01/19 06:51:57 INFO  : four: Copied (replaced existing)
2022/01/19 06:51:57 DEBUG : two: md5 = a358af4c1b8e4234dc2dd4f2c1d58be9 OK
2022/01/19 06:51:57 INFO  : two: Copied (replaced existing)
2022/01/19 06:51:57 DEBUG : Waiting for deletions to finish
    sync_test.go:992: Skip test with --checksum as no hashes supported
--- PASS: TestSyncWithUpdateOlder (5.17s)
=== RUN   TestSyncWithMaxDuration
    sync_test.go:1009: Skipping test on non local remote
--- SKIP: TestSyncWithMaxDuration (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1058: Can track renames: false
2022/01/19 06:51:59 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Ignoring --track-renames as the source and destination do not have a common hash
2022/01/19 06:51:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:51:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:51:59 DEBUG : potato: md5 = c1a708a60ed28c3f1534d3900a31b6cf OK
2022/01/19 06:51:59 INFO  : potato: Copied (new)
2022/01/19 06:51:59 DEBUG : yam: md5 = 5944957d311f01d0e1b547e918180271 OK
2022/01/19 06:51:59 INFO  : yam: Copied (new)
2022/01/19 06:51:59 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:00 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Ignoring --track-renames as the source and destination do not have a common hash
2022/01/19 06:52:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:00 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:00 DEBUG : potato: Unchanged skipping
2022/01/19 06:52:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:01 DEBUG : yaml: md5 = b49426e438fbf779fa6d9a1a9fee97a8 OK
2022/01/19 06:52:01 INFO  : yaml: Copied (new)
2022/01/19 06:52:01 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:01 INFO  : yam: Deleted
--- PASS: TestSyncWithTrackRenames (2.78s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1127: Can track renames: true
2022/01/19 06:52:02 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Making map for --track-renames
2022/01/19 06:52:02 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Finished making map for --track-renames
2022/01/19 06:52:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for renames to finish
2022/01/19 06:52:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:02 DEBUG : yam: md5 = 15c5ab86a05b5ea6236ed12df8b2f72a OK
2022/01/19 06:52:02 INFO  : yam: Copied (new)
2022/01/19 06:52:02 DEBUG : potato: md5 = 59e62ee4a975bfb2331c1d86f761fff9 OK
2022/01/19 06:52:02 INFO  : potato: Copied (new)
2022/01/19 06:52:02 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:02 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Making map for --track-renames
2022/01/19 06:52:02 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Finished making map for --track-renames
2022/01/19 06:52:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:02 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:02 DEBUG : potato: Unchanged skipping
2022/01/19 06:52:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for renames to finish
2022/01/19 06:52:03 INFO  : yam: Copied (server-side copy) to: yaml
2022/01/19 06:52:03 INFO  : yam: Deleted
2022/01/19 06:52:03 INFO  : yaml: Renamed from "yam"
2022/01/19 06:52:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:03 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyModtime (1.82s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1163: Can track renames: true
2022/01/19 06:52:04 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Making map for --track-renames
2022/01/19 06:52:04 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Finished making map for --track-renames
2022/01/19 06:52:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for renames to finish
2022/01/19 06:52:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:04 DEBUG : potato: md5 = 8fd5bd02c6d36b2d1729cf2d930c4786 OK
2022/01/19 06:52:04 INFO  : potato: Copied (new)
2022/01/19 06:52:04 DEBUG : sub/yam: md5 = 6962bf3b8ef2e67b8f2669191a2dfff5 OK
2022/01/19 06:52:04 INFO  : sub/yam: Copied (new)
2022/01/19 06:52:04 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:04 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:04 DEBUG : potato: Unchanged skipping
2022/01/19 06:52:04 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Making map for --track-renames
2022/01/19 06:52:04 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Finished making map for --track-renames
2022/01/19 06:52:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for renames to finish
2022/01/19 06:52:05 INFO  : sub/yam: Copied (server-side copy) to: yam
2022/01/19 06:52:05 INFO  : sub/yam: Deleted
2022/01/19 06:52:05 INFO  : yam: Renamed from "sub/yam"
2022/01/19 06:52:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:05 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.11s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:06 DEBUG : sub dir/hello world: md5 = 963cacd890e3b962b6dd24499638f65f OK
2022/01/19 06:52:06 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:52:06 INFO  : sub dir/hello world: Deleted
2022/01/19 06:52:06 DEBUG : nested/sub dir/file: md5 = 6df41603c6ea97bc4ee0dcd0039cb9bc OK
2022/01/19 06:52:06 INFO  : nested/sub dir/file: Copied (new)
2022/01/19 06:52:06 INFO  : nested/sub dir/file: Deleted
2022/01/19 06:52:06 INFO  : sub dir: Removing directory
2022/01/19 06:52:06 INFO  : nested/sub dir: Removing directory
2022/01/19 06:52:06 INFO  : nested: Removing directory
2022/01/19 06:52:06 DEBUG : Local file system at /tmp/rclone2954491513: deleted 3 directories
--- PASS: TestMoveWithDeleteEmptySrcDirs (1.89s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:08 DEBUG : sub dir/hello world: md5 = 263bc3f33a9dd9856520a22047ce83cd OK
2022/01/19 06:52:08 INFO  : sub dir/hello world: Copied (new)
2022/01/19 06:52:08 INFO  : sub dir/hello world: Deleted
2022/01/19 06:52:08 DEBUG : nested/sub dir/file: md5 = 5215e616b044739c9e2859c5e786c70c OK
2022/01/19 06:52:08 INFO  : nested/sub dir/file: Copied (new)
2022/01/19 06:52:08 INFO  : nested/sub dir/file: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.17s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:09 DEBUG : existing: md5 = 0ae7d5b6e1e3638918536e959ea0a3ac OK
2022/01/19 06:52:09 INFO  : existing: Copied (new)
2022/01/19 06:52:09 INFO  : existing: Deleted
2022/01/19 06:52:09 DEBUG : existing-b: md5 = 5be6b4af17c60be0dd9a5cd375794325 OK
2022/01/19 06:52:09 INFO  : existing-b: Copied (new)
2022/01/19 06:52:09 INFO  : existing-b: Deleted
2022/01/19 06:52:10 DEBUG : existing: Destination exists, skipping
2022/01/19 06:52:10 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2022/01/19 06:52:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:52:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:52:10 INFO  : There was nothing to transfer
--- PASS: TestMoveWithIgnoreExisting (1.79s)
=== RUN   TestServerSideMove
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-yizovuz3quditey7qetalen5"
2022/01/19 06:52:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yizovuz3quditey7qetalen5.bin"
2022/01/19 06:52:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-yizovuz3quditey7qetalen5"
2022/01/19 06:52:12 DEBUG : potato2: md5 = 7507f72ba2a21c566c5c460a30f70914 OK
2022/01/19 06:52:12 DEBUG : empty space: md5 = 115029da2b9be4306b505adf781dae9d OK
2022/01/19 06:52:12 DEBUG : potato3: md5 = 33b09676e58ec12fd52586816677125c OK
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0' -> Encrypted drive 'TestCryptSwift:rclone-test-yizovuz3quditey7qetalen5'
2022/01/19 06:52:13 DEBUG : empty space: md5 = 3a5b01c120cadb2f345bb04c5741788c OK
2022/01/19 06:52:14 DEBUG : potato3: md5 = fd534c073284c69b2b78a927f98d877c OK
2022/01/19 06:52:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yizovuz3quditey7qetalen5': Waiting for checks to finish
2022/01/19 06:52:14 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/19 06:52:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:14 DEBUG : empty space: Unchanged skipping
2022/01/19 06:52:14 INFO  : empty space: Deleted
2022/01/19 06:52:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yizovuz3quditey7qetalen5': Waiting for transfers to finish
2022/01/19 06:52:14 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:14 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:14 INFO  : potato2: Deleted
2022/01/19 06:52:14 INFO  : potato3: Deleted
2022/01/19 06:52:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dowihod1dojodin4qakogev8"
2022/01/19 06:52:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dowihod1dojodin4qakogev8.bin"
2022/01/19 06:52:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dowihod1dojodin4qakogev8"
2022/01/19 06:52:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowihod1dojodin4qakogev8': Waiting for checks to finish
2022/01/19 06:52:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowihod1dojodin4qakogev8': Waiting for transfers to finish
2022/01/19 06:52:16 INFO  : empty space: Copied (server-side copy)
2022/01/19 06:52:16 INFO  : empty space: Deleted
2022/01/19 06:52:16 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:17 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:17 INFO  : potato2: Deleted
2022/01/19 06:52:17 INFO  : potato3: Deleted
2022/01/19 06:52:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dowihod1dojodin4qakogev8': Purge remote
2022/01/19 06:52:17 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:18 INFO  : potato3.bin: Deleted
2022/01/19 06:52:18 INFO  : potato2.bin: Deleted
2022/01/19 06:52:18 INFO  : empty space.bin: Deleted
2022/01/19 06:52:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-yizovuz3quditey7qetalen5': Purge remote
2022/01/19 06:52:18 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMove (7.49s)
=== RUN   TestServerSideMoveWithFilter
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tepowuh8nerozec2bedafoy8"
2022/01/19 06:52:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tepowuh8nerozec2bedafoy8.bin"
2022/01/19 06:52:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tepowuh8nerozec2bedafoy8"
2022/01/19 06:52:19 DEBUG : potato2: md5 = 2d2917e37d789a63d713043d3ed154b3 OK
2022/01/19 06:52:25 DEBUG : empty space: md5 = 32e17dc000b10b70ae31258592f3b6db OK
2022/01/19 06:52:26 DEBUG : potato3: md5 = 96bbc07b10740f1327de21db776b90d5 OK
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0' -> Encrypted drive 'TestCryptSwift:rclone-test-tepowuh8nerozec2bedafoy8'
2022/01/19 06:52:26 DEBUG : empty space: md5 = e4562ecce8e2b0f71d7197583af55fc2 OK
2022/01/19 06:52:27 DEBUG : potato3: md5 = 399b6344233652d6b23391335a6943e9 OK
2022/01/19 06:52:27 DEBUG : empty space: Excluded
2022/01/19 06:52:27 DEBUG : empty space: Excluded
2022/01/19 06:52:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/19 06:52:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tepowuh8nerozec2bedafoy8': Waiting for checks to finish
2022/01/19 06:52:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tepowuh8nerozec2bedafoy8': Waiting for transfers to finish
2022/01/19 06:52:27 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:27 INFO  : potato3: Deleted
2022/01/19 06:52:27 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:27 INFO  : potato2: Deleted
2022/01/19 06:52:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nafatan7beqikod1huwugaw4"
2022/01/19 06:52:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nafatan7beqikod1huwugaw4.bin"
2022/01/19 06:52:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nafatan7beqikod1huwugaw4"
2022/01/19 06:52:29 DEBUG : empty space: Excluded
2022/01/19 06:52:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nafatan7beqikod1huwugaw4': Waiting for checks to finish
2022/01/19 06:52:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nafatan7beqikod1huwugaw4': Waiting for transfers to finish
2022/01/19 06:52:30 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:30 INFO  : potato2: Deleted
2022/01/19 06:52:30 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:30 INFO  : potato3: Deleted
2022/01/19 06:52:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nafatan7beqikod1huwugaw4': Purge remote
2022/01/19 06:52:30 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:30 INFO  : potato3.bin: Deleted
2022/01/19 06:52:30 INFO  : potato2.bin: Deleted
2022/01/19 06:52:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tepowuh8nerozec2bedafoy8': Purge remote
2022/01/19 06:52:30 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:31 INFO  : empty space.bin: Deleted
--- PASS: TestServerSideMoveWithFilter (14.78s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xikidaj0mefunut4gasohez7"
2022/01/19 06:52:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xikidaj0mefunut4gasohez7.bin"
2022/01/19 06:52:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xikidaj0mefunut4gasohez7"
2022/01/19 06:52:34 DEBUG : potato2: md5 = e8cae6437e6cc5797ffd3a4c997c86d9 OK
2022/01/19 06:52:34 DEBUG : empty space: md5 = d79a9b005bd85729f3d2db90712863ae OK
2022/01/19 06:52:35 DEBUG : potato3: md5 = 119cc5c1a40be89093c83af193f60ee5 OK
2022/01/19 06:52:35 DEBUG : tomatoDir: Making directory
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0' -> Encrypted drive 'TestCryptSwift:rclone-test-xikidaj0mefunut4gasohez7'
2022/01/19 06:52:36 DEBUG : empty space: md5 = cffc6951da17d4823160ab7a839fa89f OK
2022/01/19 06:52:36 DEBUG : potato3: md5 = ea22c5b1fdb3719641e968f6e165cf8c OK
2022/01/19 06:52:37 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/19 06:52:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xikidaj0mefunut4gasohez7': Waiting for checks to finish
2022/01/19 06:52:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:37 DEBUG : empty space: Unchanged skipping
2022/01/19 06:52:37 INFO  : empty space: Deleted
2022/01/19 06:52:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xikidaj0mefunut4gasohez7': Waiting for transfers to finish
2022/01/19 06:52:37 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:37 INFO  : potato2: Deleted
2022/01/19 06:52:37 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:38 INFO  : potato3: Deleted
2022/01/19 06:52:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qubutic8yucihom9tofeyut5"
2022/01/19 06:52:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qubutic8yucihom9tofeyut5.bin"
2022/01/19 06:52:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qubutic8yucihom9tofeyut5"
2022/01/19 06:52:39 DEBUG : tomatoDir: Making directory
2022/01/19 06:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qubutic8yucihom9tofeyut5': Waiting for checks to finish
2022/01/19 06:52:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qubutic8yucihom9tofeyut5': Waiting for transfers to finish
2022/01/19 06:52:40 INFO  : empty space: Copied (server-side copy)
2022/01/19 06:52:40 INFO  : potato2: Copied (server-side copy)
2022/01/19 06:52:40 INFO  : potato2: Deleted
2022/01/19 06:52:40 INFO  : empty space: Deleted
2022/01/19 06:52:41 INFO  : potato3: Copied (server-side copy)
2022/01/19 06:52:41 INFO  : potato3: Deleted
2022/01/19 06:52:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qubutic8yucihom9tofeyut5': Purge remote
2022/01/19 06:52:41 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:42 INFO  : potato2.bin: Deleted
2022/01/19 06:52:42 INFO  : potato3.bin: Deleted
2022/01/19 06:52:42 INFO  : empty space.bin: Deleted
2022/01/19 06:52:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xikidaj0mefunut4gasohez7': Purge remote
2022/01/19 06:52:42 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.26s)
=== RUN   TestServerSideMoveOverlap
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/rclone-move-test"
2022/01/19 06:52:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/rclone-move-test.bin"
2022/01/19 06:52:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/rclone-move-test"
2022/01/19 06:52:43 DEBUG : potato2: md5 = 832605f4259a132b7fe4a6dac775686a OK
--- PASS: TestServerSideMoveOverlap (1.83s)
=== RUN   TestSyncOverlap
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/rclone-sync-test"
2022/01/19 06:52:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/rclone-sync-test.bin"
2022/01/19 06:52:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/rclone-sync-test"
--- PASS: TestSyncOverlap (1.24s)
=== RUN   TestSyncCompareDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:52:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:52:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:52:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/CompareDest"
2022/01/19 06:52:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/CompareDest.bin"
2022/01/19 06:52:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/CompareDest"
2022/01/19 06:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:48 DEBUG : one: md5 = ffbcd6d46bfae231dc45a6c43532b688 OK
2022/01/19 06:52:48 INFO  : one: Copied (new)
2022/01/19 06:52:48 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:48 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/19 06:52:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:49 DEBUG : one: md5 = d2654068befa6ba71e15e2176332a144 OK
2022/01/19 06:52:49 INFO  : one: Copied (replaced existing)
2022/01/19 06:52:49 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:49 DEBUG : dst/one: md5 = c4c9539238a4793dc4293989d770d66e OK
2022/01/19 06:52:50 DEBUG : CompareDest/one: md5 = 9db1fcb5dc3cb776763c01be026185a2 OK
2022/01/19 06:52:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:50 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:50 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/19 06:52:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:50 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:50 INFO  : There was nothing to transfer
2022/01/19 06:52:51 DEBUG : CompareDest/two: md5 = 9b2d70f216b5d82b48a5b8f75b17a12d OK
2022/01/19 06:52:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:51 DEBUG : two: Destination found in --compare-dest, skipping
2022/01/19 06:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:51 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:51 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/19 06:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:51 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:51 INFO  : There was nothing to transfer
2022/01/19 06:52:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:51 DEBUG : two: Destination found in --compare-dest, skipping
2022/01/19 06:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:51 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:51 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/19 06:52:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:51 DEBUG : Waiting for deletions to finish
2022/01/19 06:52:51 INFO  : There was nothing to transfer
    sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test
2022/01/19 06:52:52 DEBUG : two: Sizes differ (src 5 vs dst 3)
2022/01/19 06:52:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:52:52 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:52 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/19 06:52:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:52:52 DEBUG : two: md5 = 3434e2fd8bf7cbe1d554d1f790b825d8 OK
2022/01/19 06:52:52 INFO  : two: Copied (new)
2022/01/19 06:52:52 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (8.62s)
=== RUN   TestSyncMultipleCompareDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:52:54 DEBUG : pre-dest1/1: md5 = 2f107cbfd1a91240829c1998c79c32a4 OK
2022/01/19 06:52:55 DEBUG : pre-dest2/2: md5 = 9b1d247c28c95754556fe423b1c1e0ae OK
2022/01/19 06:52:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dest"
2022/01/19 06:52:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dest.bin"
2022/01/19 06:52:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dest"
2022/01/19 06:52:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/pre-dest1"
2022/01/19 06:52:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/pre-dest1.bin"
2022/01/19 06:52:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/pre-dest1"
2022/01/19 06:52:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/pre-dest2"
2022/01/19 06:52:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/pre-dest2.bin"
2022/01/19 06:52:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/pre-dest2"
2022/01/19 06:52:58 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:58 DEBUG : 1: Destination found in --compare-dest, skipping
2022/01/19 06:52:58 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:52:58 DEBUG : 2: Destination found in --compare-dest, skipping
2022/01/19 06:52:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dest': Waiting for checks to finish
2022/01/19 06:52:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dest': Waiting for transfers to finish
2022/01/19 06:52:59 DEBUG : 3: md5 = 4b5dc64e18b05cf2607d4d95d6c978b5 OK
2022/01/19 06:52:59 INFO  : 3: Copied (new)
2022/01/19 06:52:59 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (6.41s)
=== RUN   TestSyncCopyDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/CopyDest"
2022/01/19 06:53:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/CopyDest.bin"
2022/01/19 06:53:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/CopyDest"
2022/01/19 06:53:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:03 DEBUG : one: md5 = 321a264f312dbaad23be0ef7d77c6d3e OK
2022/01/19 06:53:03 INFO  : one: Copied (new)
2022/01/19 06:53:03 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:03 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/19 06:53:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:04 DEBUG : one: md5 = b27a4c787590a30fc0faec4d1729323d OK
2022/01/19 06:53:04 INFO  : one: Copied (replaced existing)
2022/01/19 06:53:04 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:04 DEBUG : dst/one: md5 = 3e5581e883111028918fb023c7d40280 OK
2022/01/19 06:53:05 DEBUG : CopyDest/one: md5 = 25e5893bfae341937a1ccab9ea1375e2 OK
2022/01/19 06:53:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/BackupDir"
2022/01/19 06:53:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/BackupDir.bin"
2022/01/19 06:53:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/BackupDir"
2022/01/19 06:53:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:06 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:06 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/19 06:53:06 INFO  : one: Copied (server-side copy)
2022/01/19 06:53:07 INFO  : one: Deleted
2022/01/19 06:53:07 INFO  : one: Copied (server-side copy)
2022/01/19 06:53:07 DEBUG : one: Destination found in --copy-dest, using server-side copy
2022/01/19 06:53:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:07 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:08 DEBUG : CopyDest/two: md5 = 50129cb144c1c85fda100035debf14c0 OK
2022/01/19 06:53:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:08 INFO  : two: Copied (server-side copy)
2022/01/19 06:53:08 DEBUG : two: Destination found in --copy-dest, using server-side copy
2022/01/19 06:53:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:08 DEBUG : one: Unchanged skipping
2022/01/19 06:53:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:08 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:09 DEBUG : two: Unchanged skipping
2022/01/19 06:53:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:09 DEBUG : one: Unchanged skipping
2022/01/19 06:53:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:09 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:09 INFO  : There was nothing to transfer
2022/01/19 06:53:10 DEBUG : CopyDest/three: md5 = 6be4c2b56d268d32c9ef42779a5faf9d OK
2022/01/19 06:53:10 DEBUG : three: Sizes differ (src 7 vs dst 5)
2022/01/19 06:53:10 DEBUG : three: Destination not found in --copy-dest
2022/01/19 06:53:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:11 DEBUG : two: Unchanged skipping
2022/01/19 06:53:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:11 DEBUG : one: Unchanged skipping
2022/01/19 06:53:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:11 DEBUG : three: md5 = f5a64cd253628ab4e8ba1da4f4d2f877 OK
2022/01/19 06:53:11 INFO  : three: Copied (new)
2022/01/19 06:53:11 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (13.59s)
=== RUN   TestSyncBackupDir
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:14 DEBUG : dst/one: md5 = 42769037d5038dc2a646efe6f78c5b20 OK
2022/01/19 06:53:14 DEBUG : dst/two: md5 = 2c4ea71860f7f3eef886a2203cf1cafd OK
2022/01/19 06:53:15 DEBUG : dst/three.txt: md5 = 5d97abb90fe167641a56abcf34b71bec OK
2022/01/19 06:53:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup.bin"
2022/01/19 06:53:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:17 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:17 DEBUG : two: Unchanged skipping
2022/01/19 06:53:17 INFO  : one: Copied (server-side copy)
2022/01/19 06:53:17 INFO  : one: Deleted
2022/01/19 06:53:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:18 DEBUG : one: md5 = e1074e604611faec6f8cef5d7959c8a6 OK
2022/01/19 06:53:18 INFO  : one: Copied (new)
2022/01/19 06:53:18 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:18 INFO  : three.txt: Copied (server-side copy)
2022/01/19 06:53:18 INFO  : three.txt: Deleted
2022/01/19 06:53:18 INFO  : three.txt: Moved into backup dir
2022/01/19 06:53:19 DEBUG : dst/three.txt: md5 = 458a41c9815a486c1383c543e9ba5222 OK
2022/01/19 06:53:20 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:53:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:20 DEBUG : two: Unchanged skipping
2022/01/19 06:53:20 INFO  : one: Copied (server-side copy)
2022/01/19 06:53:20 INFO  : one: Deleted
2022/01/19 06:53:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:21 DEBUG : one: md5 = ad455477043d32deeed8988c5adb3197 OK
2022/01/19 06:53:21 INFO  : one: Copied (new)
2022/01/19 06:53:21 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:21 INFO  : three.txt: Copied (server-side copy)
2022/01/19 06:53:21 INFO  : three.txt: Deleted
2022/01/19 06:53:21 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (9.10s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:23 DEBUG : dst/one: md5 = 07959ead5caf46bb2e1960bb886b5490 OK
2022/01/19 06:53:23 DEBUG : dst/two: md5 = 9589ddcfc7ca055241057b6754d7fcd7 OK
2022/01/19 06:53:24 DEBUG : dst/three.txt: md5 = c3a7863feb2656540ec6c51b338b1d4e OK
2022/01/19 06:53:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup.bin"
2022/01/19 06:53:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:26 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:53:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:26 DEBUG : two: Unchanged skipping
2022/01/19 06:53:27 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:27 INFO  : one: Deleted
2022/01/19 06:53:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:28 DEBUG : one: md5 = 3a0893d641cc807d25d879dbedd2b1d1 OK
2022/01/19 06:53:28 INFO  : one: Copied (new)
2022/01/19 06:53:28 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:28 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:28 INFO  : three.txt: Deleted
2022/01/19 06:53:28 INFO  : three.txt: Moved into backup dir
2022/01/19 06:53:29 DEBUG : dst/three.txt: md5 = 8514db1fce920e85dea0f5c599b66592 OK
2022/01/19 06:53:30 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:53:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:30 DEBUG : two: Unchanged skipping
2022/01/19 06:53:31 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:31 INFO  : one: Deleted
2022/01/19 06:53:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:31 DEBUG : one: md5 = b61103998dc478a7239c08e16ab20790 OK
2022/01/19 06:53:31 INFO  : one: Copied (new)
2022/01/19 06:53:31 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:32 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:32 INFO  : three.txt: Deleted
2022/01/19 06:53:32 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (10.62s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:34 DEBUG : dst/one: md5 = d065694a858e056acd9969ec926e4de6 OK
2022/01/19 06:53:34 DEBUG : dst/two: md5 = 8a5acec7588619cf8e9e9194f5d3fc66 OK
2022/01/19 06:53:34 DEBUG : dst/three.txt: md5 = 5f6b796bb6d8b8fe0680df36c73dfabe OK
2022/01/19 06:53:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup.bin"
2022/01/19 06:53:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/backup"
2022/01/19 06:53:37 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:37 DEBUG : two: Unchanged skipping
2022/01/19 06:53:37 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/19 06:53:37 INFO  : one: Deleted
2022/01/19 06:53:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:38 DEBUG : one: md5 = c8dab9dcbc35bbcfe231ef6c540f69f5 OK
2022/01/19 06:53:38 INFO  : one: Copied (new)
2022/01/19 06:53:38 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:38 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/19 06:53:38 INFO  : three.txt: Deleted
2022/01/19 06:53:38 INFO  : three.txt: Moved into backup dir
2022/01/19 06:53:39 DEBUG : dst/three.txt: md5 = 854923fa4be3d5aa7cbbba1a20387c8f OK
2022/01/19 06:53:39 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:53:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:39 DEBUG : two: Unchanged skipping
2022/01/19 06:53:40 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/19 06:53:40 INFO  : one: Deleted
2022/01/19 06:53:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:40 DEBUG : one: md5 = 61a82a81a0ba27435c41779bc584f07a OK
2022/01/19 06:53:40 INFO  : one: Copied (new)
2022/01/19 06:53:40 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:41 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/19 06:53:41 INFO  : three.txt: Deleted
2022/01/19 06:53:41 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (9.32s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:43 DEBUG : dst/one: md5 = 8f790d65a376c12fded7e543c9b629f5 OK
2022/01/19 06:53:44 DEBUG : dst/two: md5 = fe5bfeaab94f086e1837ab95602de38e OK
2022/01/19 06:53:44 DEBUG : dst/three.txt: md5 = 0934416f7c48b79f970657530ff251c1 OK
2022/01/19 06:53:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:45 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:53:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:45 DEBUG : two: Unchanged skipping
2022/01/19 06:53:45 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:46 INFO  : one: Deleted
2022/01/19 06:53:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:46 DEBUG : one: md5 = 79ef5bec286c989e261b37b2f060960c OK
2022/01/19 06:53:46 INFO  : one: Copied (new)
2022/01/19 06:53:46 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:46 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:47 INFO  : three.txt: Deleted
2022/01/19 06:53:47 INFO  : three.txt: Moved into backup dir
2022/01/19 06:53:47 DEBUG : dst/three.txt: md5 = 5a53da89cafede260ff082a85ea18b6c OK
2022/01/19 06:53:47 DEBUG : one.bak: Excluded
2022/01/19 06:53:47 DEBUG : three.txt.bak: Excluded
2022/01/19 06:53:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for checks to finish
2022/01/19 06:53:47 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:53:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:47 DEBUG : two: Unchanged skipping
2022/01/19 06:53:48 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:48 INFO  : one: Deleted
2022/01/19 06:53:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst': Waiting for transfers to finish
2022/01/19 06:53:49 DEBUG : one: md5 = b22e14a946a77b4867301590192cda33 OK
2022/01/19 06:53:49 INFO  : one: Copied (new)
2022/01/19 06:53:49 DEBUG : Waiting for deletions to finish
2022/01/19 06:53:49 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:49 INFO  : three.txt: Deleted
2022/01/19 06:53:49 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (7.64s)
=== RUN   TestSyncSuffix
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:51 DEBUG : dst/one: md5 = c6bae880cadb4f9f66711016a90ced60 OK
2022/01/19 06:53:51 DEBUG : dst/two: md5 = efed7572f0bdf734773952e998e13323 OK
2022/01/19 06:53:51 DEBUG : dst/three.txt: md5 = c6e3f27e3ce24102692004500d53ea43 OK
2022/01/19 06:53:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:52 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:53:53 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:53 INFO  : one: Deleted
2022/01/19 06:53:53 DEBUG : one: md5 = bf19f012046f09442b30a0c6d2575722 OK
2022/01/19 06:53:53 INFO  : one: Copied (new)
2022/01/19 06:53:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:53 DEBUG : two: Unchanged skipping
2022/01/19 06:53:53 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/01/19 06:53:54 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:54 INFO  : three.txt: Deleted
2022/01/19 06:53:54 DEBUG : three.txt: md5 = dfe6c98326df5566edce39c7622adb53 OK
2022/01/19 06:53:54 INFO  : three.txt: Copied (new)
2022/01/19 06:53:55 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:53:55 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/19 06:53:55 INFO  : one: Deleted
2022/01/19 06:53:55 DEBUG : one: md5 = bb56ad6b962a753b395c896cd1c5e305 OK
2022/01/19 06:53:55 INFO  : one: Copied (new)
2022/01/19 06:53:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:53:55 DEBUG : two: Unchanged skipping
2022/01/19 06:53:55 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/01/19 06:53:56 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/19 06:53:56 INFO  : three.txt: Deleted
2022/01/19 06:53:56 DEBUG : three.txt: md5 = af2aa9ae4926226935e1222c6721e349 OK
2022/01/19 06:53:56 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (7.04s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:53:58 DEBUG : dst/one: md5 = d73d52f23d03a7a3eca7a54759ea0432 OK
2022/01/19 06:53:58 DEBUG : dst/two: md5 = 900b840c7167ef553639b3009b337424 OK
2022/01/19 06:53:58 DEBUG : dst/three.txt: md5 = ab19fc70ec638d6efb13a0f30ed3cfb9 OK
2022/01/19 06:53:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst.bin"
2022/01/19 06:53:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-posulin2catocoz5kojuhut0/dst"
2022/01/19 06:53:59 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/19 06:54:00 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/19 06:54:00 INFO  : one: Deleted
2022/01/19 06:54:00 DEBUG : one: md5 = 6ad58a2c37e58818f68689d97b5005b9 OK
2022/01/19 06:54:00 INFO  : one: Copied (new)
2022/01/19 06:54:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:00 DEBUG : two: Unchanged skipping
2022/01/19 06:54:00 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/01/19 06:54:01 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/19 06:54:01 INFO  : three.txt: Deleted
2022/01/19 06:54:01 DEBUG : three.txt: md5 = 0f3501c2276b423a61997b15fc70fe0b OK
2022/01/19 06:54:01 INFO  : three.txt: Copied (new)
2022/01/19 06:54:02 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/19 06:54:02 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/19 06:54:02 INFO  : one: Deleted
2022/01/19 06:54:03 DEBUG : one: md5 = 105bd8b231fea108af8eebd3f6e0bae8 OK
2022/01/19 06:54:03 INFO  : one: Copied (new)
2022/01/19 06:54:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:03 DEBUG : two: Unchanged skipping
2022/01/19 06:54:03 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/01/19 06:54:03 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/19 06:54:03 INFO  : three.txt: Deleted
2022/01/19 06:54:03 DEBUG : three.txt: md5 = 3a49ec8fa9ad5dfbaf189ba9a09ceffd OK
2022/01/19 06:54:03 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (7.37s)
=== RUN   TestSyncUTFNorm
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:54:05 DEBUG : Testêé: md5 = 643ecc837e8f8a2fe7870d13e8ecdc35 OK
2022/01/19 06:54:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:05 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2022/01/19 06:54:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:06 DEBUG : Testêé: md5 = 8859e15ac6086f0208ce7d67e88d39ab OK
2022/01/19 06:54:06 INFO  : Testêé: Copied (replaced existing) to: Testêé
2022/01/19 06:54:06 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (1.44s)
=== RUN   TestSyncImmutable
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:54:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:07 DEBUG : existing: md5 = 7cffc6cb822be8f6600e399b1ce0ab47 OK
2022/01/19 06:54:07 INFO  : existing: Copied (new)
2022/01/19 06:54:07 DEBUG : Waiting for deletions to finish
2022/01/19 06:54:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:07 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2022/01/19 06:54:07 ERROR : existing: Source and destination exist but do not match: immutable file modified
2022/01/19 06:54:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:07 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': not deleting files as there were IO errors
2022/01/19 06:54:07 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': not deleting directories as there were IO errors
--- PASS: TestSyncImmutable (1.58s)
=== RUN   TestSyncIgnoreCase
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:54:08 DEBUG : EXISTING: md5 = db809e13ca29bb3733ebf17f24e4ee97 OK
2022/01/19 06:54:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:08 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:08 DEBUG : existing: Unchanged skipping
2022/01/19 06:54:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:08 DEBUG : Waiting for deletions to finish
2022/01/19 06:54:08 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (1.04s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1989: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
    sync_test.go:1989: This test only runs on local
--- PASS: TestMaxTransfer (0.38s)
    --- SKIP: TestMaxTransfer/Hard (0.16s)
    --- SKIP: TestMaxTransfer/Soft (0.09s)
    --- SKIP: TestMaxTransfer/Cautious (0.13s)
=== RUN   TestSyncConcurrentDelete
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:54:10 DEBUG : both0: md5 = 96fef044ac9fd975774440da3c0cc443 OK
2022/01/19 06:54:10 DEBUG : only0: md5 = ea096ce9c8237063aac1d0306747b83d OK
2022/01/19 06:54:11 DEBUG : both1: md5 = 3be26be1cfc3ec72e50bc8821b37f217 OK
2022/01/19 06:54:12 DEBUG : only1: md5 = e8308c6c1a266c6602e33717850b329a OK
2022/01/19 06:54:12 DEBUG : both2: md5 = 60b6845a5aebda537ca4087bd1a82e38 OK
2022/01/19 06:54:12 DEBUG : only2: md5 = e70ffbbf46b4e48224bc8f00a7494f75 OK
2022/01/19 06:54:13 DEBUG : both3: md5 = 7c70378acd2d5510e93e2589116e7d5f OK
2022/01/19 06:54:13 DEBUG : only3: md5 = cead2c816f9a1c6afaf775bd5ebbb52d OK
2022/01/19 06:54:14 DEBUG : both4: md5 = 74485ae9f53b19d5fb2effb2e467f867 OK
2022/01/19 06:54:14 DEBUG : only4: md5 = b9cc2520a9cbd9eba309e506c0cfb734 OK
2022/01/19 06:54:14 DEBUG : both5: md5 = 881d3d096e4cf3a5ee2db051e88f9453 OK
2022/01/19 06:54:15 DEBUG : only5: md5 = a9caa089f5fa90f42837b2f94b326736 OK
2022/01/19 06:54:15 DEBUG : both6: md5 = d33383bffcee71c94ee90081341d46a1 OK
2022/01/19 06:54:16 DEBUG : only6: md5 = b492dc4d1841c3a6311b9a79c87149b4 OK
2022/01/19 06:54:16 DEBUG : both7: md5 = 26bcc20c887e58424a66ccd4fa1055fc OK
2022/01/19 06:54:17 DEBUG : only7: md5 = 07d59a565db728359c56022b05f02ff2 OK
2022/01/19 06:54:17 DEBUG : both8: md5 = 00a3a7b34f3728168525afa75bf760f5 OK
2022/01/19 06:54:18 DEBUG : only8: md5 = 29f6a9bfcf033feef493e6dd5a3b8c7a OK
2022/01/19 06:54:18 DEBUG : both9: md5 = e7fe0df6f5282973f7e4e696e468df8d OK
2022/01/19 06:54:19 DEBUG : only9: md5 = c6417a6ebe9dbe6cb628a396d5e0b041 OK
2022/01/19 06:54:19 DEBUG : both10: md5 = ae0bf7b83bdc0bf267427ff0e4c972a4 OK
2022/01/19 06:54:19 DEBUG : only10: md5 = 8de55b95ddd51e0a2b4592fd3c959f6e OK
2022/01/19 06:54:20 DEBUG : both11: md5 = 8359ed2745b87a90e8ddd46f843152ea OK
2022/01/19 06:54:20 DEBUG : only11: md5 = 911ce45f193d1c5b7ac6559d594f1497 OK
2022/01/19 06:54:21 DEBUG : both12: md5 = 463cdeebe79d202daae8a2764291205f OK
2022/01/19 06:54:22 DEBUG : only12: md5 = f75abee203b5856af0eda344773ad2df OK
2022/01/19 06:54:22 DEBUG : both13: md5 = cfa85ae2c43d815d0ae006b589506a73 OK
2022/01/19 06:54:22 DEBUG : only13: md5 = 7193e28d2b0fa2aabd42a0733bc5cfdb OK
2022/01/19 06:54:23 DEBUG : both14: md5 = dccfb22761ca12e688b8b230db05332d OK
2022/01/19 06:54:23 DEBUG : only14: md5 = 68f924c49cdfc552b1c2d6c7f12c8bb2 OK
2022/01/19 06:54:24 DEBUG : both15: md5 = 6690ed3c7f3b97bda5342d6870e20c86 OK
2022/01/19 06:54:24 DEBUG : only15: md5 = 03ac4cb5c60c876852d80b027d02af65 OK
2022/01/19 06:54:24 DEBUG : both16: md5 = 6628912bae7a53d4a8387cc216c3d349 OK
2022/01/19 06:54:25 DEBUG : only16: md5 = 85448fc7e72f65101eadaed4261e288a OK
2022/01/19 06:54:25 DEBUG : both17: md5 = 8595520b0ba40e2dc47f5a3ee04f5b05 OK
2022/01/19 06:54:26 DEBUG : only17: md5 = 589fa11ee5542526eee3e7f8954b8900 OK
2022/01/19 06:54:27 DEBUG : both18: md5 = 8fc7c09670822f0d59d4d539225fea88 OK
2022/01/19 06:54:27 DEBUG : only18: md5 = 4ec37dda68bacfe956a411ce25d4c8fc OK
2022/01/19 06:54:28 DEBUG : both19: md5 = 25cf8baec902bc90126435cce9a3d75a OK
2022/01/19 06:54:28 DEBUG : only19: md5 = 31615e32e307f517e9819e1cfa8607ce OK
2022/01/19 06:54:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:30 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both0: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both1: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both10: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both11: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both12: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both13: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both14: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both15: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both16: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both17: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both18: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both19: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both2: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both3: Unchanged skipping
2022/01/19 06:54:30 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:30 DEBUG : both4: Unchanged skipping
2022/01/19 06:54:31 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:31 DEBUG : both5: Unchanged skipping
2022/01/19 06:54:31 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:31 DEBUG : both6: Unchanged skipping
2022/01/19 06:54:31 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:31 DEBUG : both7: Unchanged skipping
2022/01/19 06:54:31 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:31 DEBUG : both8: Unchanged skipping
2022/01/19 06:54:31 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:31 DEBUG : both9: Unchanged skipping
2022/01/19 06:54:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:31 DEBUG : Waiting for deletions to finish
2022/01/19 06:54:31 INFO  : only0: Deleted
2022/01/19 06:54:31 INFO  : only11: Deleted
2022/01/19 06:54:31 INFO  : only1: Deleted
2022/01/19 06:54:31 INFO  : only2: Deleted
2022/01/19 06:54:31 INFO  : only3: Deleted
2022/01/19 06:54:31 INFO  : only15: Deleted
2022/01/19 06:54:31 INFO  : only5: Deleted
2022/01/19 06:54:31 INFO  : only16: Deleted
2022/01/19 06:54:31 INFO  : only10: Deleted
2022/01/19 06:54:31 INFO  : only18: Deleted
2022/01/19 06:54:31 INFO  : only9: Deleted
2022/01/19 06:54:31 INFO  : only4: Deleted
2022/01/19 06:54:31 INFO  : only13: Deleted
2022/01/19 06:54:31 INFO  : only14: Deleted
2022/01/19 06:54:31 INFO  : only17: Deleted
2022/01/19 06:54:32 INFO  : only6: Deleted
2022/01/19 06:54:32 INFO  : only19: Deleted
2022/01/19 06:54:32 INFO  : only12: Deleted
2022/01/19 06:54:32 INFO  : only7: Deleted
2022/01/19 06:54:32 INFO  : only8: Deleted
2022/01/19 06:54:32 INFO  : There was nothing to transfer
--- PASS: TestSyncConcurrentDelete (28.43s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0'", Local "Local file system at /tmp/rclone2954491513", Modify Window "1ns"
2022/01/19 06:54:38 DEBUG : both0: md5 = b30a6344ba74e2a59d9eb979892365ca OK
2022/01/19 06:54:38 DEBUG : only0: md5 = 3db0e2b50ad175a5ef52621dd12d3c33 OK
2022/01/19 06:54:39 DEBUG : both1: md5 = 1945957ab37ace2174a0f221d4c9a086 OK
2022/01/19 06:54:39 DEBUG : only1: md5 = 766d16ea189bd24c1026148c6aefa1b5 OK
2022/01/19 06:54:39 DEBUG : both2: md5 = 04db02fcf6ef4c46cc7257872cb721c3 OK
2022/01/19 06:54:40 DEBUG : only2: md5 = 61bf7f1f213baf98de198ec5a61e553e OK
2022/01/19 06:54:40 DEBUG : both3: md5 = 50c586c77f197f580fead36c8f66ffcb OK
2022/01/19 06:54:40 DEBUG : only3: md5 = cbbbda38958c6c7349d34f7b266e88bb OK
2022/01/19 06:54:41 DEBUG : both4: md5 = 146bad44b8e0d4e08ec1d4a6c387588f OK
2022/01/19 06:54:41 DEBUG : only4: md5 = 1a46914053fe6b6d8a8d22bc545df5be OK
2022/01/19 06:54:41 DEBUG : both5: md5 = 8900658a01080af369a9d68a50af861d OK
2022/01/19 06:54:42 DEBUG : only5: md5 = 3a12a853c4f3e67a022bc498262c2bda OK
2022/01/19 06:54:42 DEBUG : both6: md5 = 6a716a33e88796f5d90dcd056cf0b15b OK
2022/01/19 06:54:42 DEBUG : only6: md5 = 6e10365ffed69bef2e3b5d7d9a63aa7a OK
2022/01/19 06:54:43 DEBUG : both7: md5 = 9d27b36b2558813524b8f4488bd6b750 OK
2022/01/19 06:54:44 DEBUG : only7: md5 = 78b45a52ec04339c1220360e0aa9e856 OK
2022/01/19 06:54:44 DEBUG : both8: md5 = d3098fa6d5af0bef626b3beac19fd68b OK
2022/01/19 06:54:45 DEBUG : only8: md5 = 09bde624404e4d56da40211b8e60645d OK
2022/01/19 06:54:45 DEBUG : both9: md5 = 2ebfa79534752f0ed1845ebd14c82609 OK
2022/01/19 06:54:45 DEBUG : only9: md5 = 4e39dc8a49ba79a44b65f1b9142ac941 OK
2022/01/19 06:54:45 DEBUG : both10: md5 = 0c5ecbbe37bbaa21ebc5e7ab908469d9 OK
2022/01/19 06:54:46 DEBUG : only10: md5 = 69efdae3e01ecfbcd45b27e82aebdee8 OK
2022/01/19 06:54:46 DEBUG : both11: md5 = 23e369a753ee4d587c0aafb1e27c47bc OK
2022/01/19 06:54:47 DEBUG : only11: md5 = dd0c99056993da7f534adfe192b73a9d OK
2022/01/19 06:54:47 DEBUG : both12: md5 = eabb1dc1fc054747c454b1755cfb774c OK
2022/01/19 06:54:47 DEBUG : only12: md5 = cc8d482d165993249f71921b92ab43fe OK
2022/01/19 06:54:48 DEBUG : both13: md5 = 0cfae7b5e75c265ab623607a2870f1ac OK
2022/01/19 06:54:48 DEBUG : only13: md5 = 44e8c9934ae6a3fb76127eaf997460a2 OK
2022/01/19 06:54:48 DEBUG : both14: md5 = 0a28133e4da95900c195e37bd026b91d OK
2022/01/19 06:54:49 DEBUG : only14: md5 = 80b088c7e5a20603f6ef6cf0ce5cc66a OK
2022/01/19 06:54:49 DEBUG : both15: md5 = 609260968092145c4b3d87a41bc5ee2b OK
2022/01/19 06:54:49 DEBUG : only15: md5 = 5ff931038be5f2aa381f30bbee7b7849 OK
2022/01/19 06:54:50 DEBUG : both16: md5 = 61209f5b2b39f377cb225cf9736372b5 OK
2022/01/19 06:54:50 DEBUG : only16: md5 = c1f2ee38b12f59c2cdfab27527bd9460 OK
2022/01/19 06:54:50 DEBUG : both17: md5 = 36d9c8c4d80d9f721ea838ec4c8f4b41 OK
2022/01/19 06:54:51 DEBUG : only17: md5 = 5a6d60632669d2dacc941454178a30f6 OK
2022/01/19 06:54:51 DEBUG : both18: md5 = 0c3d2aa8594b5572433a7df7c9366143 OK
2022/01/19 06:54:51 DEBUG : only18: md5 = a57c6bddb04e27354de257b1d6b0e002 OK
2022/01/19 06:54:52 DEBUG : both19: md5 = 19c641e267e42ddeb898184f6b024701 OK
2022/01/19 06:54:52 DEBUG : only19: md5 = 08c00930ad0f148566c41f859b115a6e OK
2022/01/19 06:54:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for checks to finish
2022/01/19 06:54:54 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both1: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both11: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both0: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both10: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both12: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both13: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both15: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both14: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both16: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both17: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both18: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both19: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both2: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both3: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both4: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both5: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both6: Unchanged skipping
2022/01/19 06:54:54 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2022/01/19 06:54:54 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both7: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both8: Unchanged skipping
2022/01/19 06:54:54 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/19 06:54:54 DEBUG : both9: Unchanged skipping
2022/01/19 06:54:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Waiting for transfers to finish
2022/01/19 06:54:55 DEBUG : only0: md5 = ea153b3ef250ecd02a923fc0d8039698 OK
2022/01/19 06:54:55 INFO  : only0: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only11: md5 = 3dc3429415c1dd243e987d5b0dc1a821 OK
2022/01/19 06:54:55 INFO  : only11: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only1: md5 = af41eaee34c1d886ce76e2cae4a4a0ec OK
2022/01/19 06:54:55 INFO  : only1: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only10: md5 = 400ef693b4e59fcc2c1617879c4289c5 OK
2022/01/19 06:54:55 INFO  : only10: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only12: md5 = 17288d5726b6eecee20ef2f97b0b922b OK
2022/01/19 06:54:55 INFO  : only12: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only14: md5 = 9993094166541ecf2159d0170e535418 OK
2022/01/19 06:54:55 INFO  : only14: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only13: md5 = e2943c93e67235af6452083bfe725785 OK
2022/01/19 06:54:55 INFO  : only13: Copied (replaced existing)
2022/01/19 06:54:55 DEBUG : only15: md5 = e79c008ba07938e315e4e70c2f0c6481 OK
2022/01/19 06:54:55 INFO  : only15: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only18: md5 = 3a77eaf29c5bcc23788284a145226645 OK
2022/01/19 06:54:56 INFO  : only18: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only17: md5 = c4f29f93e306dceebadf83ab0ef22642 OK
2022/01/19 06:54:56 INFO  : only17: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only19: md5 = 407a240e6dcf0666d8ef54447eacf75c OK
2022/01/19 06:54:56 INFO  : only19: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only16: md5 = b5d918ae3b42cf9be5f8dc699a041cbb OK
2022/01/19 06:54:56 INFO  : only16: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only4: md5 = e0398d10d7f41317ac262237ff0f54eb OK
2022/01/19 06:54:56 INFO  : only4: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only2: md5 = 8c47e8c2e4ab4e223e3524358fb2c45c OK
2022/01/19 06:54:56 INFO  : only2: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only5: md5 = 2943be9fe43db4122eb673520955f3f0 OK
2022/01/19 06:54:56 INFO  : only5: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only6: md5 = 0067523f4a68eeecd83bd00a332df4e5 OK
2022/01/19 06:54:56 INFO  : only6: Copied (replaced existing)
2022/01/19 06:54:56 DEBUG : only8: md5 = de9ef550654e90dea5ca810e22985ed4 OK
2022/01/19 06:54:56 INFO  : only8: Copied (replaced existing)
2022/01/19 06:54:57 DEBUG : only9: md5 = daba7f92cf1dd2f668447e56730afb31 OK
2022/01/19 06:54:57 INFO  : only9: Copied (replaced existing)
2022/01/19 06:54:57 DEBUG : only7: md5 = d58dc1a798e6253c7365e5c898f88a5e OK
2022/01/19 06:54:57 INFO  : only7: Copied (replaced existing)
2022/01/19 06:54:58 DEBUG : only3: md5 = d764268fbff7857b964cf6d9ee8249c5 OK
2022/01/19 06:54:58 INFO  : only3: Copied (replaced existing)
2022/01/19 06:54:58 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncConcurrentTruncate (36.39s)
PASS
2022/01/19 06:55:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-posulin2catocoz5kojuhut0': Purge remote
2022/01/19 06:55:14 DEBUG : Waiting for deletions to finish
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m30.548434009s (try 1/5)