"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5)
2022/01/13 06:25:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9"
2022/01/13 06:25:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2022/01/13 06:25:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9.bin"
2022/01/13 06:25:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9"
2022/01/13 06:25:16 DEBUG : Creating backend with remote "/tmp/rclone1638522913"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.02s)
=== 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-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:16 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2022/01/13 06:25:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
--- PASS: TestCopyWithDryRun (0.39s)
=== RUN   TestCopy
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:17 DEBUG : sub dir/hello world: md5 = e3630cce2061bae9dec233cda6e74114 OK
2022/01/13 06:25:17 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopy (1.48s)
=== RUN   TestCopyMissingDirectory
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:18 DEBUG : Creating backend with remote "/non-existing"
2022/01/13 06:25:18 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2022/01/13 06:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.21s)
=== RUN   TestCopyNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:19 DEBUG : sub dir/hello world: md5 = e70196598de33975e9a7c1fc2a26fa10 OK
2022/01/13 06:25:19 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (1.54s)
=== RUN   TestCopyCheckFirst
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:20 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Running all checks before starting transfers
2022/01/13 06:25:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:20 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Checks finished, now starting transfers
2022/01/13 06:25:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:20 DEBUG : sub dir/hello world: md5 = 61c33460f434e9205de34c571c251414 OK
2022/01/13 06:25:20 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (0.93s)
=== RUN   TestSyncNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:21 ERROR : Ignoring --no-traverse with sync
2022/01/13 06:25:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:21 DEBUG : sub dir/hello world: md5 = e8a120979d9052030b5e4b0187349e2e OK
2022/01/13 06:25:21 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:25:21 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (1.04s)
=== RUN   TestCopyWithDepth
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:23 DEBUG : hello world2: md5 = 488a3811507c3dacc2c0caacad94efe4 OK
2022/01/13 06:25:23 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (2.26s)
=== RUN   TestCopyWithFilesFrom
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:24 DEBUG : hello world2: Excluded
2022/01/13 06:25:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:25 DEBUG : potato2: md5 = fcdfe93530a2f82cf8f4e23036f8cda6 OK
2022/01/13 06:25:25 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (3.59s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:29 DEBUG : potato2: md5 = 29ca3ade6d4c5d966c2b1624f974d669 OK
2022/01/13 06:25:29 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (3.05s)
=== RUN   TestCopyEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:31 DEBUG : sub dir2: Making directory
2022/01/13 06:25:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:31 DEBUG : sub dir/hello world: md5 = eeb52da4394744941d3779b025d3e9c5 OK
2022/01/13 06:25:31 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:25:31 DEBUG : sub dir2: Making directory
2022/01/13 06:25:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': copied 1 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestCopyEmptyDirectories (0.96s)
=== RUN   TestMoveEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:31 DEBUG : sub dir2: Making directory
2022/01/13 06:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:32 DEBUG : sub dir/hello world: md5 = d6a2ae3643c8ab182ba4aeac839e3de1 OK
2022/01/13 06:25:32 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:25:32 INFO  : sub dir/hello world: Deleted
2022/01/13 06:25:32 DEBUG : sub dir: Making directory
2022/01/13 06:25:32 DEBUG : sub dir2: Making directory
2022/01/13 06:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': copied 2 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestMoveEmptyDirectories (0.96s)
=== RUN   TestSyncEmptyDirectories
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:32 DEBUG : sub dir2: Making directory
2022/01/13 06:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:33 DEBUG : sub dir/hello world: md5 = bf42b0cf16109da5b5a280aa32a2f093 OK
2022/01/13 06:25:33 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:25:33 DEBUG : sub dir2: Making directory
2022/01/13 06:25:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': copied 1 directories
2022/01/13 06:25:33 DEBUG : Waiting for deletions to finish
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestSyncEmptyDirectories (1.02s)
=== RUN   TestServerSideCopy
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:35 DEBUG : sub dir/hello world: md5 = 06a3b9c9b8575de0660b27a826396010 OK
2022/01/13 06:25:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dabapud5tabeler8gujaciv8"
2022/01/13 06:25:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dabapud5tabeler8gujaciv8.bin"
2022/01/13 06:25:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dabapud5tabeler8gujaciv8"
    sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9' -> Encrypted drive 'TestCryptSwift:rclone-test-dabapud5tabeler8gujaciv8'
2022/01/13 06:25:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dabapud5tabeler8gujaciv8': Waiting for checks to finish
2022/01/13 06:25:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dabapud5tabeler8gujaciv8': Waiting for transfers to finish
2022/01/13 06:25:39 INFO  : sub dir/hello world: Copied (server-side copy)
2022/01/13 06:25:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dabapud5tabeler8gujaciv8': Purge remote
2022/01/13 06:25:39 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:40 INFO  : sub dir/hello world.bin: Deleted
--- PASS: TestServerSideCopy (6.59s)
=== RUN   TestCopyAfterDelete
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:40 DEBUG : sub dir/hello world: md5 = b15cebb6bf6a8169b766e28674492b18 OK
2022/01/13 06:25:40 ERROR : : error listing: directory not found
2022/01/13 06:25:40 DEBUG : Local file system at /tmp/rclone1638522913: Making directory
2022/01/13 06:25:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
--- PASS: TestCopyAfterDelete (1.11s)
=== RUN   TestCopyRedownload
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:41 DEBUG : sub dir/hello world: md5 = 46ed3796e4ca111cb0655fb33b6c4daa OK
2022/01/13 06:25:42 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for checks to finish
2022/01/13 06:25:42 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for transfers to finish
2022/01/13 06:25:42 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyRedownload (0.97s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:44 DEBUG : check sum: md5 = a7debe7fcf3881afed13adc1a1b65dfd OK
2022/01/13 06:25:44 INFO  : check sum: Copied (new)
2022/01/13 06:25:44 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:44 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2022/01/13 06:25:44 DEBUG : check sum: Size of src and dst objects identical
2022/01/13 06:25:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:44 DEBUG : check sum: Unchanged skipping
2022/01/13 06:25:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:44 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:44 INFO  : There was nothing to transfer
--- PASS: TestSyncBasedOnCheckSum (2.92s)
=== RUN   TestSyncSizeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:46 DEBUG : sizeonly: md5 = bd6423c73af825af96633b41a4ad1a39 OK
2022/01/13 06:25:46 INFO  : sizeonly: Copied (new)
2022/01/13 06:25:46 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:47 DEBUG : sizeonly: Sizes identical
2022/01/13 06:25:47 DEBUG : sizeonly: Unchanged skipping
2022/01/13 06:25:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:47 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:47 INFO  : There was nothing to transfer
--- PASS: TestSyncSizeOnly (2.72s)
=== RUN   TestSyncIgnoreSize
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:50 DEBUG : ignore-size: md5 = 841d86e1f6122c0bf92c8aa9352fbe35 OK
2022/01/13 06:25:50 INFO  : ignore-size: Copied (new)
2022/01/13 06:25:50 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:50 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:25:50 DEBUG : ignore-size: Unchanged skipping
2022/01/13 06:25:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:50 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:50 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreSize (3.49s)
=== RUN   TestSyncIgnoreTimes
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:52 DEBUG : existing: md5 = a401ff6517f48b3a672c733baf1b5e12 OK
2022/01/13 06:25:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:52 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:25:52 DEBUG : existing: Unchanged skipping
2022/01/13 06:25:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:52 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:52 INFO  : There was nothing to transfer
2022/01/13 06:25:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:52 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2022/01/13 06:25:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:54 DEBUG : existing: md5 = 4da588f4f3b0d7d6d36ffd01c5c25356 OK
2022/01/13 06:25:54 INFO  : existing: Copied (replaced existing)
2022/01/13 06:25:54 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (3.90s)
=== RUN   TestSyncIgnoreExisting
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:56 DEBUG : existing: md5 = a4e18dedd3187b22b978b9d7f54c5b32 OK
2022/01/13 06:25:56 INFO  : existing: Copied (new)
2022/01/13 06:25:56 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:56 DEBUG : existing: Destination exists, skipping
2022/01/13 06:25:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:25:56 DEBUG : Waiting for deletions to finish
2022/01/13 06:25:56 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (2.74s)
=== RUN   TestSyncIgnoreErrors
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:25:58 DEBUG : b/potato: md5 = c29e6a43e3d4c6bba647d441e42e4355 OK
2022/01/13 06:25:59 DEBUG : c/non empty space: md5 = ffce38f8625359b09d9eab54ac75d359 OK
2022/01/13 06:25:59 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/01/13 06:25:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:25:59 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:25:59 DEBUG : c/non empty space: Unchanged skipping
2022/01/13 06:25:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:00 DEBUG : a/potato2: md5 = f1975fc03e7d122e72e21e3bf04ee277 OK
2022/01/13 06:26:00 INFO  : a/potato2: Copied (new)
2022/01/13 06:26:00 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:01 INFO  : b/potato: Deleted
--- PASS: TestSyncIgnoreErrors (3.45s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:02 DEBUG : empty space: md5 = 61c5efacf6fd6ea34092c886598d5392 OK
2022/01/13 06:26:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:02 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/13 06:26:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:02 NOTICE: empty space: Skipped copy as --dry-run is set (size 1)
2022/01/13 06:26:02 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:02 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/13 06:26:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:03 DEBUG : empty space: md5 = dfe81660a1b7300b032d5584ea168647 OK
2022/01/13 06:26:03 INFO  : empty space: Copied (replaced existing)
2022/01/13 06:26:03 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingModtimeOnly (1.77s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
    sync_test.go:577: Can't check this if no hashes supported
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.20s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:05 DEBUG : foo: md5 = 7808e2cdff3b3175ead02c5da08a5fc5 OK
2022/01/13 06:26:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:05 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/13 06:26:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:06 DEBUG : foo: md5 = 6019d16560c2d451c5ba4dfb22bfc9e1 OK
2022/01/13 06:26:06 INFO  : foo: Copied (replaced existing)
2022/01/13 06:26:06 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncDoesntUpdateModtime (4.02s)
=== RUN   TestSyncAfterAddingAFile
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:08 DEBUG : empty space: md5 = 3a93e65a373b544dad4efb06e9494f75 OK
2022/01/13 06:26:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:08 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:08 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:08 DEBUG : potato: md5 = da8e9221483782afc72c9893cd18e7f1 OK
2022/01/13 06:26:08 INFO  : potato: Copied (new)
2022/01/13 06:26:08 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterAddingAFile (1.71s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:09 DEBUG : potato: md5 = f3e6c6b4a09f09b3d5da8d3e27c2edec OK
2022/01/13 06:26:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:10 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2022/01/13 06:26:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:10 DEBUG : potato: md5 = 1b90149a86d779c29ab641f3b2c24fc8 OK
2022/01/13 06:26:10 INFO  : potato: Copied (replaced existing)
2022/01/13 06:26:10 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingFilesSizeOnly (1.60s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:11 DEBUG : potato: md5 = ab898fc1b82ef72b578bab9e1194d1f0 OK
2022/01/13 06:26:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:11 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/13 06:26:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:12 DEBUG : potato: md5 = 9eecaf10917461e7103713e34e302dc0 OK
2022/01/13 06:26:12 INFO  : potato: Copied (replaced existing)
2022/01/13 06:26:12 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (1.42s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:13 DEBUG : potato: md5 = 8d4b939440db45b719daf96e15c0c4a5 OK
2022/01/13 06:26:13 DEBUG : empty space: md5 = 1ac37fb7282d682a276bdfc2b36cc4ac OK
2022/01/13 06:26:13 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2022/01/13 06:26:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:13 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:13 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:13 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.59s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:14 DEBUG : potato: md5 = 179a65e39ded6baacd61bc3af6504e70 OK
2022/01/13 06:26:14 DEBUG : empty space: md5 = 581ac0752a99f692449d3e4eb35c1f2d OK
2022/01/13 06:26:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:15 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:15 DEBUG : potato2: md5 = 8a6a895f469dbcb6901e74c256c1c8d8 OK
2022/01/13 06:26:15 INFO  : potato2: Copied (new)
2022/01/13 06:26:15 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:15 INFO  : potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.03s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:18 DEBUG : b/potato: md5 = ea9980f354331bc6ae206533a888cdc1 OK
2022/01/13 06:26:18 DEBUG : c/non empty space: md5 = 4abfe06a719539d197e3f92b3bda475b OK
2022/01/13 06:26:18 DEBUG : d: Making directory
2022/01/13 06:26:18 DEBUG : d/e: Making directory
    fstest.go:251: Filtering empty directory "d"
    fstest.go:251: Filtering empty directory "d/e"
2022/01/13 06:26:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:19 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:19 DEBUG : c/non empty space: Unchanged skipping
2022/01/13 06:26:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:19 DEBUG : a/potato2: md5 = 775506fdf4c4435deac84c85da1e14c9 OK
2022/01/13 06:26:19 INFO  : a/potato2: Copied (new)
2022/01/13 06:26:19 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:19 INFO  : b/potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.49s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:21 DEBUG : b/potato: md5 = 06a71b02051efe8a60d319e578520980 OK
2022/01/13 06:26:21 DEBUG : c/non empty space: md5 = d9b0d9e25b45331582fb9049c289c095 OK
2022/01/13 06:26:21 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2022/01/13 06:26:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:21 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:21 DEBUG : c/non empty space: Unchanged skipping
2022/01/13 06:26:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:21 DEBUG : a/potato2: md5 = 1772c31bb19782a37c8b11ab657160fc OK
2022/01/13 06:26:21 INFO  : a/potato2: Copied (new)
2022/01/13 06:26:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': not deleting files as there were IO errors
2022/01/13 06:26:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': not deleting directories as there were IO errors
    fstest.go:251: Filtering empty directory "d"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.17s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:23 DEBUG : potato: md5 = 7288d620ba10b6c92fd6f0fb601e8d26 OK
2022/01/13 06:26:23 DEBUG : empty space: md5 = 5c1dbadc1b323adbc79ed1f0746f7067 OK
2022/01/13 06:26:23 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:23 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:23 INFO  : potato: Deleted
2022/01/13 06:26:25 DEBUG : potato2: md5 = d1f13dbcd7e9232e2d6ed5b7a24bcba3 OK
2022/01/13 06:26:25 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteDuring (3.49s)
=== RUN   TestSyncDeleteBefore
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:27 DEBUG : potato: md5 = 50a0f315307af7872e8f1f1e98119bc3 OK
2022/01/13 06:26:27 DEBUG : empty space: md5 = f52274a1dd0d8a8076a4e006d69c275c OK
2022/01/13 06:26:27 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:27 INFO  : potato: Deleted
2022/01/13 06:26:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:28 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:28 DEBUG : potato2: md5 = 1248b345b0eddb64da16db4fdc7ae7f0 OK
2022/01/13 06:26:28 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (3.35s)
=== RUN   TestCopyDeleteBefore
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:30 DEBUG : potato: md5 = 619c03074b3c2b6eca9f0791add4528c OK
2022/01/13 06:26:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:31 DEBUG : potato2: md5 = f5ccebfedbe11156ca6aaa305aa62a14 OK
2022/01/13 06:26:31 INFO  : potato2: Copied (new)
--- PASS: TestCopyDeleteBefore (2.65s)
=== RUN   TestSyncWithExclude
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:33 DEBUG : potato2: md5 = 760445638290e9e0f5b3fec30d6f68bc OK
2022/01/13 06:26:34 DEBUG : empty space: md5 = 4bcf0d8e785737d4d22b50079b69e3cd OK
2022/01/13 06:26:34 DEBUG : enormous: Excluded
2022/01/13 06:26:34 DEBUG : potato2: Excluded
2022/01/13 06:26:34 DEBUG : potato2: Excluded
2022/01/13 06:26:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:35 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:35 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:35 INFO  : There was nothing to transfer
2022/01/13 06:26:35 DEBUG : enormous: Excluded
2022/01/13 06:26:35 DEBUG : potato2: Excluded
2022/01/13 06:26:35 DEBUG : potato2: Excluded
2022/01/13 06:26:35 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for checks to finish
2022/01/13 06:26:35 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:35 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:35 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for transfers to finish
2022/01/13 06:26:35 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:35 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExclude (3.83s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:36 DEBUG : potato2: md5 = b676191927a00f7db101e8d4966cd706 OK
2022/01/13 06:26:37 DEBUG : empty space: md5 = 9269b800b16975ee28cb96781d5f6d04 OK
2022/01/13 06:26:38 DEBUG : enormous: md5 = 2bcbaece22cd28ad5c957c34ed24ea69 OK
2022/01/13 06:26:38 DEBUG : enormous: Excluded
2022/01/13 06:26:38 DEBUG : potato2: Excluded
2022/01/13 06:26:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:39 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:39 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:39 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:39 INFO  : potato2: Deleted
2022/01/13 06:26:39 INFO  : enormous: Deleted
2022/01/13 06:26:39 INFO  : There was nothing to transfer
2022/01/13 06:26:40 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for checks to finish
2022/01/13 06:26:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:40 DEBUG : empty space: Unchanged skipping
2022/01/13 06:26:40 DEBUG : Local file system at /tmp/rclone1638522913: Waiting for transfers to finish
2022/01/13 06:26:40 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:40 INFO  : potato2: Deleted
2022/01/13 06:26:40 INFO  : enormous: Deleted
2022/01/13 06:26:40 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExcludeAndDeleteExcluded (4.29s)
=== RUN   TestSyncWithUpdateOlder
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:26:41 DEBUG : one: md5 = ff553d5108ad36907a6d18f11ba9bb52 OK
2022/01/13 06:26:42 DEBUG : two: md5 = 811b80a88d9cb9a3b908a8aa3b92292d OK
2022/01/13 06:26:42 DEBUG : three: md5 = 468853f5037d782e81dc90c4e46d0b4f OK
2022/01/13 06:26:43 DEBUG : four: md5 = bf4e2434b7ce54321796e2163e5e4267 OK
2022/01/13 06:26:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:43 DEBUG : four: Sizes differ (src 4 vs dst 8)
2022/01/13 06:26:43 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2022/01/13 06:26:43 DEBUG : three: Sizes identical
2022/01/13 06:26:43 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2022/01/13 06:26:43 DEBUG : one: Destination is newer than source, skipping
2022/01/13 06:26:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:43 DEBUG : four: md5 = 52748bb3d655643587a4bacb63c00773 OK
2022/01/13 06:26:43 INFO  : four: Copied (replaced existing)
2022/01/13 06:26:44 DEBUG : five: md5 = 038ec38e2c9ca3149172134a9fed7e79 OK
2022/01/13 06:26:44 INFO  : five: Copied (new)
2022/01/13 06:26:45 DEBUG : two: md5 = 10c1b32e19ab918feaca3c677302ab78 OK
2022/01/13 06:26:45 INFO  : two: Copied (replaced existing)
2022/01/13 06:26:45 DEBUG : Waiting for deletions to finish
    sync_test.go:992: Skip test with --checksum as no hashes supported
--- PASS: TestSyncWithUpdateOlder (6.34s)
=== 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-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
    sync_test.go:1058: Can track renames: false
2022/01/13 06:26:46 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Ignoring --track-renames as the source and destination do not have a common hash
2022/01/13 06:26:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:47 DEBUG : potato: md5 = 07b7f167d57c26bd8e311ebbe0a9f341 OK
2022/01/13 06:26:47 INFO  : potato: Copied (new)
2022/01/13 06:26:47 DEBUG : yam: md5 = d60eaf49a909567a5c8a791df628012f OK
2022/01/13 06:26:47 INFO  : yam: Copied (new)
2022/01/13 06:26:47 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Ignoring --track-renames as the source and destination do not have a common hash
2022/01/13 06:26:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:48 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:48 DEBUG : potato: Unchanged skipping
2022/01/13 06:26:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:50 DEBUG : yaml: md5 = f2587af08769bf8f428cf8b3c7e329e0 OK
2022/01/13 06:26:50 INFO  : yaml: Copied (new)
2022/01/13 06:26:50 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:50 INFO  : yam: Deleted
--- PASS: TestSyncWithTrackRenames (4.70s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
    sync_test.go:1127: Can track renames: true
2022/01/13 06:26:51 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Making map for --track-renames
2022/01/13 06:26:51 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Finished making map for --track-renames
2022/01/13 06:26:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for renames to finish
2022/01/13 06:26:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:51 DEBUG : potato: md5 = 8661aeee729f334d9f9b5fec86acbc08 OK
2022/01/13 06:26:51 INFO  : potato: Copied (new)
2022/01/13 06:26:52 DEBUG : yam: md5 = 8105efd7f29c9bd38e15ed0c8f18ce60 OK
2022/01/13 06:26:52 INFO  : yam: Copied (new)
2022/01/13 06:26:52 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:52 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Making map for --track-renames
2022/01/13 06:26:52 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Finished making map for --track-renames
2022/01/13 06:26:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:52 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:52 DEBUG : potato: Unchanged skipping
2022/01/13 06:26:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for renames to finish
2022/01/13 06:26:54 INFO  : yam: Copied (server-side copy) to: yaml
2022/01/13 06:26:55 INFO  : yam: Deleted
2022/01/13 06:26:55 INFO  : yaml: Renamed from "yam"
2022/01/13 06:26:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:55 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyModtime (6.39s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
    sync_test.go:1163: Can track renames: true
2022/01/13 06:26:58 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Making map for --track-renames
2022/01/13 06:26:58 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Finished making map for --track-renames
2022/01/13 06:26:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for renames to finish
2022/01/13 06:26:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:26:58 DEBUG : potato: md5 = c298e504f25f08e1fc5302d7d0a7f117 OK
2022/01/13 06:26:58 INFO  : potato: Copied (new)
2022/01/13 06:26:58 DEBUG : sub/yam: md5 = 84684a07fe993d16352938d937a587d1 OK
2022/01/13 06:26:58 INFO  : sub/yam: Copied (new)
2022/01/13 06:26:58 DEBUG : Waiting for deletions to finish
2022/01/13 06:26:59 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:26:59 DEBUG : potato: Unchanged skipping
2022/01/13 06:26:59 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Making map for --track-renames
2022/01/13 06:26:59 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Finished making map for --track-renames
2022/01/13 06:26:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:26:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for renames to finish
2022/01/13 06:27:01 INFO  : sub/yam: Copied (server-side copy) to: yam
2022/01/13 06:27:01 INFO  : sub/yam: Deleted
2022/01/13 06:27:01 INFO  : yam: Renamed from "sub/yam"
2022/01/13 06:27:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:27:01 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (5.33s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:27:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:27:03 DEBUG : sub dir/hello world: md5 = b118d004f8f0ce55edc35641b0fc58fe OK
2022/01/13 06:27:03 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:27:03 INFO  : sub dir/hello world: Deleted
2022/01/13 06:27:04 DEBUG : nested/sub dir/file: md5 = c360658a50894fa5dc90bcac74a6e8e1 OK
2022/01/13 06:27:04 INFO  : nested/sub dir/file: Copied (new)
2022/01/13 06:27:04 INFO  : nested/sub dir/file: Deleted
2022/01/13 06:27:04 INFO  : sub dir: Removing directory
2022/01/13 06:27:04 INFO  : nested/sub dir: Removing directory
2022/01/13 06:27:04 INFO  : nested: Removing directory
2022/01/13 06:27:04 DEBUG : Local file system at /tmp/rclone1638522913: deleted 3 directories
--- PASS: TestMoveWithDeleteEmptySrcDirs (3.20s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:27:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:27:06 DEBUG : sub dir/hello world: md5 = 5e84bfa87d05fabb9c33e6be7323b055 OK
2022/01/13 06:27:06 INFO  : sub dir/hello world: Copied (new)
2022/01/13 06:27:06 INFO  : sub dir/hello world: Deleted
2022/01/13 06:27:07 DEBUG : nested/sub dir/file: md5 = 0be5e2020a4a0346bc6c57b2d6120cc0 OK
2022/01/13 06:27:07 INFO  : nested/sub dir/file: Copied (new)
2022/01/13 06:27:07 INFO  : nested/sub dir/file: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.11s)
=== RUN   TestMoveWithIgnoreExisting
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:27:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:27:11 DEBUG : existing-b: md5 = b9c1a25350d096b07efe3dd8003c5f17 OK
2022/01/13 06:27:11 INFO  : existing-b: Copied (new)
2022/01/13 06:27:11 INFO  : existing-b: Deleted
2022/01/13 06:27:11 DEBUG : existing: md5 = ef4c7d8efda7111bca62a93d34aec029 OK
2022/01/13 06:27:11 INFO  : existing: Copied (new)
2022/01/13 06:27:11 INFO  : existing: Deleted
2022/01/13 06:27:11 DEBUG : existing: Destination exists, skipping
2022/01/13 06:27:11 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set
2022/01/13 06:27:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:27:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:27:11 INFO  : There was nothing to transfer
--- PASS: TestMoveWithIgnoreExisting (3.90s)
=== RUN   TestServerSideMove
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nefepas2zotesaj9gakeqom2"
2022/01/13 06:27:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nefepas2zotesaj9gakeqom2.bin"
2022/01/13 06:27:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nefepas2zotesaj9gakeqom2"
2022/01/13 06:27:15 DEBUG : potato2: md5 = 62907e779ef0d1a96fe1a50c2a4af120 OK
2022/01/13 06:27:15 DEBUG : empty space: md5 = 3d4d4fac97a4843b7d703afb8d00fcdc OK
2022/01/13 06:27:17 DEBUG : potato3: md5 = 6931dcf5957a6a4f470b6e4457e2dbc3 OK
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9' -> Encrypted drive 'TestCryptSwift:rclone-test-nefepas2zotesaj9gakeqom2'
2022/01/13 06:27:18 DEBUG : empty space: md5 = 4b95a7e3169c1913024a302e8acbfccd OK
2022/01/13 06:27:18 DEBUG : potato3: md5 = 15b82f8d5c30eb2ce5964c22d4b52b95 OK
2022/01/13 06:27:18 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/13 06:27:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nefepas2zotesaj9gakeqom2': Waiting for checks to finish
2022/01/13 06:27:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:27:19 DEBUG : empty space: Unchanged skipping
2022/01/13 06:27:19 INFO  : empty space: Deleted
2022/01/13 06:27:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nefepas2zotesaj9gakeqom2': Waiting for transfers to finish
2022/01/13 06:27:19 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:19 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:20 INFO  : potato2: Deleted
2022/01/13 06:27:20 INFO  : potato3: Deleted
2022/01/13 06:27:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jebijay8ranobes9wororaj7"
2022/01/13 06:27:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jebijay8ranobes9wororaj7.bin"
2022/01/13 06:27:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jebijay8ranobes9wororaj7"
2022/01/13 06:27:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jebijay8ranobes9wororaj7': Waiting for checks to finish
2022/01/13 06:27:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jebijay8ranobes9wororaj7': Waiting for transfers to finish
2022/01/13 06:27:23 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:23 INFO  : potato2: Deleted
2022/01/13 06:27:24 INFO  : empty space: Copied (server-side copy)
2022/01/13 06:27:24 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:24 INFO  : potato3: Deleted
2022/01/13 06:27:24 INFO  : empty space: Deleted
2022/01/13 06:27:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jebijay8ranobes9wororaj7': Purge remote
2022/01/13 06:27:25 DEBUG : Waiting for deletions to finish
2022/01/13 06:27:25 INFO  : potato2.bin: Deleted
2022/01/13 06:27:26 INFO  : potato3.bin: Deleted
2022/01/13 06:27:26 INFO  : empty space.bin: Deleted
2022/01/13 06:27:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nefepas2zotesaj9gakeqom2': Purge remote
2022/01/13 06:27:26 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMove (13.45s)
=== RUN   TestServerSideMoveWithFilter
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ciruxey3yicegar4penudop6"
2022/01/13 06:27:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ciruxey3yicegar4penudop6.bin"
2022/01/13 06:27:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ciruxey3yicegar4penudop6"
2022/01/13 06:27:29 DEBUG : potato2: md5 = f7457b8980dc4dff1c0dee96e98ae8f0 OK
2022/01/13 06:27:29 DEBUG : empty space: md5 = 0f466ab8143d4a3474e89ffb76ffe910 OK
2022/01/13 06:27:31 DEBUG : potato3: md5 = 5294647d82d3eeb7f2561ffc612d1a96 OK
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9' -> Encrypted drive 'TestCryptSwift:rclone-test-ciruxey3yicegar4penudop6'
2022/01/13 06:27:32 DEBUG : empty space: md5 = 2e1fdfddabc4e0b043c1bdc7a6323dd1 OK
2022/01/13 06:27:33 DEBUG : potato3: md5 = ed37d98c00afa5e35bf23aa4eb61129f OK
2022/01/13 06:27:33 DEBUG : empty space: Excluded
2022/01/13 06:27:33 DEBUG : empty space: Excluded
2022/01/13 06:27:33 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/13 06:27:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciruxey3yicegar4penudop6': Waiting for checks to finish
2022/01/13 06:27:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciruxey3yicegar4penudop6': Waiting for transfers to finish
2022/01/13 06:27:35 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:35 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:35 INFO  : potato2: Deleted
2022/01/13 06:27:36 INFO  : potato3: Deleted
2022/01/13 06:27:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kanaxid7suzinub7guzihum5"
2022/01/13 06:27:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kanaxid7suzinub7guzihum5.bin"
2022/01/13 06:27:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kanaxid7suzinub7guzihum5"
2022/01/13 06:27:38 DEBUG : empty space: Excluded
2022/01/13 06:27:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanaxid7suzinub7guzihum5': Waiting for checks to finish
2022/01/13 06:27:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanaxid7suzinub7guzihum5': Waiting for transfers to finish
2022/01/13 06:27:39 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:39 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:40 INFO  : potato2: Deleted
2022/01/13 06:27:40 INFO  : potato3: Deleted
2022/01/13 06:27:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kanaxid7suzinub7guzihum5': Purge remote
2022/01/13 06:27:40 DEBUG : Waiting for deletions to finish
2022/01/13 06:27:41 INFO  : potato2.bin: Deleted
2022/01/13 06:27:42 INFO  : potato3.bin: Deleted
2022/01/13 06:27:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ciruxey3yicegar4penudop6': Purge remote
2022/01/13 06:27:42 DEBUG : Waiting for deletions to finish
2022/01/13 06:27:42 INFO  : empty space.bin: Deleted
--- PASS: TestServerSideMoveWithFilter (16.06s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-puqaqow7fegaged3nikodiy8"
2022/01/13 06:27:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-puqaqow7fegaged3nikodiy8.bin"
2022/01/13 06:27:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-puqaqow7fegaged3nikodiy8"
2022/01/13 06:27:45 DEBUG : potato2: md5 = d534617592b89eb63fc995ebc244b0d5 OK
2022/01/13 06:27:45 DEBUG : empty space: md5 = 9c5d733cb9b1aa187830d07c95170b98 OK
2022/01/13 06:27:46 DEBUG : potato3: md5 = b5f59bd4a819f9bc4fd84ab7d79ef028 OK
2022/01/13 06:27:46 DEBUG : tomatoDir: Making directory
    sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9' -> Encrypted drive 'TestCryptSwift:rclone-test-puqaqow7fegaged3nikodiy8'
2022/01/13 06:27:48 DEBUG : empty space: md5 = 21f632d7b557cdaab6850cb3ef79a318 OK
2022/01/13 06:27:49 DEBUG : potato3: md5 = d46f3983b0e74e803a1e997afdd840bd OK
2022/01/13 06:27:49 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2022/01/13 06:27:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-puqaqow7fegaged3nikodiy8': Waiting for checks to finish
2022/01/13 06:27:49 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:27:49 DEBUG : empty space: Unchanged skipping
2022/01/13 06:27:50 INFO  : empty space: Deleted
2022/01/13 06:27:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-puqaqow7fegaged3nikodiy8': Waiting for transfers to finish
2022/01/13 06:27:50 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:50 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:51 INFO  : potato2: Deleted
2022/01/13 06:27:51 INFO  : potato3: Deleted
2022/01/13 06:27:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ponoxos5zijoyiz5xoxayaj1"
2022/01/13 06:27:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ponoxos5zijoyiz5xoxayaj1.bin"
2022/01/13 06:27:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ponoxos5zijoyiz5xoxayaj1"
2022/01/13 06:27:53 DEBUG : tomatoDir: Making directory
2022/01/13 06:27:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponoxos5zijoyiz5xoxayaj1': Waiting for checks to finish
2022/01/13 06:27:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponoxos5zijoyiz5xoxayaj1': Waiting for transfers to finish
2022/01/13 06:27:54 INFO  : potato2: Copied (server-side copy)
2022/01/13 06:27:54 INFO  : potato3: Copied (server-side copy)
2022/01/13 06:27:54 INFO  : potato2: Deleted
2022/01/13 06:27:54 INFO  : empty space: Copied (server-side copy)
2022/01/13 06:27:54 INFO  : potato3: Deleted
2022/01/13 06:27:55 INFO  : empty space: Deleted
2022/01/13 06:27:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ponoxos5zijoyiz5xoxayaj1': Purge remote
2022/01/13 06:27:56 DEBUG : Waiting for deletions to finish
2022/01/13 06:27:57 INFO  : empty space.bin: Deleted
2022/01/13 06:27:57 INFO  : potato3.bin: Deleted
2022/01/13 06:27:57 INFO  : potato2.bin: Deleted
2022/01/13 06:27:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-puqaqow7fegaged3nikodiy8': Purge remote
2022/01/13 06:27:57 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (14.83s)
=== RUN   TestServerSideMoveOverlap
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:27:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/rclone-move-test"
2022/01/13 06:27:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/rclone-move-test.bin"
2022/01/13 06:27:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/rclone-move-test"
2022/01/13 06:27:59 DEBUG : potato2: md5 = cdc569fdd50e2f5d83bd3335b90cb8f4 OK
--- PASS: TestServerSideMoveOverlap (3.64s)
=== RUN   TestSyncOverlap
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/rclone-sync-test"
2022/01/13 06:28:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/rclone-sync-test.bin"
2022/01/13 06:28:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/rclone-sync-test"
--- PASS: TestSyncOverlap (1.51s)
=== RUN   TestSyncCompareDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:28:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/CompareDest"
2022/01/13 06:28:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/CompareDest.bin"
2022/01/13 06:28:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/CompareDest"
2022/01/13 06:28:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:06 DEBUG : one: md5 = 11e2d1b6cd7cce045ad955b3e0910a4e OK
2022/01/13 06:28:06 INFO  : one: Copied (new)
2022/01/13 06:28:06 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:06 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/13 06:28:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:07 DEBUG : one: md5 = fe283bf225b8240cb8d62051f1d33130 OK
2022/01/13 06:28:07 INFO  : one: Copied (replaced existing)
2022/01/13 06:28:07 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:07 DEBUG : dst/one: md5 = f08ee3a04b6bffc2aeaed10390f372cb OK
2022/01/13 06:28:08 DEBUG : CompareDest/one: md5 = 26ca35c194f87787f2a21b00efcfca37 OK
2022/01/13 06:28:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:08 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/13 06:28:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:08 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:08 INFO  : There was nothing to transfer
2022/01/13 06:28:09 DEBUG : CompareDest/two: md5 = e9a71ee6430d73cf050f5137841f1336 OK
2022/01/13 06:28:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:10 DEBUG : two: Destination found in --compare-dest, skipping
2022/01/13 06:28:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:10 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/13 06:28:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:10 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:10 INFO  : There was nothing to transfer
2022/01/13 06:28:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:10 DEBUG : two: Destination found in --compare-dest, skipping
2022/01/13 06:28:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:10 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/13 06:28:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:10 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:10 INFO  : There was nothing to transfer
    sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test
2022/01/13 06:28:11 DEBUG : two: Sizes differ (src 5 vs dst 3)
2022/01/13 06:28:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:11 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:11 DEBUG : one: Destination found in --compare-dest, skipping
2022/01/13 06:28:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:12 DEBUG : two: md5 = 981b5e18c854a5cf65ee57c0d2bee145 OK
2022/01/13 06:28:12 INFO  : two: Copied (new)
2022/01/13 06:28:12 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (11.78s)
=== RUN   TestSyncMultipleCompareDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:15 DEBUG : pre-dest1/1: md5 = 5154b4f9dad9a0d2a3a684cb4d6e6d8c OK
2022/01/13 06:28:16 DEBUG : pre-dest2/2: md5 = 9b2b81cf22d0962c59370fb2d4e9bf73 OK
2022/01/13 06:28:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dest"
2022/01/13 06:28:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dest.bin"
2022/01/13 06:28:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dest"
2022/01/13 06:28:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/pre-dest1"
2022/01/13 06:28:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/pre-dest1.bin"
2022/01/13 06:28:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/pre-dest1"
2022/01/13 06:28:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/pre-dest2"
2022/01/13 06:28:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/pre-dest2.bin"
2022/01/13 06:28:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/pre-dest2"
2022/01/13 06:28:20 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:20 DEBUG : 1: Destination found in --compare-dest, skipping
2022/01/13 06:28:21 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:21 DEBUG : 2: Destination found in --compare-dest, skipping
2022/01/13 06:28:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dest': Waiting for checks to finish
2022/01/13 06:28:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dest': Waiting for transfers to finish
2022/01/13 06:28:22 DEBUG : 3: md5 = d12f136265644ba8f2846b8abdc671b9 OK
2022/01/13 06:28:22 INFO  : 3: Copied (new)
2022/01/13 06:28:22 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (9.42s)
=== RUN   TestSyncCopyDest
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:28:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/CopyDest"
2022/01/13 06:28:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/CopyDest.bin"
2022/01/13 06:28:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/CopyDest"
2022/01/13 06:28:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:27 DEBUG : one: md5 = a0d5d2c7f2833defe3d11c7263aa4009 OK
2022/01/13 06:28:27 INFO  : one: Copied (new)
2022/01/13 06:28:27 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:28 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/13 06:28:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:28 DEBUG : one: md5 = af07cf3abdb2dad8b3d2a1f71dfb9a3e OK
2022/01/13 06:28:28 INFO  : one: Copied (replaced existing)
2022/01/13 06:28:28 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:29 DEBUG : dst/one: md5 = 3b0199c9afa44b1d87896a5026f5e625 OK
2022/01/13 06:28:31 DEBUG : CopyDest/one: md5 = c5491482a08ec805fd5e323e85474f1b OK
2022/01/13 06:28:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/BackupDir"
2022/01/13 06:28:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/BackupDir.bin"
2022/01/13 06:28:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/BackupDir"
2022/01/13 06:28:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:33 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:33 DEBUG : one: Sizes differ (src 5 vs dst 3)
2022/01/13 06:28:34 INFO  : one: Copied (server-side copy)
2022/01/13 06:28:34 INFO  : one: Deleted
2022/01/13 06:28:34 INFO  : one: Copied (server-side copy)
2022/01/13 06:28:34 DEBUG : one: Destination found in --copy-dest, using server-side copy
2022/01/13 06:28:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:34 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:36 DEBUG : CopyDest/two: md5 = 8ffd6ccad84cc6750ea8c49192e5b653 OK
2022/01/13 06:28:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:37 INFO  : two: Copied (server-side copy)
2022/01/13 06:28:37 DEBUG : two: Destination found in --copy-dest, using server-side copy
2022/01/13 06:28:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:37 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:37 DEBUG : one: Unchanged skipping
2022/01/13 06:28:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:37 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:38 DEBUG : two: Unchanged skipping
2022/01/13 06:28:38 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:38 DEBUG : one: Unchanged skipping
2022/01/13 06:28:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:38 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:38 INFO  : There was nothing to transfer
2022/01/13 06:28:39 DEBUG : CopyDest/three: md5 = 416de5d4a113e82cbfebf8ad13237e81 OK
2022/01/13 06:28:39 DEBUG : three: Sizes differ (src 7 vs dst 5)
2022/01/13 06:28:39 DEBUG : three: Destination not found in --copy-dest
2022/01/13 06:28:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:40 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:40 DEBUG : one: Unchanged skipping
2022/01/13 06:28:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:40 DEBUG : two: Unchanged skipping
2022/01/13 06:28:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:40 DEBUG : three: md5 = a23e7d582de8712ca86a2db508790b91 OK
2022/01/13 06:28:40 INFO  : three: Copied (new)
2022/01/13 06:28:40 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (20.03s)
=== RUN   TestSyncBackupDir
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:44 DEBUG : dst/one: md5 = 81ea0dbae40dbc7264c95847b76e357f OK
2022/01/13 06:28:44 DEBUG : dst/two: md5 = 350c76633709b8e230be6211aa57e2e8 OK
2022/01/13 06:28:45 DEBUG : dst/three.txt: md5 = 2a8f92b110e711969228d1ff95096292 OK
2022/01/13 06:28:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:28:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:28:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup.bin"
2022/01/13 06:28:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:28:47 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:28:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:47 DEBUG : two: Unchanged skipping
2022/01/13 06:28:48 INFO  : one: Copied (server-side copy)
2022/01/13 06:28:49 INFO  : one: Deleted
2022/01/13 06:28:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:49 DEBUG : one: md5 = eeceb69df8d4d8d0111ae3726cb1359c OK
2022/01/13 06:28:49 INFO  : one: Copied (new)
2022/01/13 06:28:49 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:50 INFO  : three.txt: Copied (server-side copy)
2022/01/13 06:28:50 INFO  : three.txt: Deleted
2022/01/13 06:28:50 INFO  : three.txt: Moved into backup dir
2022/01/13 06:28:51 DEBUG : dst/three.txt: md5 = 95b7ce785c2a99574ae0dd622a770b91 OK
2022/01/13 06:28:51 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:28:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:51 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:51 DEBUG : two: Unchanged skipping
2022/01/13 06:28:52 INFO  : one: Copied (server-side copy)
2022/01/13 06:28:52 INFO  : one: Deleted
2022/01/13 06:28:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:28:52 DEBUG : one: md5 = 60025b360a3f04a0c73319a90945ff53 OK
2022/01/13 06:28:52 INFO  : one: Copied (new)
2022/01/13 06:28:52 DEBUG : Waiting for deletions to finish
2022/01/13 06:28:53 INFO  : three.txt: Copied (server-side copy)
2022/01/13 06:28:53 INFO  : three.txt: Deleted
2022/01/13 06:28:53 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (10.35s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:28:54 DEBUG : dst/one: md5 = 2ee025a078ad7859411b40a9bc56cb8a OK
2022/01/13 06:28:55 DEBUG : dst/two: md5 = b131ae78b9465fcc62796a5dc40d16e8 OK
2022/01/13 06:28:56 DEBUG : dst/three.txt: md5 = 14361bafd1ad17c808afff64a3d6a85d OK
2022/01/13 06:28:56 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:28:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:28:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:28:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup.bin"
2022/01/13 06:28:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:28:59 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:28:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:28:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:28:59 DEBUG : two: Unchanged skipping
2022/01/13 06:28:59 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:28:59 INFO  : one: Deleted
2022/01/13 06:28:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:00 DEBUG : one: md5 = 349fa874b8f5db0066cd8d7457dc9d64 OK
2022/01/13 06:29:00 INFO  : one: Copied (new)
2022/01/13 06:29:00 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:01 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:01 INFO  : three.txt: Deleted
2022/01/13 06:29:01 INFO  : three.txt: Moved into backup dir
2022/01/13 06:29:02 DEBUG : dst/three.txt: md5 = a48e6a7d79c34841162e38281f1c055d OK
2022/01/13 06:29:02 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:29:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:29:02 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:02 DEBUG : two: Unchanged skipping
2022/01/13 06:29:03 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:29:03 INFO  : one: Deleted
2022/01/13 06:29:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:03 DEBUG : one: md5 = 25ea417ae65ccdb4e990490c7ce7e72d OK
2022/01/13 06:29:03 INFO  : one: Copied (new)
2022/01/13 06:29:03 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:04 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:04 INFO  : three.txt: Deleted
2022/01/13 06:29:04 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (12.43s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:29:07 DEBUG : dst/one: md5 = ed1bd85514b13e81f9d64294d6fc82aa OK
2022/01/13 06:29:08 DEBUG : dst/two: md5 = 0749eb375c339fffa6d0cda952b679e8 OK
2022/01/13 06:29:08 DEBUG : dst/three.txt: md5 = 3c3482f7ba0d2c40f430f3149fd2b4ac OK
2022/01/13 06:29:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:29:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:29:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup.bin"
2022/01/13 06:29:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/backup"
2022/01/13 06:29:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:29:11 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:29:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:11 DEBUG : two: Unchanged skipping
2022/01/13 06:29:13 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/13 06:29:13 INFO  : one: Deleted
2022/01/13 06:29:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:13 DEBUG : one: md5 = db94693144572dec7908b3169fe3bb6f OK
2022/01/13 06:29:13 INFO  : one: Copied (new)
2022/01/13 06:29:13 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:15 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/13 06:29:15 INFO  : three.txt: Deleted
2022/01/13 06:29:15 INFO  : three.txt: Moved into backup dir
2022/01/13 06:29:16 DEBUG : dst/three.txt: md5 = 6413883d5640f5aacdb6b62738b1af79 OK
2022/01/13 06:29:16 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:29:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:29:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:16 DEBUG : two: Unchanged skipping
2022/01/13 06:29:17 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/13 06:29:17 INFO  : one: Deleted
2022/01/13 06:29:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:17 DEBUG : one: md5 = fd35c63d547ce1ac0495495cf76a2ba4 OK
2022/01/13 06:29:17 INFO  : one: Copied (new)
2022/01/13 06:29:17 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:18 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/13 06:29:19 INFO  : three.txt: Deleted
2022/01/13 06:29:19 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (15.28s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:29:22 DEBUG : dst/one: md5 = 5f78a7934aa73b093b1054ee3b73a573 OK
2022/01/13 06:29:23 DEBUG : dst/two: md5 = da665d7caa86bf13fe16df1188bbb3cb OK
2022/01/13 06:29:24 DEBUG : dst/three.txt: md5 = a71bc328b360e06bbe2ec86554255d55 OK
2022/01/13 06:29:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:29:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:29:25 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:29:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:25 DEBUG : two: Unchanged skipping
2022/01/13 06:29:27 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:29:27 INFO  : one: Deleted
2022/01/13 06:29:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:28 DEBUG : one: md5 = db10b2d254cdd91d0cce28f8e272d647 OK
2022/01/13 06:29:28 INFO  : one: Copied (new)
2022/01/13 06:29:28 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:29 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:29 INFO  : three.txt: Deleted
2022/01/13 06:29:29 INFO  : three.txt: Moved into backup dir
2022/01/13 06:29:31 DEBUG : dst/three.txt: md5 = 6bdf273acf1c1565279a97cb8466de9a OK
2022/01/13 06:29:31 DEBUG : one.bak: Excluded
2022/01/13 06:29:31 DEBUG : three.txt.bak: Excluded
2022/01/13 06:29:31 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:29:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for checks to finish
2022/01/13 06:29:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:32 DEBUG : two: Unchanged skipping
2022/01/13 06:29:33 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:29:33 INFO  : one: Deleted
2022/01/13 06:29:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst': Waiting for transfers to finish
2022/01/13 06:29:33 DEBUG : one: md5 = e28ded6aa0b910d5b00071775b7c898f OK
2022/01/13 06:29:33 INFO  : one: Copied (new)
2022/01/13 06:29:33 DEBUG : Waiting for deletions to finish
2022/01/13 06:29:34 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:34 INFO  : three.txt: Deleted
2022/01/13 06:29:34 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (15.60s)
=== RUN   TestSyncSuffix
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:29:38 DEBUG : dst/one: md5 = b110c2f6c9861c18395fc8461fac01f1 OK
2022/01/13 06:29:39 DEBUG : dst/two: md5 = cdc8ff9526551249472c9cf6d8935a04 OK
2022/01/13 06:29:39 DEBUG : dst/three.txt: md5 = 7e1bc9d1165cce38f7e1ac753edb15b7 OK
2022/01/13 06:29:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:29:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:41 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:29:43 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:29:44 INFO  : one: Deleted
2022/01/13 06:29:44 DEBUG : one: md5 = 5782774e25f75f02049a9fdf21937034 OK
2022/01/13 06:29:44 INFO  : one: Copied (new)
2022/01/13 06:29:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:44 DEBUG : two: Unchanged skipping
2022/01/13 06:29:44 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/01/13 06:29:45 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:45 INFO  : three.txt: Deleted
2022/01/13 06:29:45 DEBUG : three.txt: md5 = f18c223a5707de5bc508a3b1671f858d OK
2022/01/13 06:29:45 INFO  : three.txt: Copied (new)
2022/01/13 06:29:46 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:29:48 INFO  : one: Copied (server-side copy) to: one.bak
2022/01/13 06:29:49 INFO  : one: Deleted
2022/01/13 06:29:49 DEBUG : one: md5 = 1aa9e58c0e4a35d79ab9948f3f715d12 OK
2022/01/13 06:29:49 INFO  : one: Copied (new)
2022/01/13 06:29:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:49 DEBUG : two: Unchanged skipping
2022/01/13 06:29:49 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/01/13 06:29:49 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2022/01/13 06:29:49 INFO  : three.txt: Deleted
2022/01/13 06:29:50 DEBUG : three.txt: md5 = 3c2c57f1ad2581d54930d8f5d45da960 OK
2022/01/13 06:29:50 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (15.84s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:29:53 DEBUG : dst/one: md5 = bc8a20af5a5683937c5847c97870498f OK
2022/01/13 06:29:55 DEBUG : dst/two: md5 = e6a8033905b05a6e240f2b8742aca5a5 OK
2022/01/13 06:29:55 DEBUG : dst/three.txt: md5 = 580f9abcc781d4e1de01dafdbff58da7 OK
2022/01/13 06:29:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst.bin"
2022/01/13 06:29:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-fesujiy7neracoc8yakireh9/dst"
2022/01/13 06:29:56 DEBUG : one: Sizes differ (src 4 vs dst 3)
2022/01/13 06:29:58 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/13 06:29:58 INFO  : one: Deleted
2022/01/13 06:29:58 DEBUG : one: md5 = 81184de3d6b524792e8cf36c396c65c7 OK
2022/01/13 06:29:58 INFO  : one: Copied (new)
2022/01/13 06:29:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:29:58 DEBUG : two: Unchanged skipping
2022/01/13 06:29:58 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2022/01/13 06:29:59 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/13 06:29:59 INFO  : three.txt: Deleted
2022/01/13 06:30:00 DEBUG : three.txt: md5 = b9287eac541b0c34f22dde2840a2bbab OK
2022/01/13 06:30:00 INFO  : three.txt: Copied (new)
2022/01/13 06:30:01 DEBUG : one: Sizes differ (src 5 vs dst 4)
2022/01/13 06:30:01 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2022/01/13 06:30:01 INFO  : one: Deleted
2022/01/13 06:30:02 DEBUG : one: md5 = d8441b0295be9c12cb83b326be1887e0 OK
2022/01/13 06:30:02 INFO  : one: Copied (new)
2022/01/13 06:30:02 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:30:02 DEBUG : two: Unchanged skipping
2022/01/13 06:30:02 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2022/01/13 06:30:02 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2022/01/13 06:30:03 INFO  : three.txt: Deleted
2022/01/13 06:30:03 DEBUG : three.txt: md5 = ab240a4a8bd80b4b6ee525651858360a OK
2022/01/13 06:30:03 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (14.00s)
=== RUN   TestSyncUTFNorm
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:30:08 DEBUG : Testêé: md5 = 251fb22adbbddde4c8f19fd34c810960 OK
2022/01/13 06:30:08 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2022/01/13 06:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:30:09 DEBUG : Testêé: md5 = bde5a1bb93335cfb2b6e9312ec07028c OK
2022/01/13 06:30:09 INFO  : Testêé: Copied (replaced existing) to: Testêé
2022/01/13 06:30:09 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (1.82s)
=== RUN   TestSyncImmutable
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:30:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:30:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:30:11 DEBUG : existing: md5 = fa5541ad77039a671d847d1c3464056c OK
2022/01/13 06:30:11 INFO  : existing: Copied (new)
2022/01/13 06:30:11 DEBUG : Waiting for deletions to finish
2022/01/13 06:30:12 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2022/01/13 06:30:12 ERROR : existing: Source and destination exist but do not match: immutable file modified
2022/01/13 06:30:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:30:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:30:12 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': not deleting files as there were IO errors
2022/01/13 06:30:12 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': not deleting directories as there were IO errors
--- PASS: TestSyncImmutable (3.50s)
=== RUN   TestSyncIgnoreCase
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:30:14 DEBUG : EXISTING: md5 = 23aa3ed8ffed0f87fae5faef819c7c64 OK
2022/01/13 06:30:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:30:14 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:30:14 DEBUG : existing: Unchanged skipping
2022/01/13 06:30:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:30:14 DEBUG : Waiting for deletions to finish
2022/01/13 06:30:14 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (3.40s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", 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-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", 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-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
    sync_test.go:1989: This test only runs on local
--- PASS: TestMaxTransfer (0.33s)
    --- SKIP: TestMaxTransfer/Hard (0.16s)
    --- SKIP: TestMaxTransfer/Soft (0.08s)
    --- SKIP: TestMaxTransfer/Cautious (0.09s)
=== RUN   TestSyncConcurrentDelete
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:30:17 DEBUG : both0: md5 = 159861185a5a23321071d4ac490baf53 OK
2022/01/13 06:30:17 DEBUG : only0: md5 = 8f102ec4aac6d80c17c5f40d10267412 OK
2022/01/13 06:30:19 DEBUG : both1: md5 = 1c2fa669a0a6a7f9a600f4d98e0caccd OK
2022/01/13 06:30:20 DEBUG : only1: md5 = f7870e0e82c48de340428d571539081b OK
2022/01/13 06:30:21 DEBUG : both2: md5 = ee24c41e821df7ef7a72d5dc0adb9f4c OK
2022/01/13 06:30:22 DEBUG : only2: md5 = d43f77faf6bfd6cb47b3b240acb2a1f9 OK
2022/01/13 06:30:24 DEBUG : both3: md5 = 79d7886777f6ee69ead2473a7fceda82 OK
2022/01/13 06:30:24 DEBUG : only3: md5 = 78890574c804f2d46a9769c43ea0bf80 OK
2022/01/13 06:30:26 DEBUG : both4: md5 = 7720f4def4bca81f7afa4b75e8ee49a4 OK
2022/01/13 06:30:28 DEBUG : only4: md5 = bff3d3cab3d5625afbb79e906c7786ee OK
2022/01/13 06:30:29 DEBUG : both5: md5 = 8916d528356a0152d337614f41b3dbe9 OK
2022/01/13 06:30:30 DEBUG : only5: md5 = 387ae9adb2b1326e2da19c6d161b3ef6 OK
2022/01/13 06:30:30 DEBUG : both6: md5 = 6b8adbe33f1018991540377b5b39f128 OK
2022/01/13 06:30:32 DEBUG : only6: md5 = 3fa760d5e8372d63537373ed383eea60 OK
2022/01/13 06:30:33 DEBUG : both7: md5 = 505a343e3d3da4fec46d76f96dd2e236 OK
2022/01/13 06:30:34 DEBUG : only7: md5 = 741ae9e843d5318bcf26f5b996188a3c OK
2022/01/13 06:30:35 DEBUG : both8: md5 = 5c8f9def33ac9a95e47000b43e0413ae OK
2022/01/13 06:30:36 DEBUG : only8: md5 = 49a5a5212b6bc08573640dc745652a1b OK
2022/01/13 06:30:36 DEBUG : both9: md5 = 51e5cd7b1a323f9503c41f10cc49435a OK
2022/01/13 06:30:37 DEBUG : only9: md5 = 05125c832eed2f8ac78ec7e4271dd7db OK
2022/01/13 06:30:38 DEBUG : both10: md5 = 60cb68b3e6d48d273b5721aa689f85b8 OK
2022/01/13 06:30:38 DEBUG : only10: md5 = aa0ce62de9c0c2692ebb7039c99ffd0f OK
2022/01/13 06:30:39 DEBUG : both11: md5 = 0507b5df2f9ee617d1622c88675f7e42 OK
2022/01/13 06:30:41 DEBUG : only11: md5 = aff0604e47019d52fe41c2f453994127 OK
2022/01/13 06:30:42 DEBUG : both12: md5 = 655ddb93eb147eda5dfd227ae28655ba OK
2022/01/13 06:30:42 DEBUG : only12: md5 = d5798c53ec8bd1cc20edb366a7199a55 OK
2022/01/13 06:30:43 DEBUG : both13: md5 = dec5c1dbb9ad856ba0b488f1570f8fa9 OK
2022/01/13 06:30:44 DEBUG : only13: md5 = aade9593aebc43d591765c86c47952c6 OK
2022/01/13 06:30:45 DEBUG : both14: md5 = 02e7f58a546f261245ef1ec853284926 OK
2022/01/13 06:30:46 DEBUG : only14: md5 = f506748ac6b55dc3f498685bf104e471 OK
2022/01/13 06:30:47 DEBUG : both15: md5 = 45f0b6d423d1ff12016d8829d05d94f3 OK
2022/01/13 06:30:48 DEBUG : only15: md5 = 3c02331721f2610e8ed4af427cf6d8f7 OK
2022/01/13 06:30:49 DEBUG : both16: md5 = bae03b4be7d4b21c8311a1c1ea09cd85 OK
2022/01/13 06:30:51 DEBUG : only16: md5 = 8beb6d50c2483e5ad1e15ebda8abab74 OK
2022/01/13 06:30:52 DEBUG : both17: md5 = 582923d0f6cd0db0a2e96ad28ed4fb95 OK
2022/01/13 06:30:52 DEBUG : only17: md5 = 9f38ce66352eb16bcb0481b9367aa546 OK
2022/01/13 06:30:54 DEBUG : both18: md5 = 3de46bd355f530d3fe6cd1b9dc326fad OK
2022/01/13 06:30:55 DEBUG : only18: md5 = b92523d0fdd8c6eeb6c7cc7161f5089e OK
2022/01/13 06:30:56 DEBUG : both19: md5 = a0e6a537962c4fecc5d62493b3ff3cab OK
2022/01/13 06:30:57 DEBUG : only19: md5 = 208185bba529e091342d649522cab754 OK
2022/01/13 06:31:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:31:01 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both0: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both1: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both10: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both11: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both12: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both13: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both14: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both16: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both17: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both18: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both19: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both2: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both3: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both4: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both5: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both6: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both7: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both9: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both15: Unchanged skipping
2022/01/13 06:31:01 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:01 DEBUG : both8: Unchanged skipping
2022/01/13 06:31:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:31:01 DEBUG : Waiting for deletions to finish
2022/01/13 06:31:01 INFO  : only12: Deleted
2022/01/13 06:31:01 INFO  : only3: Deleted
2022/01/13 06:31:01 INFO  : only17: Deleted
2022/01/13 06:31:02 INFO  : only13: Deleted
2022/01/13 06:31:02 INFO  : only5: Deleted
2022/01/13 06:31:02 INFO  : only8: Deleted
2022/01/13 06:31:02 INFO  : only0: Deleted
2022/01/13 06:31:02 INFO  : only14: Deleted
2022/01/13 06:31:03 INFO  : only11: Deleted
2022/01/13 06:31:03 INFO  : only2: Deleted
2022/01/13 06:31:03 INFO  : only7: Deleted
2022/01/13 06:31:03 INFO  : only15: Deleted
2022/01/13 06:31:03 INFO  : only4: Deleted
2022/01/13 06:31:03 INFO  : only10: Deleted
2022/01/13 06:31:04 INFO  : only16: Deleted
2022/01/13 06:31:04 INFO  : only1: Deleted
2022/01/13 06:31:04 INFO  : only18: Deleted
2022/01/13 06:31:04 INFO  : only19: Deleted
2022/01/13 06:31:05 INFO  : only9: Deleted
2022/01/13 06:31:05 INFO  : only6: Deleted
2022/01/13 06:31:05 INFO  : There was nothing to transfer
--- PASS: TestSyncConcurrentDelete (60.00s)
=== RUN   TestSyncConcurrentTruncate
    run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9'", Local "Local file system at /tmp/rclone1638522913", Modify Window "1ns"
2022/01/13 06:31:18 DEBUG : both0: md5 = 727522ae385f66a38241a1b5bd1983d1 OK
2022/01/13 06:31:18 DEBUG : only0: md5 = 2583794a43396efcfee8e996b920aaf3 OK
2022/01/13 06:31:20 DEBUG : both1: md5 = d88f3dfdfe475446505e2dcf53ffec4b OK
2022/01/13 06:31:21 DEBUG : only1: md5 = b863e3e2f4645bc94b9ea4c101d4ad25 OK
2022/01/13 06:31:23 DEBUG : both2: md5 = a0cfb1b463364b9197aa9397689143ca OK
2022/01/13 06:31:24 DEBUG : only2: md5 = e3eba03f90d01dfc23c42119cd5148ff OK
2022/01/13 06:31:24 DEBUG : both3: md5 = 655f884e78cc371328a9fa53cca11e05 OK
2022/01/13 06:31:25 DEBUG : only3: md5 = 1797fd3f968e030284a2674dc38992c8 OK
2022/01/13 06:31:26 DEBUG : both4: md5 = 1b9ad3199454523a66dcae7dd59ae254 OK
2022/01/13 06:31:27 DEBUG : only4: md5 = 1ddda50626eb748a43f2d118d11bb847 OK
2022/01/13 06:31:28 DEBUG : both5: md5 = 07ed2892f29be8b2f7d90a6b8945a9b4 OK
2022/01/13 06:31:28 DEBUG : only5: md5 = 91fa48e68ff049dfa3327966c905cc23 OK
2022/01/13 06:31:28 DEBUG : both6: md5 = aa5effecea48af6282efdbc550ea5465 OK
2022/01/13 06:31:30 DEBUG : only6: md5 = 3b3ec9a32a7b595c58d7c14e8c81ca8b OK
2022/01/13 06:31:32 DEBUG : both7: md5 = 2263baf2134d5845518a3a8ab92b6707 OK
2022/01/13 06:31:32 DEBUG : only7: md5 = b0d6e977eb5eeefe6b73b413b0849566 OK
2022/01/13 06:31:33 DEBUG : both8: md5 = c70bc828f3222fb5fc1a8510aad41926 OK
2022/01/13 06:31:34 DEBUG : only8: md5 = 09b6cf7b758e2c7c90fe4892d1a947ba OK
2022/01/13 06:31:34 DEBUG : both9: md5 = 6a2ff449c23988d686b3dd7b520d0055 OK
2022/01/13 06:31:35 DEBUG : only9: md5 = 3029d213a531d4e65a896485987f6f9b OK
2022/01/13 06:31:35 DEBUG : both10: md5 = a146a5adbc4ad8980552e2e1419e3be0 OK
2022/01/13 06:31:36 DEBUG : only10: md5 = 95371ed24f9e9493a0e03ca194490baf OK
2022/01/13 06:31:36 DEBUG : both11: md5 = 7a81a224ff4273b4efd5a8d79811999b OK
2022/01/13 06:31:37 DEBUG : only11: md5 = df230cfcb69a883900ae0c73cea56773 OK
2022/01/13 06:31:38 DEBUG : both12: md5 = f5a40eac71c384ec62e13d7f730356fc OK
2022/01/13 06:31:39 DEBUG : only12: md5 = 1f73649ad2dc950f93f2dbfa4c5b7e97 OK
2022/01/13 06:31:40 DEBUG : both13: md5 = d2354fa17421cec671dbe28d17f1ee43 OK
2022/01/13 06:31:41 DEBUG : only13: md5 = 05ca3039393814cf01f9347b32adce34 OK
2022/01/13 06:31:41 DEBUG : both14: md5 = 6cca4a7a11f2a9f45a9451be3d5414b5 OK
2022/01/13 06:31:42 DEBUG : only14: md5 = 63b3dd735a3962c8d112ac725cac61c7 OK
2022/01/13 06:31:43 DEBUG : both15: md5 = d1bdf177ae5dc9bd9e9c60c5688570a2 OK
2022/01/13 06:31:44 DEBUG : only15: md5 = 6f005eae562c1fbc288a83622d08e09f OK
2022/01/13 06:31:45 DEBUG : both16: md5 = fb7f8235583312205ad9ec5f6741bcf6 OK
2022/01/13 06:31:47 DEBUG : only16: md5 = a7c4edb7e3b6a1d78dba36255328d02c OK
2022/01/13 06:31:47 DEBUG : both17: md5 = 841bcb1238d6f3dc6de4b0c88d744c97 OK
2022/01/13 06:31:47 DEBUG : only17: md5 = d497208b5363a43956e626b2fbf89dcc OK
2022/01/13 06:31:48 DEBUG : both18: md5 = c9f8c38bd7151bc8f849ffede845e5ac OK
2022/01/13 06:31:49 DEBUG : only18: md5 = 5f906f4f8b4d4826115ed2365b01ad81 OK
2022/01/13 06:31:50 DEBUG : both19: md5 = a2601cc4c922ec115a18f5138c1ef962 OK
2022/01/13 06:31:51 DEBUG : only19: md5 = fd93970bf6653fd9b70383830242757b OK
2022/01/13 06:31:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for checks to finish
2022/01/13 06:31:53 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both1: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both10: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both0: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both12: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both13: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both14: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both16: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both17: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both19: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both11: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both2: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both3: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both4: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both15: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both18: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both5: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both6: Unchanged skipping
2022/01/13 06:31:53 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2022/01/13 06:31:53 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both9: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both7: Unchanged skipping
2022/01/13 06:31:53 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2022/01/13 06:31:53 DEBUG : both8: Unchanged skipping
2022/01/13 06:31:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Waiting for transfers to finish
2022/01/13 06:31:53 DEBUG : only0: md5 = 0b87f9a4abe5e54a9473508f56095886 OK
2022/01/13 06:31:53 INFO  : only0: Copied (replaced existing)
2022/01/13 06:31:53 DEBUG : only10: md5 = 5c65c054852d69343f211e581e3e948a OK
2022/01/13 06:31:53 INFO  : only10: Copied (replaced existing)
2022/01/13 06:31:54 DEBUG : only12: md5 = 25d0da94c5c92aa05b2fd568150b3f9a OK
2022/01/13 06:31:54 INFO  : only12: Copied (replaced existing)
2022/01/13 06:31:54 DEBUG : only13: md5 = 2eb9085f065bb07a6197db9c490936f7 OK
2022/01/13 06:31:54 INFO  : only13: Copied (replaced existing)
2022/01/13 06:31:54 DEBUG : only14: md5 = 973d411678e4f779c568b71c92cd7e82 OK
2022/01/13 06:31:54 INFO  : only14: Copied (replaced existing)
2022/01/13 06:31:54 DEBUG : only1: md5 = 0d12adf24c9a247feeb4264330e14fb3 OK
2022/01/13 06:31:54 INFO  : only1: Copied (replaced existing)
2022/01/13 06:31:54 DEBUG : only11: md5 = 04fd0af3cfd6eddead596d9a98087b6a OK
2022/01/13 06:31:54 INFO  : only11: Copied (replaced existing)
2022/01/13 06:31:55 DEBUG : only17: md5 = 3c2322ba50959d36ed03367cfcffe9e3 OK
2022/01/13 06:31:55 INFO  : only17: Copied (replaced existing)
2022/01/13 06:31:55 DEBUG : only15: md5 = be2b4df2171ed00500711b94d74f3c77 OK
2022/01/13 06:31:55 INFO  : only15: Copied (replaced existing)
2022/01/13 06:31:55 DEBUG : only16: md5 = 1c72c954098a2deb136e29a856574d47 OK
2022/01/13 06:31:55 INFO  : only16: Copied (replaced existing)
2022/01/13 06:31:55 DEBUG : only18: md5 = ae638899849771ea2fc02affd468a90a OK
2022/01/13 06:31:55 INFO  : only18: Copied (replaced existing)
2022/01/13 06:31:56 DEBUG : only3: md5 = 1b02e0d1c71603ef1267966df27cd3c4 OK
2022/01/13 06:31:56 INFO  : only3: Copied (replaced existing)
2022/01/13 06:31:56 DEBUG : only5: md5 = 216bdda86b711c8e83a28b7d55d70370 OK
2022/01/13 06:31:56 INFO  : only5: Copied (replaced existing)
2022/01/13 06:31:56 DEBUG : only19: md5 = ee900b80585d5b5301806ec3c573aa3a OK
2022/01/13 06:31:56 INFO  : only19: Copied (replaced existing)
2022/01/13 06:31:56 DEBUG : only7: md5 = 92ece6c9aea49033941b4e994fd8a525 OK
2022/01/13 06:31:56 INFO  : only7: Copied (replaced existing)
2022/01/13 06:31:57 DEBUG : only2: md5 = b1ccd44b472b725c14b3a867b3fd0e0a OK
2022/01/13 06:31:57 INFO  : only2: Copied (replaced existing)
2022/01/13 06:31:57 DEBUG : only8: md5 = f5c7dd734e7ac3b72c5671476259c908 OK
2022/01/13 06:31:57 INFO  : only8: Copied (replaced existing)
2022/01/13 06:31:57 DEBUG : only4: md5 = 362e07e23829c80bea2ee794b9da5ce4 OK
2022/01/13 06:31:57 INFO  : only4: Copied (replaced existing)
2022/01/13 06:31:58 DEBUG : only6: md5 = e02cec43f6891a44a8b57d47709d0b29 OK
2022/01/13 06:31:58 INFO  : only6: Copied (replaced existing)
2022/01/13 06:31:58 DEBUG : only9: md5 = 55d49578f7425fb6eaa8bfa239ab23ce OK
2022/01/13 06:31:58 INFO  : only9: Copied (replaced existing)
2022/01/13 06:31:58 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncConcurrentTruncate (65.07s)
PASS
2022/01/13 06:32:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-fesujiy7neracoc8yakireh9': Purge remote
2022/01/13 06:32:21 DEBUG : Waiting for deletions to finish
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 7m7.383919584s (try 1/5)