"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5)
2021/07/25 05:55:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9"
2021/07/25 05:55:12 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2021/07/25 05:55:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9.bin"
2021/07/25 05:55:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9"
2021/07/25 05:55:13 DEBUG : Creating backend with remote "/tmp/rclone162028006"
=== RUN   TestPipe
--- PASS: TestPipe (0.00s)
=== RUN   TestPipeConcurrent
--- PASS: TestPipeConcurrent (0.21s)
=== RUN   TestPipeOrderBy
=== RUN   TestPipeOrderBy/#00
=== RUN   TestPipeOrderBy/size
=== RUN   TestPipeOrderBy/name
=== RUN   TestPipeOrderBy/modtime
=== RUN   TestPipeOrderBy/size,ascending
=== RUN   TestPipeOrderBy/name,asc
=== RUN   TestPipeOrderBy/modtime,ascending
=== RUN   TestPipeOrderBy/size,descending
=== RUN   TestPipeOrderBy/name,desc
=== RUN   TestPipeOrderBy/modtime,descending
=== RUN   TestPipeOrderBy/size,mixed,50
=== RUN   TestPipeOrderBy/size,mixed,51
--- PASS: TestPipeOrderBy (0.00s)
    --- PASS: TestPipeOrderBy/#00 (0.00s)
    --- PASS: TestPipeOrderBy/size (0.00s)
    --- PASS: TestPipeOrderBy/name (0.00s)
    --- PASS: TestPipeOrderBy/modtime (0.00s)
    --- PASS: TestPipeOrderBy/size,ascending (0.00s)
    --- PASS: TestPipeOrderBy/name,asc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,ascending (0.00s)
    --- PASS: TestPipeOrderBy/size,descending (0.00s)
    --- PASS: TestPipeOrderBy/name,desc (0.00s)
    --- PASS: TestPipeOrderBy/modtime,descending (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s)
    --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s)
=== RUN   TestNewLess
=== RUN   TestNewLess/blankOK
=== RUN   TestNewLess/tooManyParts
=== RUN   TestNewLess/tooManyParts2
=== RUN   TestNewLess/badMixed
=== RUN   TestNewLess/unknownComparison
=== RUN   TestNewLess/unknownSortDirection
=== RUN   TestNewLess/size
=== RUN   TestNewLess/name
=== RUN   TestNewLess/modtime
=== RUN   TestNewLess/size,ascending
=== RUN   TestNewLess/name,asc
=== RUN   TestNewLess/modtime,ascending
=== RUN   TestNewLess/size,descending
=== RUN   TestNewLess/name,desc
=== RUN   TestNewLess/modtime,descending
=== RUN   TestNewLess/modtime,mixed
=== RUN   TestNewLess/modtime,mixed,30
--- PASS: TestNewLess (0.00s)
    --- PASS: TestNewLess/blankOK (0.00s)
    --- PASS: TestNewLess/tooManyParts (0.00s)
    --- PASS: TestNewLess/tooManyParts2 (0.00s)
    --- PASS: TestNewLess/badMixed (0.00s)
    --- PASS: TestNewLess/unknownComparison (0.00s)
    --- PASS: TestNewLess/unknownSortDirection (0.00s)
    --- PASS: TestNewLess/size (0.00s)
    --- PASS: TestNewLess/name (0.00s)
    --- PASS: TestNewLess/modtime (0.00s)
    --- PASS: TestNewLess/size,ascending (0.00s)
    --- PASS: TestNewLess/name,asc (0.00s)
    --- PASS: TestNewLess/modtime,ascending (0.00s)
    --- PASS: TestNewLess/size,descending (0.00s)
    --- PASS: TestNewLess/name,desc (0.00s)
    --- PASS: TestNewLess/modtime,descending (0.00s)
    --- PASS: TestNewLess/modtime,mixed (0.00s)
    --- PASS: TestNewLess/modtime,mixed,30 (0.00s)
=== RUN   TestRcCopy
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcCopy (0.00s)
=== RUN   TestRcMove
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcMove (0.00s)
=== RUN   TestRcSync
    rc_test.go:16: Skipping test on non local remote
--- SKIP: TestRcSync (0.00s)
=== RUN   TestCopyWithDryRun
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:13 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11)
2021/07/25 05:55:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
--- PASS: TestCopyWithDryRun (0.32s)
=== RUN   TestCopy
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:17 DEBUG : sub dir/hello world: md5 = 115096c6f753f8fbf56b4c62260beb11 OK
2021/07/25 05:55:17 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopy (4.04s)
=== RUN   TestCopyMissingDirectory
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:18 DEBUG : Creating backend with remote "/non-existing"
2021/07/25 05:55:18 ERROR : Local file system at /non-existing: error reading source root directory: directory not found
2021/07/25 05:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
--- PASS: TestCopyMissingDirectory (0.20s)
=== RUN   TestCopyNoTraverse
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:20 DEBUG : sub dir/hello world: md5 = 0d1d4fbe4a2d31e89ec6d6b18eb721b3 OK
2021/07/25 05:55:20 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyNoTraverse (2.27s)
=== RUN   TestCopyCheckFirst
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:20 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Running all checks before starting transfers
2021/07/25 05:55:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:20 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Checks finished, now starting transfers
2021/07/25 05:55:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:22 DEBUG : sub dir/hello world: md5 = 63790a624f3abea14bdf960db3088e33 OK
2021/07/25 05:55:22 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyCheckFirst (2.90s)
=== RUN   TestSyncNoTraverse
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:23 ERROR : Ignoring --no-traverse with sync
2021/07/25 05:55:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:25 DEBUG : sub dir/hello world: md5 = 9a0f50c842a1edd69711eb8cb2a321f9 OK
2021/07/25 05:55:25 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:55:25 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncNoTraverse (2.73s)
=== RUN   TestCopyWithDepth
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:27 DEBUG : hello world2: md5 = 8fb638fe35dbf22c3b679a2e474267f2 OK
2021/07/25 05:55:27 INFO  : hello world2: Copied (new)
--- PASS: TestCopyWithDepth (1.37s)
=== RUN   TestCopyWithFilesFrom
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:27 DEBUG : hello world2: Excluded
2021/07/25 05:55:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:28 DEBUG : potato2: md5 = b32bcefb3d479d5be346d91face37e7f OK
2021/07/25 05:55:28 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFrom (0.88s)
=== RUN   TestCopyWithFilesFromAndNoTraverse
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:28 DEBUG : potato2: md5 = 369ff6501edb49c4c864d0f054bb7c88 OK
2021/07/25 05:55:28 INFO  : potato2: Copied (new)
--- PASS: TestCopyWithFilesFromAndNoTraverse (0.64s)
=== RUN   TestCopyEmptyDirectories
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:29 DEBUG : sub dir2: Making directory
2021/07/25 05:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:30 DEBUG : sub dir/hello world: md5 = a0afe838cf05274594c09adb071bc0d9 OK
2021/07/25 05:55:30 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:55:30 DEBUG : sub dir2: Making directory
2021/07/25 05:55:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': copied 1 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestCopyEmptyDirectories (3.46s)
=== RUN   TestMoveEmptyDirectories
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:32 DEBUG : sub dir2: Making directory
2021/07/25 05:55:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:35 DEBUG : sub dir/hello world: md5 = dd427d7ed5c66926fb4df6bcfabbe8a3 OK
2021/07/25 05:55:35 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:55:35 INFO  : sub dir/hello world: Deleted
2021/07/25 05:55:35 DEBUG : sub dir: Making directory
2021/07/25 05:55:35 DEBUG : sub dir2: Making directory
2021/07/25 05:55:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': copied 2 directories
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestMoveEmptyDirectories (4.16s)
=== RUN   TestSyncEmptyDirectories
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:36 DEBUG : sub dir2: Making directory
2021/07/25 05:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:39 DEBUG : sub dir/hello world: md5 = 1fdadcb51e286ae6990413f7b090a23f OK
2021/07/25 05:55:39 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:55:39 DEBUG : sub dir2: Making directory
2021/07/25 05:55:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': copied 1 directories
2021/07/25 05:55:39 DEBUG : Waiting for deletions to finish
    fstest.go:251: Filtering empty directory "sub dir2"
--- PASS: TestSyncEmptyDirectories (4.40s)
=== RUN   TestServerSideCopy
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:42 DEBUG : sub dir/hello world: md5 = aed8bcf4c785adb2f485ecbe4fea3a7c OK
2021/07/25 05:55:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rotegoh8fejoxop0fecisel0"
2021/07/25 05:55:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rotegoh8fejoxop0fecisel0.bin"
2021/07/25 05:55:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rotegoh8fejoxop0fecisel0"
    sync_test.go:281: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9' -> Encrypted drive 'TestCryptSwift:rclone-test-rotegoh8fejoxop0fecisel0'
2021/07/25 05:55:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rotegoh8fejoxop0fecisel0': Waiting for checks to finish
2021/07/25 05:55:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rotegoh8fejoxop0fecisel0': Waiting for transfers to finish
2021/07/25 05:55:45 INFO  : sub dir/hello world: Copied (server-side copy)
2021/07/25 05:55:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rotegoh8fejoxop0fecisel0': Purge remote
2021/07/25 05:55:45 DEBUG : Waiting for deletions to finish
2021/07/25 05:55:45 INFO  : sub dir/hello world.bin: Deleted
--- PASS: TestServerSideCopy (5.74s)
=== RUN   TestCopyAfterDelete
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:49 DEBUG : sub dir/hello world: md5 = 30c4075e72b8460ca570a192cf380d72 OK
2021/07/25 05:55:49 ERROR : : error listing: directory not found
2021/07/25 05:55:49 DEBUG : Local file system at /tmp/rclone162028006: Making directory
2021/07/25 05:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
--- PASS: TestCopyAfterDelete (5.34s)
=== RUN   TestCopyRedownload
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:54 DEBUG : sub dir/hello world: md5 = 9a677c5cdf5afb7fc83e54d3ed28e987 OK
2021/07/25 05:55:54 DEBUG : Local file system at /tmp/rclone162028006: Waiting for checks to finish
2021/07/25 05:55:54 DEBUG : Local file system at /tmp/rclone162028006: Waiting for transfers to finish
2021/07/25 05:55:55 INFO  : sub dir/hello world: Copied (new)
--- PASS: TestCopyRedownload (4.83s)
=== RUN   TestSyncBasedOnCheckSum
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:57 DEBUG : check sum: md5 = 17beec0c266efd9e90b3581558d68d87 OK
2021/07/25 05:55:57 INFO  : check sum: Copied (new)
2021/07/25 05:55:57 DEBUG : Waiting for deletions to finish
2021/07/25 05:55:57 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only
2021/07/25 05:55:57 DEBUG : check sum: Size of src and dst objects identical
2021/07/25 05:55:57 DEBUG : check sum: Unchanged skipping
2021/07/25 05:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:57 DEBUG : Waiting for deletions to finish
2021/07/25 05:55:57 INFO  : There was nothing to transfer
--- PASS: TestSyncBasedOnCheckSum (0.91s)
=== RUN   TestSyncSizeOnly
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:55:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:55:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:55:59 DEBUG : sizeonly: md5 = 6d4349cab548af74b2bf3c19c3427036 OK
2021/07/25 05:55:59 INFO  : sizeonly: Copied (new)
2021/07/25 05:55:59 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:00 DEBUG : sizeonly: Sizes identical
2021/07/25 05:56:00 DEBUG : sizeonly: Unchanged skipping
2021/07/25 05:56:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:00 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:00 INFO  : There was nothing to transfer
--- PASS: TestSyncSizeOnly (2.69s)
=== RUN   TestSyncIgnoreSize
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:01 DEBUG : ignore-size: md5 = 2a8488841f6310c1ebe2fa3fd7023ef7 OK
2021/07/25 05:56:01 INFO  : ignore-size: Copied (new)
2021/07/25 05:56:01 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:01 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:01 DEBUG : ignore-size: Unchanged skipping
2021/07/25 05:56:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:01 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:01 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreSize (0.92s)
=== RUN   TestSyncIgnoreTimes
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:02 DEBUG : existing: md5 = f4dba006dd58c07cf644458a4b8312df OK
2021/07/25 05:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:02 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:02 DEBUG : existing: Unchanged skipping
2021/07/25 05:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:02 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:02 INFO  : There was nothing to transfer
2021/07/25 05:56:02 DEBUG : existing: Transferring unconditionally as --ignore-times is in use
2021/07/25 05:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:02 DEBUG : existing: md5 = a66eb67adf0ebdf8247f2150cadb3199 OK
2021/07/25 05:56:02 INFO  : existing: Copied (replaced existing)
2021/07/25 05:56:02 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncIgnoreTimes (1.13s)
=== RUN   TestSyncIgnoreExisting
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:03 DEBUG : existing: md5 = d62ed591f1332c0f7cfcfb60923c1a65 OK
2021/07/25 05:56:03 INFO  : existing: Copied (new)
2021/07/25 05:56:03 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:03 DEBUG : existing: Destination exists, skipping
2021/07/25 05:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:03 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:03 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreExisting (0.87s)
=== RUN   TestSyncIgnoreErrors
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:04 DEBUG : b/potato: md5 = c686240a79e8a0ecc3ca5a46f08271bf OK
2021/07/25 05:56:06 DEBUG : c/non empty space: md5 = 975bb65a7b42bdfbaa5c4cc3ddaa0976 OK
2021/07/25 05:56:06 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2021/07/25 05:56:07 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:07 DEBUG : c/non empty space: Unchanged skipping
2021/07/25 05:56:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:07 DEBUG : a/potato2: md5 = 2b27f6bd0974b48d145cde76c54aef85 OK
2021/07/25 05:56:07 INFO  : a/potato2: Copied (new)
2021/07/25 05:56:07 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:07 INFO  : b/potato: Deleted
--- PASS: TestSyncIgnoreErrors (6.39s)
=== RUN   TestSyncAfterChangingModtimeOnly
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:10 DEBUG : empty space: md5 = a3e01270a537ea32c032e6161ee5c515 OK
2021/07/25 05:56:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:10 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2021/07/25 05:56:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:10 NOTICE: empty space: Skipped copy as --dry-run is set (size 1)
2021/07/25 05:56:10 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:10 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2021/07/25 05:56:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:10 DEBUG : empty space: md5 = 34a979f5f0157b68831d95c1334cf53f OK
2021/07/25 05:56:10 INFO  : empty space: Copied (replaced existing)
2021/07/25 05:56:10 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingModtimeOnly (1.14s)
=== RUN   TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:591: Can't check this if no hashes supported
--- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.02s)
=== RUN   TestSyncDoesntUpdateModtime
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:13 DEBUG : foo: md5 = e3f02e673d6d0b3696aa8b30bd8848b3 OK
2021/07/25 05:56:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:14 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC
2021/07/25 05:56:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:15 DEBUG : foo: md5 = 5bf44454408e5293fd94216c4d47e1ea OK
2021/07/25 05:56:15 INFO  : foo: Copied (replaced existing)
2021/07/25 05:56:15 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncDoesntUpdateModtime (5.75s)
=== RUN   TestSyncAfterAddingAFile
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:17 DEBUG : empty space: md5 = a1f62a21ecebb7ba5acb502507c2ee9c OK
2021/07/25 05:56:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:17 DEBUG : empty space: Unchanged skipping
2021/07/25 05:56:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:20 DEBUG : potato: md5 = f713cfad71a6674fb421b024e08a1c58 OK
2021/07/25 05:56:20 INFO  : potato: Copied (new)
2021/07/25 05:56:20 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterAddingAFile (4.95s)
=== RUN   TestSyncAfterChangingFilesSizeOnly
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:23 DEBUG : potato: md5 = 89a5e82f3882b9ac388c1dc894f1bd03 OK
2021/07/25 05:56:23 DEBUG : potato: Sizes differ (src 21 vs dst 60)
2021/07/25 05:56:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:24 DEBUG : potato: md5 = 9fd7e4fb913c052df5a79b5637bba2ed OK
2021/07/25 05:56:24 INFO  : potato: Copied (replaced existing)
2021/07/25 05:56:24 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingFilesSizeOnly (4.34s)
=== RUN   TestSyncAfterChangingContentsOnly
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:27 DEBUG : potato: md5 = aa9cdeac7a2eddf8d014bd8f948e0a7c OK
2021/07/25 05:56:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:28 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC
2021/07/25 05:56:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:31 DEBUG : potato: md5 = 59f52cf403869c2bccfb2ecb8e8c3db1 OK
2021/07/25 05:56:31 INFO  : potato: Copied (replaced existing)
2021/07/25 05:56:31 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncAfterChangingContentsOnly (6.00s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileDryRun
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:33 DEBUG : potato: md5 = 40d0ae0d08a39984f9e5d1e0a9ec3eb8 OK
2021/07/25 05:56:34 DEBUG : empty space: md5 = c661be0a354624fa29a268d0941290f1 OK
2021/07/25 05:56:34 NOTICE: potato2: Skipped copy as --dry-run is set (size 60)
2021/07/25 05:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:34 DEBUG : empty space: Unchanged skipping
2021/07/25 05:56:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:34 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:34 NOTICE: potato: Skipped delete as --dry-run is set (size 21)
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (7.69s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFile
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:42 DEBUG : potato: md5 = 1fa89c86611e8cd314e7da151f6a71fd OK
2021/07/25 05:56:42 DEBUG : empty space: md5 = c16251faed99e3c5d85a2402dd2e0786 OK
2021/07/25 05:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:42 DEBUG : empty space: Unchanged skipping
2021/07/25 05:56:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:42 DEBUG : potato2: md5 = fce3a0b641b934d282bdce20083af417 OK
2021/07/25 05:56:42 INFO  : potato2: Copied (new)
2021/07/25 05:56:42 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:43 INFO  : potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFile (4.35s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDir
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:44 DEBUG : b/potato: md5 = 912da20de4fe4b725579ec15d1f969a2 OK
2021/07/25 05:56:45 DEBUG : c/non empty space: md5 = c23e62580f55b3ad688185fca811d61c OK
2021/07/25 05:56:45 DEBUG : d: Making directory
2021/07/25 05:56:45 DEBUG : d/e: Making directory
    fstest.go:251: Filtering empty directory "d"
    fstest.go:251: Filtering empty directory "d/e"
2021/07/25 05:56:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:45 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:45 DEBUG : c/non empty space: Unchanged skipping
2021/07/25 05:56:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:45 DEBUG : a/potato2: md5 = d120a047ca931b6730169de30f875ae4 OK
2021/07/25 05:56:45 INFO  : a/potato2: Copied (new)
2021/07/25 05:56:45 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:45 INFO  : b/potato: Deleted
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.98s)
=== RUN   TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:47 DEBUG : b/potato: md5 = 907f2ab4bc1793bd961d15ed7a940f74 OK
2021/07/25 05:56:48 DEBUG : c/non empty space: md5 = 04b6d8608349a0526a0466fa73320d8e OK
2021/07/25 05:56:48 DEBUG : d: Making directory
    fstest.go:251: Filtering empty directory "d"
2021/07/25 05:56:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:48 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:48 DEBUG : c/non empty space: Unchanged skipping
2021/07/25 05:56:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:48 DEBUG : a/potato2: md5 = 25c9598a2b16044423cbc73ae4e13c24 OK
2021/07/25 05:56:48 INFO  : a/potato2: Copied (new)
2021/07/25 05:56:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': not deleting files as there were IO errors
2021/07/25 05:56:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': not deleting directories as there were IO errors
    fstest.go:251: Filtering empty directory "d"
--- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.47s)
=== RUN   TestSyncDeleteAfter
--- PASS: TestSyncDeleteAfter (0.00s)
=== RUN   TestSyncDeleteDuring
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:51 DEBUG : potato: md5 = c2a3c27d69fe5f3f710a6bb10a0eccee OK
2021/07/25 05:56:51 DEBUG : empty space: md5 = 5613967126c1f1fefee2a4d27eb886c5 OK
2021/07/25 05:56:51 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:52 DEBUG : empty space: Unchanged skipping
2021/07/25 05:56:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:52 DEBUG : potato2: md5 = be77be1e4417c17ff1fa45d1b1f1540e OK
2021/07/25 05:56:52 INFO  : potato2: Copied (new)
2021/07/25 05:56:53 INFO  : potato: Deleted
--- PASS: TestSyncDeleteDuring (3.78s)
=== RUN   TestSyncDeleteBefore
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:54 DEBUG : potato: md5 = 9eccbfcf8d3f0bd36da5da315da5ccca OK
2021/07/25 05:56:54 DEBUG : empty space: md5 = b3cf4882a56967da08a20ee9e947fbc9 OK
2021/07/25 05:56:54 DEBUG : Waiting for deletions to finish
2021/07/25 05:56:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:56 INFO  : potato: Deleted
2021/07/25 05:56:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:56:56 DEBUG : empty space: Unchanged skipping
2021/07/25 05:56:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:56 DEBUG : potato2: md5 = 4d3891006bae998246c8a455176b0ecc OK
2021/07/25 05:56:56 INFO  : potato2: Copied (new)
--- PASS: TestSyncDeleteBefore (3.43s)
=== RUN   TestCopyDeleteBefore
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:56:58 DEBUG : potato: md5 = f75efbdba9422185513e707be1467154 OK
2021/07/25 05:56:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:56:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:56:58 DEBUG : potato2: md5 = 258d007e0c523024eb03afd30923a44a OK
2021/07/25 05:56:58 INFO  : potato2: Copied (new)
--- PASS: TestCopyDeleteBefore (2.99s)
=== RUN   TestSyncWithExclude
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:00 DEBUG : potato2: md5 = 343af8b3b0971919b54907cd3e3bfc83 OK
2021/07/25 05:57:00 DEBUG : empty space: md5 = 287cf4a8e19c0b434210a17b365d95bb OK
2021/07/25 05:57:00 DEBUG : enormous: Excluded
2021/07/25 05:57:00 DEBUG : potato2: Excluded
2021/07/25 05:57:00 DEBUG : potato2: Excluded
2021/07/25 05:57:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:00 DEBUG : empty space: Unchanged skipping
2021/07/25 05:57:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:00 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:00 INFO  : There was nothing to transfer
2021/07/25 05:57:00 DEBUG : enormous: Excluded
2021/07/25 05:57:00 DEBUG : potato2: Excluded
2021/07/25 05:57:00 DEBUG : potato2: Excluded
2021/07/25 05:57:00 DEBUG : Local file system at /tmp/rclone162028006: Waiting for checks to finish
2021/07/25 05:57:00 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:00 DEBUG : empty space: Unchanged skipping
2021/07/25 05:57:00 DEBUG : Local file system at /tmp/rclone162028006: Waiting for transfers to finish
2021/07/25 05:57:00 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:00 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExclude (1.08s)
=== RUN   TestSyncWithExcludeAndDeleteExcluded
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:01 DEBUG : potato2: md5 = 1a5da8f0cd15cd4de65a97281de8dc46 OK
2021/07/25 05:57:01 DEBUG : empty space: md5 = e741edaa8d688fc80c06d0545e9464ed OK
2021/07/25 05:57:02 DEBUG : enormous: md5 = 5d17eb569c8595b205311b06ce39a65c OK
2021/07/25 05:57:02 DEBUG : enormous: Excluded
2021/07/25 05:57:02 DEBUG : potato2: Excluded
2021/07/25 05:57:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:02 DEBUG : empty space: Unchanged skipping
2021/07/25 05:57:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:02 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:02 INFO  : enormous: Deleted
2021/07/25 05:57:02 INFO  : potato2: Deleted
2021/07/25 05:57:02 INFO  : There was nothing to transfer
2021/07/25 05:57:03 DEBUG : Local file system at /tmp/rclone162028006: Waiting for checks to finish
2021/07/25 05:57:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:03 DEBUG : empty space: Unchanged skipping
2021/07/25 05:57:03 DEBUG : Local file system at /tmp/rclone162028006: Waiting for transfers to finish
2021/07/25 05:57:03 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:03 INFO  : enormous: Deleted
2021/07/25 05:57:03 INFO  : potato2: Deleted
2021/07/25 05:57:03 INFO  : There was nothing to transfer
--- PASS: TestSyncWithExcludeAndDeleteExcluded (2.30s)
=== RUN   TestSyncWithUpdateOlder
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:04 DEBUG : one: md5 = b952bc7cb71e07b4eb488bbb2e8fe80a OK
2021/07/25 05:57:05 DEBUG : two: md5 = bb4ab221ff284ea07e69143b50b25da1 OK
2021/07/25 05:57:07 DEBUG : three: md5 = 575c2b9624c495bc8a23c2843696193f OK
2021/07/25 05:57:07 DEBUG : four: md5 = 99c3a5a25828d428a93708e337ff9467 OK
2021/07/25 05:57:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:08 DEBUG : one: Destination is newer than source, skipping
2021/07/25 05:57:08 DEBUG : four: Sizes differ (src 4 vs dst 8)
2021/07/25 05:57:08 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring
2021/07/25 05:57:08 DEBUG : three: Sizes identical
2021/07/25 05:57:08 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping
2021/07/25 05:57:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:08 DEBUG : four: md5 = b109014c1280ae640cbda50be7dd81c3 OK
2021/07/25 05:57:08 INFO  : four: Copied (replaced existing)
2021/07/25 05:57:08 DEBUG : five: md5 = a380877e7e207da889483fe4226b520f OK
2021/07/25 05:57:08 INFO  : five: Copied (new)
2021/07/25 05:57:10 DEBUG : two: md5 = 9c49099236157b395b7b3aa0b8caef78 OK
2021/07/25 05:57:10 INFO  : two: Copied (replaced existing)
2021/07/25 05:57:10 DEBUG : Waiting for deletions to finish
    sync_test.go:1022: Skip test with --checksum as no hashes supported
--- PASS: TestSyncWithUpdateOlder (10.45s)
=== RUN   TestSyncWithMaxDuration
    sync_test.go:1039: Skipping test on non local remote
--- SKIP: TestSyncWithMaxDuration (0.00s)
=== RUN   TestSyncWithTrackRenames
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1088: Can track renames: false
2021/07/25 05:57:13 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Ignoring --track-renames as the source and destination do not have a common hash
2021/07/25 05:57:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:14 DEBUG : yam: md5 = 632da9b6e946bb4522fa4e2694d78d6c OK
2021/07/25 05:57:14 INFO  : yam: Copied (new)
2021/07/25 05:57:14 DEBUG : potato: md5 = 07d0327f17677bb8cbc6b1e2ffd53250 OK
2021/07/25 05:57:14 INFO  : potato: Copied (new)
2021/07/25 05:57:14 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:15 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Ignoring --track-renames as the source and destination do not have a common hash
2021/07/25 05:57:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:15 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:15 DEBUG : potato: Unchanged skipping
2021/07/25 05:57:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:15 DEBUG : yaml: md5 = e49902bdb224ba486f086d50881f85cc OK
2021/07/25 05:57:15 INFO  : yaml: Copied (new)
2021/07/25 05:57:15 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:15 INFO  : yam: Deleted
--- PASS: TestSyncWithTrackRenames (4.07s)
=== RUN   TestParseRenamesStrategyModtime
--- PASS: TestParseRenamesStrategyModtime (0.00s)
=== RUN   TestRenamesStrategyModtime
--- PASS: TestRenamesStrategyModtime (0.00s)
=== RUN   TestSyncWithTrackRenamesStrategyModtime
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1157: Can track renames: true
2021/07/25 05:57:17 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Making map for --track-renames
2021/07/25 05:57:17 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Finished making map for --track-renames
2021/07/25 05:57:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for renames to finish
2021/07/25 05:57:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:18 DEBUG : yam: md5 = 0f7ae92d3650f652011a0ebbf96fea47 OK
2021/07/25 05:57:18 INFO  : yam: Copied (new)
2021/07/25 05:57:18 DEBUG : potato: md5 = 2203d4e2fbe8a31bc3a2cc36cf209497 OK
2021/07/25 05:57:18 INFO  : potato: Copied (new)
2021/07/25 05:57:18 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:18 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Making map for --track-renames
2021/07/25 05:57:18 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Finished making map for --track-renames
2021/07/25 05:57:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:18 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:18 DEBUG : potato: Unchanged skipping
2021/07/25 05:57:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for renames to finish
2021/07/25 05:57:21 INFO  : yam: Copied (server-side copy) to: yaml
2021/07/25 05:57:22 INFO  : yam: Deleted
2021/07/25 05:57:22 INFO  : yaml: Renamed from "yam"
2021/07/25 05:57:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:22 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyModtime (7.27s)
=== RUN   TestSyncWithTrackRenamesStrategyLeaf
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1193: Can track renames: true
2021/07/25 05:57:25 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Making map for --track-renames
2021/07/25 05:57:25 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Finished making map for --track-renames
2021/07/25 05:57:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for renames to finish
2021/07/25 05:57:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:26 DEBUG : potato: md5 = 0b02bcaad41bf318d3acbeed25924f1a OK
2021/07/25 05:57:26 INFO  : potato: Copied (new)
2021/07/25 05:57:27 DEBUG : sub/yam: md5 = 908910e6dcc22db4d2757ac342a6e21d OK
2021/07/25 05:57:27 INFO  : sub/yam: Copied (new)
2021/07/25 05:57:27 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:27 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:27 DEBUG : potato: Unchanged skipping
2021/07/25 05:57:27 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Making map for --track-renames
2021/07/25 05:57:27 INFO  : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Finished making map for --track-renames
2021/07/25 05:57:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for renames to finish
2021/07/25 05:57:29 INFO  : sub/yam: Copied (server-side copy) to: yam
2021/07/25 05:57:31 INFO  : sub/yam: Deleted
2021/07/25 05:57:31 INFO  : yam: Renamed from "sub/yam"
2021/07/25 05:57:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:31 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncWithTrackRenamesStrategyLeaf (8.81s)
=== RUN   TestMoveWithDeleteEmptySrcDirs
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:34 DEBUG : nested/sub dir/file: md5 = 671eaab5647a1bd3ef79ae4c8f145032 OK
2021/07/25 05:57:34 INFO  : nested/sub dir/file: Copied (new)
2021/07/25 05:57:34 INFO  : nested/sub dir/file: Deleted
2021/07/25 05:57:34 DEBUG : sub dir/hello world: md5 = deb97370d74fce679cd8805ef1bb10b7 OK
2021/07/25 05:57:34 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:57:34 INFO  : sub dir/hello world: Deleted
2021/07/25 05:57:34 INFO  : sub dir: Removing directory
2021/07/25 05:57:34 INFO  : nested/sub dir: Removing directory
2021/07/25 05:57:34 INFO  : nested: Removing directory
2021/07/25 05:57:34 DEBUG : Local file system at /tmp/rclone162028006: deleted 3 directories
--- PASS: TestMoveWithDeleteEmptySrcDirs (1.74s)
=== RUN   TestMoveWithoutDeleteEmptySrcDirs
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 05:57:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 05:57:36 DEBUG : nested/sub dir/file: md5 = a7c4a7d68068e7fcf071e4e8410c60a1 OK
2021/07/25 05:57:36 INFO  : nested/sub dir/file: Copied (new)
2021/07/25 05:57:36 INFO  : nested/sub dir/file: Deleted
2021/07/25 05:57:36 DEBUG : sub dir/hello world: md5 = 43651ca136855ce0d80fa38533fe8db8 OK
2021/07/25 05:57:36 INFO  : sub dir/hello world: Copied (new)
2021/07/25 05:57:36 INFO  : sub dir/hello world: Deleted
--- PASS: TestMoveWithoutDeleteEmptySrcDirs (3.11s)
=== RUN   TestServerSideMove
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-falamuv3werayok2napogod6"
2021/07/25 05:57:38 DEBUG : Config file has changed externaly - reloading
2021/07/25 05:57:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-falamuv3werayok2napogod6.bin"
2021/07/25 05:57:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-falamuv3werayok2napogod6"
2021/07/25 05:57:39 DEBUG : potato2: md5 = 8bfbbe1901c7feb03200c94d887fd7b5 OK
2021/07/25 05:57:40 DEBUG : empty space: md5 = e2507287c65c4e1501397c0ff5544e50 OK
2021/07/25 05:57:41 DEBUG : potato3: md5 = 72820838a314261411012446c304ecf2 OK
    sync_test.go:1245: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9' -> Encrypted drive 'TestCryptSwift:rclone-test-falamuv3werayok2napogod6'
2021/07/25 05:57:42 DEBUG : empty space: md5 = af1a309b7995871434208804aeae36b0 OK
2021/07/25 05:57:44 DEBUG : potato3: md5 = c2859aeef60517fbafe3db905612329b OK
2021/07/25 05:57:44 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/07/25 05:57:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-falamuv3werayok2napogod6': Waiting for checks to finish
2021/07/25 05:57:44 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:57:44 DEBUG : empty space: Unchanged skipping
2021/07/25 05:57:44 INFO  : empty space: Deleted
2021/07/25 05:57:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-falamuv3werayok2napogod6': Waiting for transfers to finish
2021/07/25 05:57:47 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:57:47 INFO  : potato2: Deleted
2021/07/25 05:57:47 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:57:48 INFO  : potato3: Deleted
2021/07/25 05:57:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xekocob2zikuwuj2kavopix1"
2021/07/25 05:57:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xekocob2zikuwuj2kavopix1.bin"
2021/07/25 05:57:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xekocob2zikuwuj2kavopix1"
2021/07/25 05:57:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xekocob2zikuwuj2kavopix1': Waiting for checks to finish
2021/07/25 05:57:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xekocob2zikuwuj2kavopix1': Waiting for transfers to finish
2021/07/25 05:57:50 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:57:50 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:57:51 INFO  : potato2: Deleted
2021/07/25 05:57:51 INFO  : potato3: Deleted
2021/07/25 05:57:51 INFO  : empty space: Copied (server-side copy)
2021/07/25 05:57:51 INFO  : empty space: Deleted
2021/07/25 05:57:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xekocob2zikuwuj2kavopix1': Purge remote
2021/07/25 05:57:52 DEBUG : Waiting for deletions to finish
2021/07/25 05:57:52 INFO  : potato2.bin: Deleted
2021/07/25 05:57:52 INFO  : empty space.bin: Deleted
2021/07/25 05:57:52 INFO  : potato3.bin: Deleted
2021/07/25 05:57:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-falamuv3werayok2napogod6': Purge remote
2021/07/25 05:57:52 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMove (14.39s)
=== RUN   TestServerSideMoveWithFilter
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:57:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kagetis2lopores4necilaq6"
2021/07/25 05:57:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kagetis2lopores4necilaq6.bin"
2021/07/25 05:57:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kagetis2lopores4necilaq6"
2021/07/25 05:57:54 DEBUG : potato2: md5 = a90fddf8e9849b4d9d06147fc2737ceb OK
2021/07/25 05:57:54 DEBUG : empty space: md5 = 9b46ffb6397cac2fab2f413b35e24139 OK
2021/07/25 05:57:55 DEBUG : potato3: md5 = cad26f0b0b76742d3910fad05f69ca2d OK
    sync_test.go:1245: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9' -> Encrypted drive 'TestCryptSwift:rclone-test-kagetis2lopores4necilaq6'
2021/07/25 05:57:56 DEBUG : empty space: md5 = 9e9825c5d95a17948b6219ced8141ecb OK
2021/07/25 05:57:58 DEBUG : potato3: md5 = de60158ee888d26c746cd452b2cdb7e0 OK
2021/07/25 05:57:58 DEBUG : empty space: Excluded
2021/07/25 05:57:58 DEBUG : empty space: Excluded
2021/07/25 05:57:58 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/07/25 05:57:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kagetis2lopores4necilaq6': Waiting for checks to finish
2021/07/25 05:57:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kagetis2lopores4necilaq6': Waiting for transfers to finish
2021/07/25 05:57:59 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:57:59 INFO  : potato2: Deleted
2021/07/25 05:58:00 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:58:01 INFO  : potato3: Deleted
2021/07/25 05:58:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sozayis6bobicol3secicor0"
2021/07/25 05:58:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sozayis6bobicol3secicor0.bin"
2021/07/25 05:58:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sozayis6bobicol3secicor0"
2021/07/25 05:58:01 DEBUG : empty space: Excluded
2021/07/25 05:58:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sozayis6bobicol3secicor0': Waiting for checks to finish
2021/07/25 05:58:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sozayis6bobicol3secicor0': Waiting for transfers to finish
2021/07/25 05:58:02 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:58:02 INFO  : potato2: Deleted
2021/07/25 05:58:03 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:58:03 INFO  : potato3: Deleted
2021/07/25 05:58:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sozayis6bobicol3secicor0': Purge remote
2021/07/25 05:58:04 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:04 INFO  : potato2.bin: Deleted
2021/07/25 05:58:04 INFO  : potato3.bin: Deleted
2021/07/25 05:58:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kagetis2lopores4necilaq6': Purge remote
2021/07/25 05:58:04 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:04 INFO  : empty space.bin: Deleted
--- PASS: TestServerSideMoveWithFilter (11.86s)
=== RUN   TestServerSideMoveDeleteEmptySourceDirs
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gadazob5mapidat0femipuv3"
2021/07/25 05:58:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gadazob5mapidat0femipuv3.bin"
2021/07/25 05:58:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gadazob5mapidat0femipuv3"
2021/07/25 05:58:06 DEBUG : potato2: md5 = 9e90509eb214b73e615b3585fc3d0209 OK
2021/07/25 05:58:06 DEBUG : empty space: md5 = de94c31feeedbcb8d8ba992b21357ced OK
2021/07/25 05:58:07 DEBUG : potato3: md5 = e4a99765bb8a965f6dc64bd32e814616 OK
2021/07/25 05:58:07 DEBUG : tomatoDir: Making directory
    sync_test.go:1245: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9' -> Encrypted drive 'TestCryptSwift:rclone-test-gadazob5mapidat0femipuv3'
2021/07/25 05:58:08 DEBUG : empty space: md5 = 8beda0512de51f08f7f7addebe2366e8 OK
2021/07/25 05:58:09 DEBUG : potato3: md5 = ab81bb00c772c4bf527b7a649cd7a107 OK
2021/07/25 05:58:10 DEBUG : potato3: Sizes differ (src 68 vs dst 60)
2021/07/25 05:58:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gadazob5mapidat0femipuv3': Waiting for checks to finish
2021/07/25 05:58:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:10 DEBUG : empty space: Unchanged skipping
2021/07/25 05:58:10 INFO  : empty space: Deleted
2021/07/25 05:58:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gadazob5mapidat0femipuv3': Waiting for transfers to finish
2021/07/25 05:58:10 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:58:11 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:58:11 INFO  : potato2: Deleted
2021/07/25 05:58:11 INFO  : potato3: Deleted
2021/07/25 05:58:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xerabeg4toqivuz9nuvazis2"
2021/07/25 05:58:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xerabeg4toqivuz9nuvazis2.bin"
2021/07/25 05:58:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xerabeg4toqivuz9nuvazis2"
2021/07/25 05:58:12 DEBUG : tomatoDir: Making directory
2021/07/25 05:58:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xerabeg4toqivuz9nuvazis2': Waiting for checks to finish
2021/07/25 05:58:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xerabeg4toqivuz9nuvazis2': Waiting for transfers to finish
2021/07/25 05:58:12 INFO  : empty space: Copied (server-side copy)
2021/07/25 05:58:13 INFO  : empty space: Deleted
2021/07/25 05:58:15 INFO  : potato2: Copied (server-side copy)
2021/07/25 05:58:15 INFO  : potato3: Copied (server-side copy)
2021/07/25 05:58:16 INFO  : potato2: Deleted
2021/07/25 05:58:16 INFO  : potato3: Deleted
2021/07/25 05:58:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xerabeg4toqivuz9nuvazis2': Purge remote
2021/07/25 05:58:17 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:17 INFO  : empty space.bin: Deleted
2021/07/25 05:58:17 INFO  : potato2.bin: Deleted
2021/07/25 05:58:17 INFO  : potato3.bin: Deleted
2021/07/25 05:58:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gadazob5mapidat0femipuv3': Purge remote
2021/07/25 05:58:17 DEBUG : Waiting for deletions to finish
--- PASS: TestServerSideMoveDeleteEmptySourceDirs (12.73s)
=== RUN   TestServerSideMoveOverlap
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/rclone-move-test"
2021/07/25 05:58:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/rclone-move-test.bin"
2021/07/25 05:58:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/rclone-move-test"
2021/07/25 05:58:18 DEBUG : potato2: md5 = 74aa34e3883b33b4b9f799931396d679 OK
--- PASS: TestServerSideMoveOverlap (1.61s)
=== RUN   TestSyncOverlap
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/rclone-sync-test"
2021/07/25 05:58:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/rclone-sync-test.bin"
2021/07/25 05:58:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/rclone-sync-test"
--- PASS: TestSyncOverlap (1.11s)
=== RUN   TestSyncCompareDest
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:58:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/CompareDest"
2021/07/25 05:58:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/CompareDest.bin"
2021/07/25 05:58:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/CompareDest"
2021/07/25 05:58:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:23 DEBUG : one: md5 = 0db9ecbe2506fe2b680581a30e1c906b OK
2021/07/25 05:58:23 INFO  : one: Copied (new)
2021/07/25 05:58:23 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:23 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/07/25 05:58:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:24 DEBUG : one: md5 = 0cc2b91c683025fa1977eb029d4e8c2e OK
2021/07/25 05:58:24 INFO  : one: Copied (replaced existing)
2021/07/25 05:58:24 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:24 DEBUG : dst/one: md5 = a3826e03af0a1f162f80406cdf298cf5 OK
2021/07/25 05:58:24 DEBUG : CompareDest/one: md5 = 537db9ca7ff96612df519967a00ab5c6 OK
2021/07/25 05:58:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:24 DEBUG : one: Destination found in --compare-dest, skipping
2021/07/25 05:58:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:24 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:24 INFO  : There was nothing to transfer
2021/07/25 05:58:25 DEBUG : CompareDest/two: md5 = 442a36a2e8d4c4a3626e93d429b92478 OK
2021/07/25 05:58:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:26 DEBUG : two: Destination found in --compare-dest, skipping
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:26 DEBUG : one: Destination found in --compare-dest, skipping
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:26 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:26 INFO  : There was nothing to transfer
2021/07/25 05:58:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:26 DEBUG : two: Destination found in --compare-dest, skipping
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:26 DEBUG : one: Destination found in --compare-dest, skipping
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:26 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:26 INFO  : There was nothing to transfer
    sync_test.go:1526: No hash on uploaded file so skipping compare timestamp test
2021/07/25 05:58:26 DEBUG : two: Sizes differ (src 5 vs dst 3)
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:26 DEBUG : one: Destination found in --compare-dest, skipping
2021/07/25 05:58:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:27 DEBUG : two: md5 = 83d8b424bfbc7ac813ee76cb76f494ab OK
2021/07/25 05:58:27 INFO  : two: Copied (new)
2021/07/25 05:58:27 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCompareDest (9.24s)
=== RUN   TestSyncMultipleCompareDest
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:30 DEBUG : pre-dest1/1: md5 = 07a6f67c6676feed3cf5ddd927ef2a9b OK
2021/07/25 05:58:31 DEBUG : pre-dest2/2: md5 = e3d547e3680c791ad964474638d0c982 OK
2021/07/25 05:58:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dest"
2021/07/25 05:58:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dest.bin"
2021/07/25 05:58:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dest"
2021/07/25 05:58:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/pre-dest1"
2021/07/25 05:58:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/pre-dest1.bin"
2021/07/25 05:58:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/pre-dest1"
2021/07/25 05:58:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/pre-dest2"
2021/07/25 05:58:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/pre-dest2.bin"
2021/07/25 05:58:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/pre-dest2"
2021/07/25 05:58:34 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:34 DEBUG : 1: Destination found in --compare-dest, skipping
2021/07/25 05:58:34 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:34 DEBUG : 2: Destination found in --compare-dest, skipping
2021/07/25 05:58:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dest': Waiting for checks to finish
2021/07/25 05:58:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dest': Waiting for transfers to finish
2021/07/25 05:58:35 DEBUG : 3: md5 = e634719ac960ae058fec4cc969cb8e94 OK
2021/07/25 05:58:35 INFO  : 3: Copied (new)
2021/07/25 05:58:35 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncMultipleCompareDest (7.82s)
=== RUN   TestSyncCopyDest
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:58:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/CopyDest"
2021/07/25 05:58:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/CopyDest.bin"
2021/07/25 05:58:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/CopyDest"
2021/07/25 05:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:39 DEBUG : one: md5 = d2c2e0f8b5cea9a90523bfb093040a3d OK
2021/07/25 05:58:39 INFO  : one: Copied (new)
2021/07/25 05:58:39 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:39 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/07/25 05:58:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:39 DEBUG : one: md5 = 62e9939c4d046537f1b86b9579bd899a OK
2021/07/25 05:58:39 INFO  : one: Copied (replaced existing)
2021/07/25 05:58:39 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:40 DEBUG : dst/one: md5 = ef5361e5cf5c17881bf5d4571b6e597d OK
2021/07/25 05:58:40 DEBUG : CopyDest/one: md5 = d2c3f7eae375359cde9717acd1e041fb OK
2021/07/25 05:58:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/BackupDir"
2021/07/25 05:58:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/BackupDir.bin"
2021/07/25 05:58:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/BackupDir"
2021/07/25 05:58:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:42 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:42 DEBUG : one: Sizes differ (src 5 vs dst 3)
2021/07/25 05:58:43 INFO  : one: Copied (server-side copy)
2021/07/25 05:58:43 INFO  : one: Deleted
2021/07/25 05:58:43 INFO  : one: Copied (server-side copy)
2021/07/25 05:58:43 DEBUG : one: Destination found in --copy-dest, using server-side copy
2021/07/25 05:58:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:43 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:44 DEBUG : CopyDest/two: md5 = 6d1b6b782d802f875c6f4cb36a1134c0 OK
2021/07/25 05:58:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 INFO  : two: Copied (server-side copy)
2021/07/25 05:58:44 DEBUG : two: Destination found in --copy-dest, using server-side copy
2021/07/25 05:58:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : one: Unchanged skipping
2021/07/25 05:58:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:44 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : one: Unchanged skipping
2021/07/25 05:58:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:44 DEBUG : two: Unchanged skipping
2021/07/25 05:58:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:44 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:44 INFO  : There was nothing to transfer
2021/07/25 05:58:45 DEBUG : CopyDest/three: md5 = b57ed7dc25153f5bdafb04d1d866b1e7 OK
2021/07/25 05:58:45 DEBUG : three: Sizes differ (src 7 vs dst 5)
2021/07/25 05:58:45 DEBUG : three: Destination not found in --copy-dest
2021/07/25 05:58:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:45 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:45 DEBUG : one: Unchanged skipping
2021/07/25 05:58:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:45 DEBUG : two: Unchanged skipping
2021/07/25 05:58:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:47 DEBUG : three: md5 = e0b4b1cd9df435860765484b0ae6f58e OK
2021/07/25 05:58:47 INFO  : three: Copied (new)
2021/07/25 05:58:47 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncCopyDest (11.76s)
=== RUN   TestSyncBackupDir
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:58:49 DEBUG : dst/one: md5 = 7ff4c44e03700698d9f30c30c5c924a4 OK
2021/07/25 05:58:49 DEBUG : dst/two: md5 = 6d88862857472c2f209d8a17b33cb92c OK
2021/07/25 05:58:51 DEBUG : dst/three.txt: md5 = e162b7547a217bd35c22cc882c6b3331 OK
2021/07/25 05:58:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:58:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:58:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:58:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup.bin"
2021/07/25 05:58:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:58:53 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 05:58:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:53 DEBUG : two: Unchanged skipping
2021/07/25 05:58:54 INFO  : one: Copied (server-side copy)
2021/07/25 05:58:54 INFO  : one: Deleted
2021/07/25 05:58:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:58:54 DEBUG : one: md5 = 43a0f28c8d215a84c11d977cfe59c0f3 OK
2021/07/25 05:58:54 INFO  : one: Copied (new)
2021/07/25 05:58:54 DEBUG : Waiting for deletions to finish
2021/07/25 05:58:57 INFO  : three.txt: Copied (server-side copy)
2021/07/25 05:58:58 INFO  : three.txt: Deleted
2021/07/25 05:58:58 INFO  : three.txt: Moved into backup dir
2021/07/25 05:58:58 DEBUG : dst/three.txt: md5 = d4552567a77603dfce0a2b35858c60aa OK
2021/07/25 05:58:58 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 05:58:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:58:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:58:58 DEBUG : two: Unchanged skipping
2021/07/25 05:59:02 INFO  : one: Copied (server-side copy)
2021/07/25 05:59:02 INFO  : one: Deleted
2021/07/25 05:59:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:02 DEBUG : one: md5 = 380e598e220f5edbc54964077179c600 OK
2021/07/25 05:59:02 INFO  : one: Copied (new)
2021/07/25 05:59:02 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:05 INFO  : three.txt: Copied (server-side copy)
2021/07/25 05:59:05 INFO  : three.txt: Deleted
2021/07/25 05:59:05 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDir (18.83s)
=== RUN   TestSyncBackupDirWithSuffix
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:59:08 DEBUG : dst/one: md5 = ea65e1987ea2e11c99175e38b6cc582d OK
2021/07/25 05:59:08 DEBUG : dst/two: md5 = c08e069918f2777ff2a0e01079b8200c OK
2021/07/25 05:59:09 DEBUG : dst/three.txt: md5 = 938fa60e80070c68624d7c48afc9aa30 OK
2021/07/25 05:59:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:59:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:59:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup.bin"
2021/07/25 05:59:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:59:11 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 05:59:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:11 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:11 DEBUG : two: Unchanged skipping
2021/07/25 05:59:12 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 05:59:12 INFO  : one: Deleted
2021/07/25 05:59:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:13 DEBUG : one: md5 = 2b65e4f59e75bf0775f008e190435361 OK
2021/07/25 05:59:13 INFO  : one: Copied (new)
2021/07/25 05:59:13 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:14 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 05:59:15 INFO  : three.txt: Deleted
2021/07/25 05:59:15 INFO  : three.txt: Moved into backup dir
2021/07/25 05:59:17 DEBUG : dst/three.txt: md5 = 4ae0a595048e9f0144443c723872245c OK
2021/07/25 05:59:17 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 05:59:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:17 DEBUG : two: Unchanged skipping
2021/07/25 05:59:18 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 05:59:18 INFO  : one: Deleted
2021/07/25 05:59:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:18 DEBUG : one: md5 = 1c0143a717dca537780249bf0ec6a107 OK
2021/07/25 05:59:18 INFO  : one: Copied (new)
2021/07/25 05:59:18 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:20 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 05:59:21 INFO  : three.txt: Deleted
2021/07/25 05:59:21 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffix (14.44s)
=== RUN   TestSyncBackupDirWithSuffixKeepExtension
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:59:22 DEBUG : dst/one: md5 = 6a30af6fe2ade244ce406fcfc5cfa06d OK
2021/07/25 05:59:23 DEBUG : dst/two: md5 = 0a8e98e0681fa92dc3d04b57ca100d0b OK
2021/07/25 05:59:24 DEBUG : dst/three.txt: md5 = 7889fa41fd4d9484833f00361695cd13 OK
2021/07/25 05:59:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:59:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:59:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup.bin"
2021/07/25 05:59:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/backup"
2021/07/25 05:59:26 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 05:59:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:26 DEBUG : two: Unchanged skipping
2021/07/25 05:59:29 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2021/07/25 05:59:29 INFO  : one: Deleted
2021/07/25 05:59:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:29 DEBUG : one: md5 = 67a7c3dc7f32e80f65c8b8e3eba4e080 OK
2021/07/25 05:59:29 INFO  : one: Copied (new)
2021/07/25 05:59:29 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:32 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2021/07/25 05:59:33 INFO  : three.txt: Deleted
2021/07/25 05:59:33 INFO  : three.txt: Moved into backup dir
2021/07/25 05:59:35 DEBUG : dst/three.txt: md5 = 78e7f2844a9a33235f5a1c7f85932bb8 OK
2021/07/25 05:59:35 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 05:59:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:35 DEBUG : two: Unchanged skipping
2021/07/25 05:59:36 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2021/07/25 05:59:36 INFO  : one: Deleted
2021/07/25 05:59:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:37 DEBUG : one: md5 = c4f6bdad97ed1afb7215c0ebfcc6372b OK
2021/07/25 05:59:37 INFO  : one: Copied (new)
2021/07/25 05:59:37 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:41 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2021/07/25 05:59:42 INFO  : three.txt: Deleted
2021/07/25 05:59:42 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirWithSuffixKeepExtension (23.22s)
=== RUN   TestSyncBackupDirSuffixOnly
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 05:59:46 DEBUG : dst/one: md5 = 3812ac774257afb3faea99e804237525 OK
2021/07/25 05:59:46 DEBUG : dst/two: md5 = ab8f8c3ddfb19ee82400b225c9785786 OK
2021/07/25 05:59:48 DEBUG : dst/three.txt: md5 = 47ca5ec8181c7376789e767832212700 OK
2021/07/25 05:59:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 05:59:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 05:59:49 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 05:59:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:49 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:49 DEBUG : two: Unchanged skipping
2021/07/25 05:59:51 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 05:59:51 INFO  : one: Deleted
2021/07/25 05:59:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:51 DEBUG : one: md5 = ae8cf5d0ba9a11cae94957b187d78ef0 OK
2021/07/25 05:59:51 INFO  : one: Copied (new)
2021/07/25 05:59:51 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:54 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 05:59:55 INFO  : three.txt: Deleted
2021/07/25 05:59:55 INFO  : three.txt: Moved into backup dir
2021/07/25 05:59:56 DEBUG : dst/three.txt: md5 = c6d033b0bad4e3f26f255427ac577b5f OK
2021/07/25 05:59:56 DEBUG : one.bak: Excluded
2021/07/25 05:59:56 DEBUG : three.txt.bak: Excluded
2021/07/25 05:59:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for checks to finish
2021/07/25 05:59:56 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 05:59:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 05:59:56 DEBUG : two: Unchanged skipping
2021/07/25 05:59:57 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 05:59:57 INFO  : one: Deleted
2021/07/25 05:59:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst': Waiting for transfers to finish
2021/07/25 05:59:58 DEBUG : one: md5 = 8d143c581c4b1b929584bce8cae44d4b OK
2021/07/25 05:59:58 INFO  : one: Copied (new)
2021/07/25 05:59:58 DEBUG : Waiting for deletions to finish
2021/07/25 05:59:59 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 06:00:00 INFO  : three.txt: Deleted
2021/07/25 06:00:00 INFO  : three.txt: Moved into backup dir
--- PASS: TestSyncBackupDirSuffixOnly (17.85s)
=== RUN   TestSyncSuffix
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:00:04 DEBUG : dst/one: md5 = ff23561e9163ffb26018f356d26ef44d OK
2021/07/25 06:00:04 DEBUG : dst/two: md5 = e753c31f12b6c542e311d78e246359f9 OK
2021/07/25 06:00:07 DEBUG : dst/three.txt: md5 = 5eef9d4a450bbb91ee4d765e0848afde OK
2021/07/25 06:00:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 06:00:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 06:00:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 06:00:08 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 06:00:10 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 06:00:10 INFO  : one: Deleted
2021/07/25 06:00:10 DEBUG : one: md5 = f015bdab58bb11cc4609df2b3430b365 OK
2021/07/25 06:00:10 INFO  : one: Copied (new)
2021/07/25 06:00:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:00:10 DEBUG : two: Unchanged skipping
2021/07/25 06:00:10 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2021/07/25 06:00:14 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 06:00:15 INFO  : three.txt: Deleted
2021/07/25 06:00:17 DEBUG : three.txt: md5 = 04c79848a0854cb8a181ea129f73c057 OK
2021/07/25 06:00:17 INFO  : three.txt: Copied (new)
2021/07/25 06:00:17 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 06:00:19 INFO  : one: Copied (server-side copy) to: one.bak
2021/07/25 06:00:19 INFO  : one: Deleted
2021/07/25 06:00:20 DEBUG : one: md5 = f934716255de915734fc2e205f056c4e OK
2021/07/25 06:00:20 INFO  : one: Copied (new)
2021/07/25 06:00:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:00:20 DEBUG : two: Unchanged skipping
2021/07/25 06:00:20 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2021/07/25 06:00:22 INFO  : three.txt: Copied (server-side copy) to: three.txt.bak
2021/07/25 06:00:23 INFO  : three.txt: Deleted
2021/07/25 06:00:23 DEBUG : three.txt: md5 = 8cf8393312b4444466a5cdbde16a1696 OK
2021/07/25 06:00:23 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffix (23.70s)
=== RUN   TestSyncSuffixKeepExtension
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:00:27 DEBUG : dst/one: md5 = af4ae7cd243107c545b43cc53086af3b OK
2021/07/25 06:00:27 DEBUG : dst/two: md5 = bf59f6bc6f06dd28b1d7c5735708229d OK
2021/07/25 06:00:29 DEBUG : dst/three.txt: md5 = 412011811ff45177a0cd609f2e1d69fa OK
2021/07/25 06:00:29 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 06:00:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst.bin"
2021/07/25 06:00:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-caqafag1gigulam2xuxived9/dst"
2021/07/25 06:00:30 DEBUG : one: Sizes differ (src 4 vs dst 3)
2021/07/25 06:00:31 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2021/07/25 06:00:31 INFO  : one: Deleted
2021/07/25 06:00:31 DEBUG : one: md5 = afb31e5793f9a90f992e9034f281661c OK
2021/07/25 06:00:31 INFO  : one: Copied (new)
2021/07/25 06:00:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:00:31 DEBUG : two: Unchanged skipping
2021/07/25 06:00:31 DEBUG : three.txt: Sizes differ (src 6 vs dst 5)
2021/07/25 06:00:37 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2021/07/25 06:00:39 INFO  : three.txt: Deleted
2021/07/25 06:00:43 DEBUG : three.txt: md5 = e41e301a8656104d0c5daa2a674480bb OK
2021/07/25 06:00:43 INFO  : three.txt: Copied (new)
2021/07/25 06:00:43 DEBUG : one: Sizes differ (src 5 vs dst 4)
2021/07/25 06:00:47 INFO  : one: Copied (server-side copy) to: one-2019-01-01
2021/07/25 06:00:47 INFO  : one: Deleted
2021/07/25 06:00:48 DEBUG : one: md5 = ed548360bacdffe4f23c71285a449a4c OK
2021/07/25 06:00:48 INFO  : one: Copied (new)
2021/07/25 06:00:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:00:48 DEBUG : two: Unchanged skipping
2021/07/25 06:00:48 DEBUG : three.txt: Sizes differ (src 19 vs dst 6)
2021/07/25 06:00:55 INFO  : three.txt: Copied (server-side copy) to: three-2019-01-01.txt
2021/07/25 06:00:58 INFO  : three.txt: Deleted
2021/07/25 06:01:02 DEBUG : three.txt: md5 = 9a1757bb87971f02e53391080ae92385 OK
2021/07/25 06:01:02 INFO  : three.txt: Copied (new)
--- PASS: TestSyncSuffixKeepExtension (43.93s)
=== RUN   TestSyncUTFNorm
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:01:14 DEBUG : Testêé: md5 = e2f3862e133f82d520bd791e4a6bc9d8 OK
2021/07/25 06:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:01:15 DEBUG : Testêé: Sizes differ (src 14 vs dst 18)
2021/07/25 06:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:01:18 DEBUG : Testêé: md5 = 82abd15a44a4c6d3ccbb710115f287a2 OK
2021/07/25 06:01:18 INFO  : Testêé: Copied (replaced existing) to: Testêé
2021/07/25 06:01:18 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncUTFNorm (9.46s)
=== RUN   TestSyncImmutable
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:01:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:01:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:01:21 DEBUG : existing: md5 = 2d1b776f6570e9453fb5b5a6a889fc0f OK
2021/07/25 06:01:21 INFO  : existing: Copied (new)
2021/07/25 06:01:21 DEBUG : Waiting for deletions to finish
2021/07/25 06:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:01:21 DEBUG : existing: Sizes differ (src 8 vs dst 6)
2021/07/25 06:01:21 ERROR : existing: Source and destination exist but do not match: immutable file modified
2021/07/25 06:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:01:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': not deleting files as there were IO errors
2021/07/25 06:01:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': not deleting directories as there were IO errors
--- PASS: TestSyncImmutable (0.77s)
=== RUN   TestSyncIgnoreCase
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:01:23 DEBUG : EXISTING: md5 = bfecbdc1fef5e13e30ed0b291d37468e OK
2021/07/25 06:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:01:23 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:01:23 DEBUG : existing: Unchanged skipping
2021/07/25 06:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:01:23 DEBUG : Waiting for deletions to finish
2021/07/25 06:01:23 INFO  : There was nothing to transfer
--- PASS: TestSyncIgnoreCase (2.92s)
=== RUN   TestMaxTransfer
=== RUN   TestMaxTransfer/Hard
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1971: This test only runs on local
=== RUN   TestMaxTransfer/Soft
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1971: This test only runs on local
=== RUN   TestMaxTransfer/Cautious
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
    sync_test.go:1971: This test only runs on local
--- PASS: TestMaxTransfer (0.24s)
    --- SKIP: TestMaxTransfer/Hard (0.08s)
    --- SKIP: TestMaxTransfer/Soft (0.14s)
    --- SKIP: TestMaxTransfer/Cautious (0.02s)
=== RUN   TestSyncConcurrentDelete
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:01:26 DEBUG : both0: md5 = 3f5745011c3be11527b99c10af805584 OK
2021/07/25 06:01:27 DEBUG : only0: md5 = dafb0f100ee54e89e14c92efedda6aff OK
2021/07/25 06:01:29 DEBUG : both1: md5 = 1b8bdfdf8323b4e48afcd2a190685dac OK
2021/07/25 06:01:29 DEBUG : only1: md5 = 28160b7259806b99339aacce2db4cae1 OK
2021/07/25 06:01:30 DEBUG : both2: md5 = 32850f9e6c5acdb8c00f007ebcb4b398 OK
2021/07/25 06:01:32 DEBUG : only2: md5 = f27989ddb947dbd79e6e5c1b51151cf4 OK
2021/07/25 06:01:33 DEBUG : both3: md5 = 34e67b82f4ae8680aaf1a3ca3887c5d6 OK
2021/07/25 06:01:34 DEBUG : only3: md5 = 09f4d1bb1fd9b81564366e421a3c2d11 OK
2021/07/25 06:01:34 DEBUG : both4: md5 = 301e5af9d02704d4e2b707d3ff145f38 OK
2021/07/25 06:01:35 DEBUG : only4: md5 = 1c375ff756218ae0b3874927ef56f4fb OK
2021/07/25 06:01:37 DEBUG : both5: md5 = 97684f6fc6b8f62b4ec873c585548749 OK
2021/07/25 06:01:39 DEBUG : only5: md5 = b82027d46b07338078bbb0ccc7f6e567 OK
2021/07/25 06:01:40 DEBUG : both6: md5 = 950ec437a8606ba87c5baa0988d11b4f OK
2021/07/25 06:01:40 DEBUG : only6: md5 = f8d56cc97bb2325f165221b62be5e587 OK
2021/07/25 06:01:40 DEBUG : both7: md5 = 6ae4b6c309cbbd45c15c7a725d611cf3 OK
2021/07/25 06:01:41 DEBUG : only7: md5 = 74ac435e1ca39bf2f2b4f929dc4563f9 OK
2021/07/25 06:01:43 DEBUG : both8: md5 = a110a349a0f218ba722e6b8c2b64d14c OK
2021/07/25 06:01:43 DEBUG : only8: md5 = 55c48e90b13484f98f4b13a5accf7192 OK
2021/07/25 06:01:45 DEBUG : both9: md5 = 9f041e1cb40106a794603526fee71497 OK
2021/07/25 06:01:45 DEBUG : only9: md5 = 13f9c6ddcfe59b10e70c8cf8a3f5339f OK
2021/07/25 06:01:46 DEBUG : both10: md5 = 53629345260bf04bd08b297584a42f3d OK
2021/07/25 06:01:47 DEBUG : only10: md5 = d0ff9ca18fd5fe188bdc7e97b65c48f8 OK
2021/07/25 06:01:48 DEBUG : both11: md5 = bcc3ee79c39d6fb98a766f42964b3126 OK
2021/07/25 06:01:50 DEBUG : only11: md5 = b1db08b3d29b5bdc67f6e0348ad633c7 OK
2021/07/25 06:01:50 DEBUG : both12: md5 = e546766c38957de181b0cedadb906279 OK
2021/07/25 06:01:51 DEBUG : only12: md5 = f74eb35cbdc0c3ceb288f9b7d491409d OK
2021/07/25 06:01:52 DEBUG : both13: md5 = 390b8586e4810b3a7c201ecfcb4b9ac0 OK
2021/07/25 06:01:55 DEBUG : only13: md5 = c2cde029e3336cd32f68077e0be154be OK
2021/07/25 06:01:57 DEBUG : both14: md5 = 5a2b499c0e7260b89835169da3b404ed OK
2021/07/25 06:01:57 DEBUG : only14: md5 = 0545e29ea649fbe8ce1ada28a56a2912 OK
2021/07/25 06:01:58 DEBUG : both15: md5 = 59994f8267478f46511377252c45f384 OK
2021/07/25 06:01:58 DEBUG : only15: md5 = f5872fd9e95fc4531f2744e93e7d6dcb OK
2021/07/25 06:01:58 DEBUG : both16: md5 = 9664cf14d2c51ed202afd72c0a676937 OK
2021/07/25 06:01:59 DEBUG : only16: md5 = de200e0362527bdec62add8c23f9192f OK
2021/07/25 06:02:01 DEBUG : both17: md5 = 66e7037ccb59d58fc7d2be991e238dbb OK
2021/07/25 06:02:01 DEBUG : only17: md5 = 7da99c7b371051fd6cb418effee282aa OK
2021/07/25 06:02:02 DEBUG : both18: md5 = d80a48f41b27bd537a8d3704b0422c4e OK
2021/07/25 06:02:04 DEBUG : only18: md5 = 5d2c50f33850d95bef2ab9b023062ad3 OK
2021/07/25 06:02:05 DEBUG : both19: md5 = 5c5188e9cce2e13758e309a09c3dca47 OK
2021/07/25 06:02:05 DEBUG : only19: md5 = c3c5f5ed12c832792147e4bd0ec03294 OK
2021/07/25 06:02:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:02:07 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both1: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both11: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both12: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both10: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both15: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both14: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both16: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both0: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both19: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both2: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both13: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both17: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both18: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both4: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both6: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both7: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both8: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both9: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both3: Unchanged skipping
2021/07/25 06:02:07 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:07 DEBUG : both5: Unchanged skipping
2021/07/25 06:02:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:02:07 DEBUG : Waiting for deletions to finish
2021/07/25 06:02:07 INFO  : only12: Deleted
2021/07/25 06:02:07 INFO  : only14: Deleted
2021/07/25 06:02:08 INFO  : only19: Deleted
2021/07/25 06:02:08 INFO  : only10: Deleted
2021/07/25 06:02:08 INFO  : only5: Deleted
2021/07/25 06:02:08 INFO  : only18: Deleted
2021/07/25 06:02:08 INFO  : only8: Deleted
2021/07/25 06:02:08 INFO  : only11: Deleted
2021/07/25 06:02:08 INFO  : only6: Deleted
2021/07/25 06:02:08 INFO  : only2: Deleted
2021/07/25 06:02:08 INFO  : only13: Deleted
2021/07/25 06:02:08 INFO  : only9: Deleted
2021/07/25 06:02:08 INFO  : only1: Deleted
2021/07/25 06:02:08 INFO  : only3: Deleted
2021/07/25 06:02:08 INFO  : only16: Deleted
2021/07/25 06:02:08 INFO  : only15: Deleted
2021/07/25 06:02:08 INFO  : only17: Deleted
2021/07/25 06:02:08 INFO  : only7: Deleted
2021/07/25 06:02:09 INFO  : only0: Deleted
2021/07/25 06:02:10 INFO  : only4: Deleted
2021/07/25 06:02:10 INFO  : There was nothing to transfer
--- PASS: TestSyncConcurrentDelete (58.47s)
=== RUN   TestSyncConcurrentTruncate
    run.go:176: Remote "Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9'", Local "Local file system at /tmp/rclone162028006", Modify Window "1ns"
2021/07/25 06:02:24 DEBUG : both0: md5 = 901da2b49e50f904fa6c5e5dd3ce54b4 OK
2021/07/25 06:02:25 DEBUG : only0: md5 = d23f4d66b30dc0dc85a86a9d787f95ae OK
2021/07/25 06:02:27 DEBUG : both1: md5 = abd65c1eed88f2f22150741c41b7da3c OK
2021/07/25 06:02:27 DEBUG : only1: md5 = 2fa2f1917f2a91f1d6035ae569be55f0 OK
2021/07/25 06:02:27 DEBUG : both2: md5 = a9a3527f4e97b697d0ed12c6e83d83ef OK
2021/07/25 06:02:28 DEBUG : only2: md5 = af7c9c3f56ae8c8977d4b0f3b447484a OK
2021/07/25 06:02:30 DEBUG : both3: md5 = 39937519746c26124c280499e85ac853 OK
2021/07/25 06:02:30 DEBUG : only3: md5 = 44fab0b17f6b7dca4e09016cde9cc3e8 OK
2021/07/25 06:02:30 DEBUG : both4: md5 = 6e9a69bd9cb95686264d060536f11bda OK
2021/07/25 06:02:31 DEBUG : only4: md5 = e3c664aae1c63883107b35f6b23c5da9 OK
2021/07/25 06:02:33 DEBUG : both5: md5 = 5f9e44b6836b8e04bda6ec804d10690d OK
2021/07/25 06:02:34 DEBUG : only5: md5 = af87eabdf2916039f3c78c3c4790de3f OK
2021/07/25 06:02:34 DEBUG : both6: md5 = 70df500f4c6d0330b0ba7ea27dfae57a OK
2021/07/25 06:02:35 DEBUG : only6: md5 = 43d7b6981d960f5ef4060381b0bbe0c8 OK
2021/07/25 06:02:35 DEBUG : both7: md5 = 2aa66b497f3fa79f36c6ae44257bc6b9 OK
2021/07/25 06:02:35 DEBUG : only7: md5 = 9855225ac639bf0f73cbf1e80f599142 OK
2021/07/25 06:02:36 DEBUG : both8: md5 = 400fb79cadad343b384fe1d36279a64c OK
2021/07/25 06:02:37 DEBUG : only8: md5 = 77c324968f37eb70dc86a03f0f752afd OK
2021/07/25 06:02:38 DEBUG : both9: md5 = e748b14faadced0d00bece08483aa508 OK
2021/07/25 06:02:39 DEBUG : only9: md5 = 08c08df695b518e3eb5b71ac841ded4c OK
2021/07/25 06:02:39 DEBUG : both10: md5 = bb32fc612441418f2e2d40ba6092f080 OK
2021/07/25 06:02:40 DEBUG : only10: md5 = 0f920cc16ff3d9b80d339fbee44b0136 OK
2021/07/25 06:02:41 DEBUG : both11: md5 = 010b1e09e08c03c606275308f56dcee1 OK
2021/07/25 06:02:41 DEBUG : only11: md5 = 1900274ddb57ede77f834ed1caab6e61 OK
2021/07/25 06:02:42 DEBUG : both12: md5 = dd1d84abc9bdced585a6912fc1f71ee1 OK
2021/07/25 06:02:42 DEBUG : only12: md5 = b71c7b02150e33ae6aaf2ac75fd94698 OK
2021/07/25 06:02:43 DEBUG : both13: md5 = 46798f4e441dd1f83d2dae117d2965d2 OK
2021/07/25 06:02:45 DEBUG : only13: md5 = 52c7863e0a25d9a66326601d22cc069b OK
2021/07/25 06:02:47 DEBUG : both14: md5 = 1269b75036cb00ded58e23522d2aed33 OK
2021/07/25 06:02:47 DEBUG : only14: md5 = 6abbd89d42dd4a77b85073c74272c7d1 OK
2021/07/25 06:02:47 DEBUG : both15: md5 = b4762250c3aec6b3c9787f6f18aaa519 OK
2021/07/25 06:02:48 DEBUG : only15: md5 = fb7fa1797636b5a48e79effb7de1afdb OK
2021/07/25 06:02:48 DEBUG : both16: md5 = 686b4856cd71e68847bb688aa0037d1a OK
2021/07/25 06:02:48 DEBUG : only16: md5 = e6af64055fb1562a6e153918921b2c7f OK
2021/07/25 06:02:49 DEBUG : both17: md5 = 12b9b309dfab192bf8d9cdc9d9970903 OK
2021/07/25 06:02:49 DEBUG : only17: md5 = 15cf303185b49a1e30c0ff04d0e618c9 OK
2021/07/25 06:02:51 DEBUG : both18: md5 = b981f426f8e8f4642a9d83ba7689f712 OK
2021/07/25 06:02:52 DEBUG : only18: md5 = ce2d155da4f49751291838b16c993fff OK
2021/07/25 06:02:52 DEBUG : both19: md5 = 68c5313a43227c6c34c46bed2c20400f OK
2021/07/25 06:02:53 DEBUG : only19: md5 = 8d5a3c192ebd7bb1c2fc8383748f6b0f OK
2021/07/25 06:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for checks to finish
2021/07/25 06:02:53 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both10: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both1: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both0: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both11: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both12: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both15: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both14: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both16: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both18: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both19: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both2: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both4: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both5: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both6: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both7: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both8: Unchanged skipping
2021/07/25 06:02:53 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:53 DEBUG : both9: Unchanged skipping
2021/07/25 06:02:53 DEBUG : only0: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only1: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only10: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only11: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only12: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only13: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only14: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only15: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only16: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only17: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only18: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only19: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only2: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only3: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only4: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only5: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only6: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only7: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only8: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:53 DEBUG : only9: Sizes differ (src 0 vs dst 6)
2021/07/25 06:02:54 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:54 DEBUG : both13: Unchanged skipping
2021/07/25 06:02:54 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:54 DEBUG : both17: Unchanged skipping
2021/07/25 06:02:54 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns)
2021/07/25 06:02:54 DEBUG : both3: Unchanged skipping
2021/07/25 06:02:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Waiting for transfers to finish
2021/07/25 06:02:54 DEBUG : only1: md5 = 551928c8977f25b339ca6357574c145d OK
2021/07/25 06:02:54 INFO  : only1: Copied (replaced existing)
2021/07/25 06:02:54 DEBUG : only12: md5 = 2641e410cc7a36bc2231ad729a19b956 OK
2021/07/25 06:02:54 INFO  : only12: Copied (replaced existing)
2021/07/25 06:02:54 DEBUG : only0: md5 = 5ab17afceb5a63338e5d3987c2602b5a OK
2021/07/25 06:02:54 INFO  : only0: Copied (replaced existing)
2021/07/25 06:02:54 DEBUG : only10: md5 = 3ce85900d8f2df29f952234feea24892 OK
2021/07/25 06:02:54 INFO  : only10: Copied (replaced existing)
2021/07/25 06:02:54 DEBUG : only14: md5 = c0cbd805bca1920e95da214017ad25a7 OK
2021/07/25 06:02:54 INFO  : only14: Copied (replaced existing)
2021/07/25 06:02:54 DEBUG : only11: md5 = e2e0646f7c18458c9d32d774324b9e7e OK
2021/07/25 06:02:54 INFO  : only11: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only15: md5 = 611c5e34720d0c1b45e799da439b3586 OK
2021/07/25 06:02:55 INFO  : only15: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only13: md5 = 7cd1f1dea85f56e7eb8de8e21bf39017 OK
2021/07/25 06:02:55 INFO  : only13: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only18: md5 = 772e3560bb0db0a07cd6bf9879990074 OK
2021/07/25 06:02:55 INFO  : only18: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only16: md5 = ef30a6152ba6cfe1511aacb23f86840b OK
2021/07/25 06:02:55 INFO  : only16: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only17: md5 = 8c666d4b1fbcf0921f423d6aa1434b26 OK
2021/07/25 06:02:55 INFO  : only17: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only3: md5 = 80da0af5ffa5aec82c3414e4e28fcbae OK
2021/07/25 06:02:55 INFO  : only3: Copied (replaced existing)
2021/07/25 06:02:55 DEBUG : only19: md5 = da604b62740810cfbbdf7767aac6ea95 OK
2021/07/25 06:02:55 INFO  : only19: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only4: md5 = ce1164eddddb5483874275ade7a78e7f OK
2021/07/25 06:02:56 INFO  : only4: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only2: md5 = 92c7ae34c351f835db0548e4a764f3a7 OK
2021/07/25 06:02:56 INFO  : only2: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only6: md5 = ffce3318cf92c434fa9521bfe49b19e6 OK
2021/07/25 06:02:56 INFO  : only6: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only8: md5 = 004b9681761d2327bcc6616e4c4c163c OK
2021/07/25 06:02:56 INFO  : only8: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only9: md5 = 7c383514f875e7543559238a74e50145 OK
2021/07/25 06:02:56 INFO  : only9: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only5: md5 = 269dc7a6481ca1c693fb82d1384ec0f5 OK
2021/07/25 06:02:56 INFO  : only5: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : only7: md5 = baa9bd9a6f09983200df8915765a1847 OK
2021/07/25 06:02:56 INFO  : only7: Copied (replaced existing)
2021/07/25 06:02:56 DEBUG : Waiting for deletions to finish
--- PASS: TestSyncConcurrentTruncate (52.70s)
PASS
2021/07/25 06:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-caqafag1gigulam2xuxived9': Purge remote
2021/07/25 06:03:15 DEBUG : Waiting for deletions to finish
"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 8m3.981647868s (try 1/5)