"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2022/05/18 05:22:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3" 2022/05/18 05:22:44 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/05/18 05:22:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3.bin" 2022/05/18 05:22:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3" 2022/05/18 05:22:45 DEBUG : Creating backend with remote "/tmp/rclone2890825392" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:45 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/05/18 05:22:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.38s) === RUN TestCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:46 DEBUG : sub dir/hello world: md5 = 4cc60a372f395221f2638945a9088095 OK 2022/05/18 05:22:46 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.09s) === RUN TestCopyMissingDirectory run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:47 DEBUG : Creating backend with remote "/non-existing" 2022/05/18 05:22:47 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/05/18 05:22:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.38s) === RUN TestCopyNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:49 DEBUG : sub dir/hello world: md5 = 3062b0544ae953601cfadb5fcaca9bf9 OK 2022/05/18 05:22:49 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.37s) === RUN TestCopyCheckFirst run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Running all checks before starting transfers 2022/05/18 05:22:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Checks finished, now starting transfers 2022/05/18 05:22:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:50 DEBUG : sub dir/hello world: md5 = 5ff6d761526865f2c80087c006ac9403 OK 2022/05/18 05:22:50 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.01s) === RUN TestSyncNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:50 ERROR : Ignoring --no-traverse with sync 2022/05/18 05:22:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:51 DEBUG : sub dir/hello world: md5 = c18f3e4a043c47c808efb3a75c099b36 OK 2022/05/18 05:22:51 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:22:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.93s) === RUN TestCopyWithDepth run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:52 DEBUG : hello world2: md5 = 08766efa00dfe0e78173d2f93b186758 OK 2022/05/18 05:22:52 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.64s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:53 DEBUG : hello world2: Excluded 2022/05/18 05:22:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:53 DEBUG : potato2: md5 = a7e65b28dbd3d52b0f5840f5dbaf7bf8 OK 2022/05/18 05:22:53 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.15s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:54 DEBUG : potato2: md5 = fa05f7eded43c4705daf89fb86ee5876 OK 2022/05/18 05:22:54 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.90s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:55 DEBUG : sub dir2: Making directory 2022/05/18 05:22:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:55 DEBUG : sub dir/hello world: md5 = 5a021713023e380a7b9c14857c8f7501 OK 2022/05/18 05:22:55 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:22:55 DEBUG : sub dir2: Making directory 2022/05/18 05:22:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.20s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:56 DEBUG : sub dir2: Making directory 2022/05/18 05:22:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:58 DEBUG : sub dir/hello world: md5 = 57a488977d90819ba6ae856b25246048 OK 2022/05/18 05:22:58 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:22:58 INFO : sub dir/hello world: Deleted 2022/05/18 05:22:58 DEBUG : sub dir: Making directory 2022/05/18 05:22:58 DEBUG : sub dir2: Making directory 2022/05/18 05:22:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (2.50s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:22:58 DEBUG : sub dir2: Making directory 2022/05/18 05:22:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:22:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:22:59 DEBUG : sub dir/hello world: md5 = f93c621748b5b3ed3911910b41e18c32 OK 2022/05/18 05:22:59 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:22:59 DEBUG : sub dir2: Making directory 2022/05/18 05:22:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': copied 1 directories 2022/05/18 05:22:59 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.68s) === RUN TestServerSideCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:01 DEBUG : sub dir/hello world: md5 = b2d01f8cdc028ab1075dbcc45f0edff3 OK 2022/05/18 05:23:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lomopoc3qefazof1yagagef5" 2022/05/18 05:23:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lomopoc3qefazof1yagagef5.bin" 2022/05/18 05:23:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lomopoc3qefazof1yagagef5" sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3' -> Encrypted drive 'TestCryptSwift:rclone-test-lomopoc3qefazof1yagagef5' 2022/05/18 05:23:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lomopoc3qefazof1yagagef5': Waiting for checks to finish 2022/05/18 05:23:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lomopoc3qefazof1yagagef5': Waiting for transfers to finish 2022/05/18 05:23:04 INFO : sub dir/hello world: Copied (server-side copy) 2022/05/18 05:23:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lomopoc3qefazof1yagagef5': Purge remote 2022/05/18 05:23:04 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:05 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (5.23s) === RUN TestCopyAfterDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:06 DEBUG : sub dir/hello world: md5 = 75099ca6b60cddbb6c1122ba7ea7d069 OK 2022/05/18 05:23:06 ERROR : : error listing: directory not found 2022/05/18 05:23:06 DEBUG : Local file system at /tmp/rclone2890825392: Making directory 2022/05/18 05:23:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (3.69s) === RUN TestCopyRedownload run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:09 DEBUG : sub dir/hello world: md5 = c57ed00d8d1e31eb275bd5e53b53f91e OK 2022/05/18 05:23:10 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for checks to finish 2022/05/18 05:23:10 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for transfers to finish 2022/05/18 05:23:10 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.71s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:11 DEBUG : check sum: md5 = 6b4d4771f9d3da0354b574487f82bc44 OK 2022/05/18 05:23:11 INFO : check sum: Copied (new) 2022/05/18 05:23:11 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:12 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/05/18 05:23:12 DEBUG : check sum: Size of src and dst objects identical 2022/05/18 05:23:12 DEBUG : check sum: Unchanged skipping 2022/05/18 05:23:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:12 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:12 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.52s) === RUN TestSyncSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:13 DEBUG : sizeonly: md5 = ae5613b5f3639c61eb81c4a4c4756245 OK 2022/05/18 05:23:13 INFO : sizeonly: Copied (new) 2022/05/18 05:23:13 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:14 DEBUG : sizeonly: Sizes identical 2022/05/18 05:23:14 DEBUG : sizeonly: Unchanged skipping 2022/05/18 05:23:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:14 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:14 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.01s) === RUN TestSyncIgnoreSize run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:15 DEBUG : ignore-size: md5 = 1348e85ba75721f0e7c1b198f6f18ee9 OK 2022/05/18 05:23:15 INFO : ignore-size: Copied (new) 2022/05/18 05:23:15 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:15 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:15 DEBUG : ignore-size: Unchanged skipping 2022/05/18 05:23:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:15 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.77s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:17 DEBUG : existing: md5 = 6bbdba74c21ac92bda2c9016f9c5ef7d OK 2022/05/18 05:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:17 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:17 DEBUG : existing: Unchanged skipping 2022/05/18 05:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:17 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:17 INFO : There was nothing to transfer 2022/05/18 05:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:17 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/05/18 05:23:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:17 DEBUG : existing: md5 = 918df827d2771dde081261dd1c143cab OK 2022/05/18 05:23:17 INFO : existing: Copied (replaced existing) 2022/05/18 05:23:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.08s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:19 DEBUG : existing: md5 = 29271bba7eb4191ab0b26acf0d9c8c25 OK 2022/05/18 05:23:19 INFO : existing: Copied (new) 2022/05/18 05:23:19 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:19 DEBUG : existing: Destination exists, skipping 2022/05/18 05:23:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:19 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:19 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.23s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:20 DEBUG : b/potato: md5 = 0b580a10d2807fd4b6a129e311e0c19d OK 2022/05/18 05:23:21 DEBUG : c/non empty space: md5 = 59d94e95ca1e92cdeae2ef115c3f06cf OK 2022/05/18 05:23:21 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/05/18 05:23:21 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:21 DEBUG : c/non empty space: Unchanged skipping 2022/05/18 05:23:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:22 DEBUG : a/potato2: md5 = 2d8bc23e8715b1273d5bb781afa60376 OK 2022/05/18 05:23:22 INFO : a/potato2: Copied (new) 2022/05/18 05:23:22 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:22 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (3.89s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:24 DEBUG : empty space: md5 = 2342d82c1866ad5712eed5314b88a25c OK 2022/05/18 05:23:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:24 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/05/18 05:23:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:24 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2022/05/18 05:23:24 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:25 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/05/18 05:23:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:25 DEBUG : empty space: md5 = 88949fb336186ec63b2c4a6176c62325 OK 2022/05/18 05:23:25 INFO : empty space: Copied (replaced existing) 2022/05/18 05:23:25 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (2.28s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.37s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:26 DEBUG : foo: md5 = b9e8047df1dce9c55958d9535ea2a087 OK 2022/05/18 05:23:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:27 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/05/18 05:23:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:27 DEBUG : foo: md5 = 4005b878951ae9159def50fdd28c4585 OK 2022/05/18 05:23:27 INFO : foo: Copied (replaced existing) 2022/05/18 05:23:27 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (1.79s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:28 DEBUG : empty space: md5 = ae4041fd8a52d30efa52be5272cd9767 OK 2022/05/18 05:23:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:29 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:29 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:29 DEBUG : potato: md5 = 15f22c350085cbab15d3b02ca579eef1 OK 2022/05/18 05:23:29 INFO : potato: Copied (new) 2022/05/18 05:23:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.41s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:30 DEBUG : potato: md5 = 94ed252d9b5eadb12745d872ba853c70 OK 2022/05/18 05:23:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:31 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/05/18 05:23:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:31 DEBUG : potato: md5 = 5b0651669adf166d15eab4b32c874497 OK 2022/05/18 05:23:31 INFO : potato: Copied (replaced existing) 2022/05/18 05:23:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.58s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:32 DEBUG : potato: md5 = 01617b39cf76fb915e9e2e74cb06a150 OK 2022/05/18 05:23:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:33 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2022/05/18 05:23:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:33 DEBUG : potato: md5 = 38dc372fff2ee62093d90826c4cc9730 OK 2022/05/18 05:23:33 INFO : potato: Copied (replaced existing) 2022/05/18 05:23:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (2.89s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:35 DEBUG : potato: md5 = d9739e5336e320c6914821f16a6975ac OK 2022/05/18 05:23:36 DEBUG : empty space: md5 = 33f1bf151c561b47da2750b30515bf73 OK 2022/05/18 05:23:36 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/05/18 05:23:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:36 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:36 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:36 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:36 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.00s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:37 DEBUG : potato: md5 = c8669da19955d7d3cf6e05e42cc83504 OK 2022/05/18 05:23:37 DEBUG : empty space: md5 = f1ee23fbf4591a095806891de386f558 OK 2022/05/18 05:23:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:38 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:38 DEBUG : potato2: md5 = 901923e6c729bd38a020f1318da09620 OK 2022/05/18 05:23:38 INFO : potato2: Copied (new) 2022/05/18 05:23:38 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:38 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.34s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:40 DEBUG : b/potato: md5 = a6bc2ce85c1fdb04c01036f3bf57262d OK 2022/05/18 05:23:41 DEBUG : c/non empty space: md5 = c8f4520615c84304414ddb14fce13004 OK 2022/05/18 05:23:41 DEBUG : d: Making directory 2022/05/18 05:23:41 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/05/18 05:23:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:41 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:41 DEBUG : c/non empty space: Unchanged skipping 2022/05/18 05:23:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:41 DEBUG : a/potato2: md5 = ea8b4a0ee95956cd5d3e1180a88f8d6a OK 2022/05/18 05:23:41 INFO : a/potato2: Copied (new) 2022/05/18 05:23:41 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:42 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.41s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:43 DEBUG : b/potato: md5 = 459a5b468aebbe01e4d15c518f1ca73f OK 2022/05/18 05:23:43 DEBUG : c/non empty space: md5 = 2c70e26ba021f9e21f7c6abee14b99a7 OK 2022/05/18 05:23:43 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/05/18 05:23:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:43 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:43 DEBUG : c/non empty space: Unchanged skipping 2022/05/18 05:23:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:44 DEBUG : a/potato2: md5 = 7b135fa96582009e7e13a1e9a6b8bb30 OK 2022/05/18 05:23:44 INFO : a/potato2: Copied (new) 2022/05/18 05:23:44 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': not deleting files as there were IO errors 2022/05/18 05:23:44 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.38s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:45 DEBUG : potato: md5 = c15c8c7c129c91eb08b053eaed7989c5 OK 2022/05/18 05:23:46 DEBUG : empty space: md5 = 8aa80729662924aa93093e7b81f96be7 OK 2022/05/18 05:23:46 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:46 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:46 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:46 INFO : potato: Deleted 2022/05/18 05:23:46 DEBUG : potato2: md5 = cbdd5b1ad16855669d71eea6d0de6198 OK 2022/05/18 05:23:46 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.35s) === RUN TestSyncDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:47 DEBUG : potato: md5 = 5b46d3666d70de0af307bcb1aae5060d OK 2022/05/18 05:23:48 DEBUG : empty space: md5 = cd8df1726167fa8d961ed36bbaf2699b OK 2022/05/18 05:23:48 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:48 INFO : potato: Deleted 2022/05/18 05:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:48 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:48 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:49 DEBUG : potato2: md5 = 085f07eee9f1e5a5ced1b85e86d3bde3 OK 2022/05/18 05:23:49 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (2.42s) === RUN TestCopyDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:50 DEBUG : potato: md5 = 46fd5d90a8f79c32b1fce372563ee12e OK 2022/05/18 05:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:51 DEBUG : potato2: md5 = 23d4dcc486824d1e6a1e861e1ea6cf76 OK 2022/05/18 05:23:51 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.78s) === RUN TestSyncWithExclude run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:53 DEBUG : potato2: md5 = 96eddb9af74eac49c8bbe93867c982c5 OK 2022/05/18 05:23:53 DEBUG : empty space: md5 = bb5b6a12833fc2615614ce3499dcd7ae OK 2022/05/18 05:23:53 DEBUG : enormous: Excluded 2022/05/18 05:23:53 DEBUG : potato2: Excluded 2022/05/18 05:23:54 DEBUG : potato2: Excluded 2022/05/18 05:23:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:54 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:54 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:54 INFO : There was nothing to transfer 2022/05/18 05:23:54 DEBUG : enormous: Excluded 2022/05/18 05:23:54 DEBUG : potato2: Excluded 2022/05/18 05:23:54 DEBUG : potato2: Excluded 2022/05/18 05:23:54 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for checks to finish 2022/05/18 05:23:54 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:54 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:54 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for transfers to finish 2022/05/18 05:23:54 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:54 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.25s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:55 DEBUG : potato2: md5 = b980404f9f96482d19fa5d8b8b064535 OK 2022/05/18 05:23:56 DEBUG : empty space: md5 = c50953e55590c9d4b02062bc784810c7 OK 2022/05/18 05:23:56 DEBUG : enormous: md5 = 7715d0ee41b774f69f79308c11a93944 OK 2022/05/18 05:23:57 DEBUG : enormous: Excluded 2022/05/18 05:23:57 DEBUG : potato2: Excluded 2022/05/18 05:23:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:23:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:57 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:23:57 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:57 INFO : potato2: Deleted 2022/05/18 05:23:57 INFO : enormous: Deleted 2022/05/18 05:23:57 INFO : There was nothing to transfer 2022/05/18 05:23:57 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for checks to finish 2022/05/18 05:23:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:23:57 DEBUG : empty space: Unchanged skipping 2022/05/18 05:23:57 DEBUG : Local file system at /tmp/rclone2890825392: Waiting for transfers to finish 2022/05/18 05:23:57 DEBUG : Waiting for deletions to finish 2022/05/18 05:23:57 INFO : potato2: Deleted 2022/05/18 05:23:57 INFO : enormous: Deleted 2022/05/18 05:23:57 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (3.28s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:23:58 DEBUG : one: md5 = dccfe1075e6230caa3b57a4f9c2ad3f8 OK 2022/05/18 05:23:59 DEBUG : two: md5 = 62623693aafdac0f761d094300fdb808 OK 2022/05/18 05:23:59 DEBUG : three: md5 = c7dbc4fb2a35cb5f8dd93eaceface052 OK 2022/05/18 05:24:00 DEBUG : four: md5 = d155d08cda42a972d3f9f2955d01c32b OK 2022/05/18 05:24:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:00 DEBUG : three: Sizes identical 2022/05/18 05:24:00 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/05/18 05:24:00 DEBUG : one: Destination is newer than source, skipping 2022/05/18 05:24:00 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/05/18 05:24:00 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/05/18 05:24:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:01 DEBUG : four: md5 = c26e7a035ef888ec6f8e5f99d876a018 OK 2022/05/18 05:24:01 INFO : four: Copied (replaced existing) 2022/05/18 05:24:01 DEBUG : two: md5 = 0dfa9d6335a81449a8cb8d4c56d4b7bf OK 2022/05/18 05:24:01 INFO : two: Copied (replaced existing) 2022/05/18 05:24:01 DEBUG : five: md5 = c4e4f3250a942d9b05b02a29429d2f72 OK 2022/05/18 05:24:01 INFO : five: Copied (new) 2022/05/18 05:24:01 DEBUG : Waiting for deletions to finish sync_test.go:992: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.74s) === RUN TestSyncWithMaxDuration sync_test.go:1009: Skipping test on non local remote --- SKIP: TestSyncWithMaxDuration (0.00s) === RUN TestSyncWithTrackRenames run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1058: Can track renames: false 2022/05/18 05:24:03 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Ignoring --track-renames as the source and destination do not have a common hash 2022/05/18 05:24:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:03 DEBUG : potato: md5 = 368e0868b480bac9c55a611f83964e2e OK 2022/05/18 05:24:03 INFO : potato: Copied (new) 2022/05/18 05:24:03 DEBUG : yam: md5 = 7b7c6911e50434e6dfcbd5a6518b420a OK 2022/05/18 05:24:03 INFO : yam: Copied (new) 2022/05/18 05:24:03 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:04 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Ignoring --track-renames as the source and destination do not have a common hash 2022/05/18 05:24:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:04 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:04 DEBUG : potato: Unchanged skipping 2022/05/18 05:24:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:06 DEBUG : yaml: md5 = 61b8313277cffbbbba32365aebda1213 OK 2022/05/18 05:24:06 INFO : yaml: Copied (new) 2022/05/18 05:24:06 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:06 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (4.10s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/05/18 05:24:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Making map for --track-renames 2022/05/18 05:24:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Finished making map for --track-renames 2022/05/18 05:24:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for renames to finish 2022/05/18 05:24:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:07 DEBUG : yam: md5 = eadefe707ab777b61286e4fb5710dab7 OK 2022/05/18 05:24:07 INFO : yam: Copied (new) 2022/05/18 05:24:08 DEBUG : potato: md5 = 2dd5bd713d40426d017b165fd6fadf0c OK 2022/05/18 05:24:08 INFO : potato: Copied (new) 2022/05/18 05:24:08 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:08 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Making map for --track-renames 2022/05/18 05:24:08 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Finished making map for --track-renames 2022/05/18 05:24:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:08 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:08 DEBUG : potato: Unchanged skipping 2022/05/18 05:24:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for renames to finish 2022/05/18 05:24:08 INFO : yam: Copied (server-side copy) to: yaml 2022/05/18 05:24:08 INFO : yam: Deleted 2022/05/18 05:24:08 INFO : yaml: Renamed from "yam" 2022/05/18 05:24:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:08 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.48s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/05/18 05:24:09 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Making map for --track-renames 2022/05/18 05:24:09 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Finished making map for --track-renames 2022/05/18 05:24:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for renames to finish 2022/05/18 05:24:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:10 DEBUG : potato: md5 = 9c26e428f61f675449c9f83b7695885c OK 2022/05/18 05:24:10 INFO : potato: Copied (new) 2022/05/18 05:24:11 DEBUG : sub/yam: md5 = d6062bbc6edef35a1a0837b00a5812fa OK 2022/05/18 05:24:11 INFO : sub/yam: Copied (new) 2022/05/18 05:24:11 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:11 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:11 DEBUG : potato: Unchanged skipping 2022/05/18 05:24:11 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Making map for --track-renames 2022/05/18 05:24:11 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Finished making map for --track-renames 2022/05/18 05:24:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for renames to finish 2022/05/18 05:24:12 INFO : sub/yam: Copied (server-side copy) to: yam 2022/05/18 05:24:12 INFO : sub/yam: Deleted 2022/05/18 05:24:12 INFO : yam: Renamed from "sub/yam" 2022/05/18 05:24:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.54s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:13 DEBUG : sub dir/hello world: md5 = 7669ddd38ca3df5271252908c989c200 OK 2022/05/18 05:24:13 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:24:13 INFO : sub dir/hello world: Deleted 2022/05/18 05:24:13 DEBUG : nested/sub dir/file: md5 = cdd9bd08de8897342e10372d73a852bd OK 2022/05/18 05:24:13 INFO : nested/sub dir/file: Copied (new) 2022/05/18 05:24:13 INFO : nested/sub dir/file: Deleted 2022/05/18 05:24:13 INFO : sub dir: Removing directory 2022/05/18 05:24:13 INFO : nested/sub dir: Removing directory 2022/05/18 05:24:13 INFO : nested: Removing directory 2022/05/18 05:24:13 DEBUG : Local file system at /tmp/rclone2890825392: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.16s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:15 DEBUG : sub dir/hello world: md5 = 87de8642dccd78bdf81cd859b406809e OK 2022/05/18 05:24:15 INFO : sub dir/hello world: Copied (new) 2022/05/18 05:24:15 INFO : sub dir/hello world: Deleted 2022/05/18 05:24:15 DEBUG : nested/sub dir/file: md5 = 33fc1cb9c81c56aa96e02b2f90758ff1 OK 2022/05/18 05:24:15 INFO : nested/sub dir/file: Copied (new) 2022/05/18 05:24:15 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.32s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:17 DEBUG : existing: md5 = 35106c0616a41b8b617e7955dd6e084d OK 2022/05/18 05:24:17 INFO : existing: Copied (new) 2022/05/18 05:24:17 INFO : existing: Deleted 2022/05/18 05:24:17 DEBUG : existing-b: md5 = 6edbf9b0b4b704c6d51bdc11de4c64da OK 2022/05/18 05:24:17 INFO : existing-b: Copied (new) 2022/05/18 05:24:17 INFO : existing-b: Deleted 2022/05/18 05:24:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:24:17 DEBUG : existing: Destination exists, skipping 2022/05/18 05:24:17 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/05/18 05:24:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:24:17 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (1.54s) === RUN TestServerSideMove run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xeraxat2wiweyep7xihayud6" 2022/05/18 05:24:18 DEBUG : Config file has changed externaly - reloading 2022/05/18 05:24:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xeraxat2wiweyep7xihayud6.bin" 2022/05/18 05:24:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xeraxat2wiweyep7xihayud6" 2022/05/18 05:24:19 DEBUG : potato2: md5 = 8221891803ea8619d2ee8581555d9d69 OK 2022/05/18 05:24:20 DEBUG : empty space: md5 = aafe80ff903464d27af3f4a4cd66aa10 OK 2022/05/18 05:24:20 DEBUG : potato3: md5 = 7a9611a6194d79081700c99bced5f962 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3' -> Encrypted drive 'TestCryptSwift:rclone-test-xeraxat2wiweyep7xihayud6' 2022/05/18 05:24:21 DEBUG : empty space: md5 = 5958678453176f5717268e4b77085080 OK 2022/05/18 05:24:22 DEBUG : potato3: md5 = 8eebf847a39bdae449a355557254c023 OK 2022/05/18 05:24:22 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/05/18 05:24:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xeraxat2wiweyep7xihayud6': Waiting for checks to finish 2022/05/18 05:24:22 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:22 DEBUG : empty space: Unchanged skipping 2022/05/18 05:24:22 INFO : empty space: Deleted 2022/05/18 05:24:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xeraxat2wiweyep7xihayud6': Waiting for transfers to finish 2022/05/18 05:24:23 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:23 INFO : potato2: Deleted 2022/05/18 05:24:23 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:23 INFO : potato3: Deleted 2022/05/18 05:24:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-finazut0wuheleq3cewiwor2" 2022/05/18 05:24:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-finazut0wuheleq3cewiwor2.bin" 2022/05/18 05:24:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-finazut0wuheleq3cewiwor2" 2022/05/18 05:24:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-finazut0wuheleq3cewiwor2': Waiting for checks to finish 2022/05/18 05:24:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-finazut0wuheleq3cewiwor2': Waiting for transfers to finish 2022/05/18 05:24:25 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:26 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:26 INFO : potato3: Deleted 2022/05/18 05:24:26 INFO : empty space: Copied (server-side copy) 2022/05/18 05:24:26 INFO : empty space: Deleted 2022/05/18 05:24:26 INFO : potato2: Deleted 2022/05/18 05:24:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-finazut0wuheleq3cewiwor2': Purge remote 2022/05/18 05:24:26 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:27 INFO : potato2.bin: Deleted 2022/05/18 05:24:27 INFO : empty space.bin: Deleted 2022/05/18 05:24:27 INFO : potato3.bin: Deleted 2022/05/18 05:24:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xeraxat2wiweyep7xihayud6': Purge remote 2022/05/18 05:24:27 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (9.31s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-quqofex6bahiceb6lolirix9" 2022/05/18 05:24:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quqofex6bahiceb6lolirix9.bin" 2022/05/18 05:24:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quqofex6bahiceb6lolirix9" 2022/05/18 05:24:28 DEBUG : potato2: md5 = 179967b96809b39c813ccbd5cbd57ba4 OK 2022/05/18 05:24:29 DEBUG : empty space: md5 = 5dc659071c9c387b31bb603a1485b172 OK 2022/05/18 05:24:29 DEBUG : potato3: md5 = 05aadb02382e58410456a122f3e74630 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3' -> Encrypted drive 'TestCryptSwift:rclone-test-quqofex6bahiceb6lolirix9' 2022/05/18 05:24:31 DEBUG : empty space: md5 = ebd84e72e7b92307f81eef98ad50b776 OK 2022/05/18 05:24:31 DEBUG : potato3: md5 = edbbb3a6c98e20df26a1957eddcb2918 OK 2022/05/18 05:24:31 DEBUG : empty space: Excluded 2022/05/18 05:24:31 DEBUG : empty space: Excluded 2022/05/18 05:24:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqofex6bahiceb6lolirix9': Waiting for checks to finish 2022/05/18 05:24:31 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/05/18 05:24:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqofex6bahiceb6lolirix9': Waiting for transfers to finish 2022/05/18 05:24:32 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:32 INFO : potato3: Deleted 2022/05/18 05:24:32 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:32 INFO : potato2: Deleted 2022/05/18 05:24:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zelohef0zatohut1todarob6" 2022/05/18 05:24:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zelohef0zatohut1todarob6.bin" 2022/05/18 05:24:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zelohef0zatohut1todarob6" 2022/05/18 05:24:34 DEBUG : empty space: Excluded 2022/05/18 05:24:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zelohef0zatohut1todarob6': Waiting for checks to finish 2022/05/18 05:24:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zelohef0zatohut1todarob6': Waiting for transfers to finish 2022/05/18 05:24:35 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:35 INFO : potato2: Deleted 2022/05/18 05:24:35 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:35 INFO : potato3: Deleted 2022/05/18 05:24:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zelohef0zatohut1todarob6': Purge remote 2022/05/18 05:24:36 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:36 INFO : potato3.bin: Deleted 2022/05/18 05:24:37 INFO : potato2.bin: Deleted 2022/05/18 05:24:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqofex6bahiceb6lolirix9': Purge remote 2022/05/18 05:24:37 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:37 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (10.62s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xibahif1mobuvaf8tedohaz0" 2022/05/18 05:24:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xibahif1mobuvaf8tedohaz0.bin" 2022/05/18 05:24:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xibahif1mobuvaf8tedohaz0" 2022/05/18 05:24:39 DEBUG : potato2: md5 = 7e0937304be6198457d98975882e2ab8 OK 2022/05/18 05:24:39 DEBUG : empty space: md5 = d4585a249ce05c68f38c9ca03562e348 OK 2022/05/18 05:24:40 DEBUG : potato3: md5 = aff76cd1ab364262cbd9b847287375a0 OK 2022/05/18 05:24:40 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3' -> Encrypted drive 'TestCryptSwift:rclone-test-xibahif1mobuvaf8tedohaz0' 2022/05/18 05:24:41 DEBUG : empty space: md5 = 266658c5ed942fb547144cde5e441cbe OK 2022/05/18 05:24:42 DEBUG : potato3: md5 = f84413f6c7df31c0a2a7132550b9aa3b OK 2022/05/18 05:24:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xibahif1mobuvaf8tedohaz0': Waiting for checks to finish 2022/05/18 05:24:42 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/05/18 05:24:42 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:42 DEBUG : empty space: Unchanged skipping 2022/05/18 05:24:43 INFO : empty space: Deleted 2022/05/18 05:24:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xibahif1mobuvaf8tedohaz0': Waiting for transfers to finish 2022/05/18 05:24:43 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:43 INFO : potato2: Deleted 2022/05/18 05:24:43 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:43 INFO : potato3: Deleted 2022/05/18 05:24:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-quveqof6wodomoy7tivilak4" 2022/05/18 05:24:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quveqof6wodomoy7tivilak4.bin" 2022/05/18 05:24:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quveqof6wodomoy7tivilak4" 2022/05/18 05:24:45 DEBUG : tomatoDir: Making directory 2022/05/18 05:24:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quveqof6wodomoy7tivilak4': Waiting for checks to finish 2022/05/18 05:24:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quveqof6wodomoy7tivilak4': Waiting for transfers to finish 2022/05/18 05:24:46 INFO : potato3: Copied (server-side copy) 2022/05/18 05:24:46 INFO : potato2: Copied (server-side copy) 2022/05/18 05:24:46 INFO : potato3: Deleted 2022/05/18 05:24:46 INFO : empty space: Copied (server-side copy) 2022/05/18 05:24:46 INFO : potato2: Deleted 2022/05/18 05:24:46 INFO : empty space: Deleted 2022/05/18 05:24:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quveqof6wodomoy7tivilak4': Purge remote 2022/05/18 05:24:47 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:47 INFO : potato3.bin: Deleted 2022/05/18 05:24:47 INFO : potato2.bin: Deleted 2022/05/18 05:24:47 INFO : empty space.bin: Deleted 2022/05/18 05:24:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xibahif1mobuvaf8tedohaz0': Purge remote 2022/05/18 05:24:47 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.76s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/rclone-move-test" 2022/05/18 05:24:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/rclone-move-test.bin" 2022/05/18 05:24:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/rclone-move-test" 2022/05/18 05:24:49 DEBUG : potato2: md5 = bcb0dfe0df0993399acb36871c434851 OK --- PASS: TestServerSideMoveOverlap (2.33s) === RUN TestSyncOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:50 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/rclone-sync-test" 2022/05/18 05:24:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/rclone-sync-test.bin" 2022/05/18 05:24:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/rclone-sync-test" --- PASS: TestSyncOverlap (1.52s) === RUN TestSyncCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:24:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:24:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:24:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:24:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/CompareDest" 2022/05/18 05:24:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/CompareDest.bin" 2022/05/18 05:24:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/CompareDest" 2022/05/18 05:24:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:24:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:24:54 DEBUG : one: md5 = 01f0c66dde80a3485db4f125a9beb954 OK 2022/05/18 05:24:54 INFO : one: Copied (new) 2022/05/18 05:24:54 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:24:55 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/05/18 05:24:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:24:55 DEBUG : one: md5 = 94ada7be7ed8143f5115e1277c1036b9 OK 2022/05/18 05:24:55 INFO : one: Copied (replaced existing) 2022/05/18 05:24:55 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:56 DEBUG : dst/one: md5 = 0a443884ea86d3068d95635a5c97fdf2 OK 2022/05/18 05:24:57 DEBUG : CompareDest/one: md5 = ce24cf137f7ba9bcfdbb0f6129133a31 OK 2022/05/18 05:24:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:24:57 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:57 DEBUG : one: Destination found in --compare-dest, skipping 2022/05/18 05:24:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:24:57 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:57 INFO : There was nothing to transfer 2022/05/18 05:24:59 DEBUG : CompareDest/two: md5 = 845227ff2d4bbe0c0a5486d2051d84c4 OK 2022/05/18 05:24:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:59 DEBUG : two: Destination found in --compare-dest, skipping 2022/05/18 05:24:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:24:59 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:59 DEBUG : one: Destination found in --compare-dest, skipping 2022/05/18 05:24:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:24:59 DEBUG : Waiting for deletions to finish 2022/05/18 05:24:59 INFO : There was nothing to transfer 2022/05/18 05:24:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:24:59 DEBUG : two: Destination found in --compare-dest, skipping 2022/05/18 05:24:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:00 DEBUG : one: Destination found in --compare-dest, skipping 2022/05/18 05:25:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:00 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:00 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/05/18 05:25:00 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/05/18 05:25:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:00 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:00 DEBUG : one: Destination found in --compare-dest, skipping 2022/05/18 05:25:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:01 DEBUG : two: md5 = 3c8c0748e5a9b958caabe590eec83dc5 OK 2022/05/18 05:25:01 INFO : two: Copied (new) 2022/05/18 05:25:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (10.76s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:25:02 DEBUG : pre-dest1/1: md5 = 357449f55f7b676465f03692c4d77668 OK 2022/05/18 05:25:04 DEBUG : pre-dest2/2: md5 = d023a924eaad4df644422b5700ed30e3 OK 2022/05/18 05:25:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dest" 2022/05/18 05:25:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dest.bin" 2022/05/18 05:25:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dest" 2022/05/18 05:25:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/pre-dest1" 2022/05/18 05:25:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/pre-dest1.bin" 2022/05/18 05:25:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/pre-dest1" 2022/05/18 05:25:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/pre-dest2" 2022/05/18 05:25:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/pre-dest2.bin" 2022/05/18 05:25:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/pre-dest2" 2022/05/18 05:25:08 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:08 DEBUG : 1: Destination found in --compare-dest, skipping 2022/05/18 05:25:08 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:08 DEBUG : 2: Destination found in --compare-dest, skipping 2022/05/18 05:25:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dest': Waiting for checks to finish 2022/05/18 05:25:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dest': Waiting for transfers to finish 2022/05/18 05:25:12 DEBUG : 3: md5 = d875ce0974d719b01106f83d96b9e813 OK 2022/05/18 05:25:12 INFO : 3: Copied (new) 2022/05/18 05:25:12 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (11.31s) === RUN TestSyncCopyDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:25:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:25:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/CopyDest" 2022/05/18 05:25:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/CopyDest.bin" 2022/05/18 05:25:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/CopyDest" 2022/05/18 05:25:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:16 DEBUG : one: md5 = 968668b4b4e69e2184cfa78253130556 OK 2022/05/18 05:25:16 INFO : one: Copied (new) 2022/05/18 05:25:16 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:16 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/05/18 05:25:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:17 DEBUG : one: md5 = 6459890a64598dcaabbac9cb09c14c8e OK 2022/05/18 05:25:17 INFO : one: Copied (replaced existing) 2022/05/18 05:25:17 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:17 DEBUG : dst/one: md5 = a3b815d0ffdb22dd3cbe0bf53e44cf4e OK 2022/05/18 05:25:18 DEBUG : CopyDest/one: md5 = 3579250372b625ed4ea93079307c1e74 OK 2022/05/18 05:25:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/BackupDir" 2022/05/18 05:25:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/BackupDir.bin" 2022/05/18 05:25:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/BackupDir" 2022/05/18 05:25:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:19 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/05/18 05:25:20 INFO : one: Copied (server-side copy) 2022/05/18 05:25:20 INFO : one: Deleted 2022/05/18 05:25:20 INFO : one: Copied (server-side copy) 2022/05/18 05:25:20 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/05/18 05:25:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:20 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:22 DEBUG : CopyDest/two: md5 = 4b0399075ab19af629c6e176c85e18ee OK 2022/05/18 05:25:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:23 INFO : two: Copied (server-side copy) 2022/05/18 05:25:23 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/05/18 05:25:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:23 DEBUG : one: Unchanged skipping 2022/05/18 05:25:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:23 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:24 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:24 DEBUG : one: Unchanged skipping 2022/05/18 05:25:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:24 DEBUG : two: Unchanged skipping 2022/05/18 05:25:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:24 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:24 INFO : There was nothing to transfer 2022/05/18 05:25:25 DEBUG : CopyDest/three: md5 = 3d1bf2152e316b7a55668cb632034cf2 OK 2022/05/18 05:25:26 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/05/18 05:25:26 DEBUG : three: Destination not found in --copy-dest 2022/05/18 05:25:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:26 DEBUG : one: Unchanged skipping 2022/05/18 05:25:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:26 DEBUG : two: Unchanged skipping 2022/05/18 05:25:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:26 DEBUG : three: md5 = c60917b39dfb8bc6ec143cf415ff3a91 OK 2022/05/18 05:25:26 INFO : three: Copied (new) 2022/05/18 05:25:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (14.95s) === RUN TestSyncBackupDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:25:29 DEBUG : dst/one: md5 = 4fe521a2168265c8a3673fce4832e4b4 OK 2022/05/18 05:25:29 DEBUG : dst/two: md5 = b4663bffa0d4da1ca8dc8540bf310082 OK 2022/05/18 05:25:29 DEBUG : dst/three.txt: md5 = 5ed5ba61a5cfc76398bf2e51689d36f8 OK 2022/05/18 05:25:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:25:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:30 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:30 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup.bin" 2022/05/18 05:25:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:25:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:32 DEBUG : two: Unchanged skipping 2022/05/18 05:25:33 INFO : one: Copied (server-side copy) 2022/05/18 05:25:33 INFO : one: Deleted 2022/05/18 05:25:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:33 DEBUG : one: md5 = ccb918c1742041ba389a9ea66baef20f OK 2022/05/18 05:25:33 INFO : one: Copied (new) 2022/05/18 05:25:33 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:34 INFO : three.txt: Copied (server-side copy) 2022/05/18 05:25:34 INFO : three.txt: Deleted 2022/05/18 05:25:34 INFO : three.txt: Moved into backup dir 2022/05/18 05:25:35 DEBUG : dst/three.txt: md5 = af5688f9559a2e644970f5cc6067ba6c OK 2022/05/18 05:25:35 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:25:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:35 DEBUG : two: Unchanged skipping 2022/05/18 05:25:35 INFO : one: Copied (server-side copy) 2022/05/18 05:25:37 INFO : one: Deleted 2022/05/18 05:25:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:37 DEBUG : one: md5 = cf3c761c19c031c312f36c5cf9a7c771 OK 2022/05/18 05:25:37 INFO : one: Copied (new) 2022/05/18 05:25:37 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:38 INFO : three.txt: Copied (server-side copy) 2022/05/18 05:25:38 INFO : three.txt: Deleted 2022/05/18 05:25:38 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (11.32s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:25:40 DEBUG : dst/one: md5 = 497770fdd5dbbad54bcfe33b708297a7 OK 2022/05/18 05:25:41 DEBUG : dst/two: md5 = bb0938c95b04af44bf964dcbb52656b6 OK 2022/05/18 05:25:41 DEBUG : dst/three.txt: md5 = 219af36ed94623a7a6f38bab5e484437 OK 2022/05/18 05:25:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:25:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup.bin" 2022/05/18 05:25:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:43 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:25:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:43 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:43 DEBUG : two: Unchanged skipping 2022/05/18 05:25:44 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:25:44 INFO : one: Deleted 2022/05/18 05:25:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:44 DEBUG : one: md5 = 51686742319571b7819312427f019b44 OK 2022/05/18 05:25:44 INFO : one: Copied (new) 2022/05/18 05:25:44 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:45 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:25:45 INFO : three.txt: Deleted 2022/05/18 05:25:45 INFO : three.txt: Moved into backup dir 2022/05/18 05:25:46 DEBUG : dst/three.txt: md5 = ba6c2a97c8b8215c060d495d80e26e04 OK 2022/05/18 05:25:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:46 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:25:46 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:46 DEBUG : two: Unchanged skipping 2022/05/18 05:25:47 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:25:47 INFO : one: Deleted 2022/05/18 05:25:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:48 DEBUG : one: md5 = ea50fa2e313a31efe7787cd8625b57b6 OK 2022/05/18 05:25:48 INFO : one: Copied (new) 2022/05/18 05:25:48 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:48 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:25:48 INFO : three.txt: Deleted 2022/05/18 05:25:48 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (10.69s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:25:51 DEBUG : dst/one: md5 = 683edd2572d488b19b8c00e2e13e9159 OK 2022/05/18 05:25:52 DEBUG : dst/two: md5 = e2f05453cebae3593000a091ef279792 OK 2022/05/18 05:25:53 DEBUG : dst/three.txt: md5 = e526bfbc30ed987d4de858e7dc10b318 OK 2022/05/18 05:25:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:25:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:25:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup.bin" 2022/05/18 05:25:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/backup" 2022/05/18 05:25:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:55 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:25:55 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:55 DEBUG : two: Unchanged skipping 2022/05/18 05:25:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/05/18 05:25:56 INFO : one: Deleted 2022/05/18 05:25:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:56 DEBUG : one: md5 = 2d360f6bd47240547a69acc499fbf44f OK 2022/05/18 05:25:56 INFO : one: Copied (new) 2022/05/18 05:25:56 DEBUG : Waiting for deletions to finish 2022/05/18 05:25:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/05/18 05:25:57 INFO : three.txt: Deleted 2022/05/18 05:25:57 INFO : three.txt: Moved into backup dir 2022/05/18 05:25:58 DEBUG : dst/three.txt: md5 = 830858a7a84f02cc9dbdb73303d550f3 OK 2022/05/18 05:25:58 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:25:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:25:58 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:25:58 DEBUG : two: Unchanged skipping 2022/05/18 05:25:59 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/05/18 05:25:59 INFO : one: Deleted 2022/05/18 05:25:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:25:59 DEBUG : one: md5 = 8c2a84a42e5f8adddd0b3656f894a8bd OK 2022/05/18 05:25:59 INFO : one: Copied (new) 2022/05/18 05:25:59 DEBUG : Waiting for deletions to finish 2022/05/18 05:26:00 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/05/18 05:26:00 INFO : three.txt: Deleted 2022/05/18 05:26:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (11.07s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:02 DEBUG : dst/one: md5 = 081289e9cd6caa0a4951b1bc3e5c8961 OK 2022/05/18 05:26:02 DEBUG : dst/two: md5 = c3f8a6c6c422e55e4b80baebe1d1ef53 OK 2022/05/18 05:26:02 DEBUG : dst/three.txt: md5 = e07b2489d4807a716391a4f03870cd2c OK 2022/05/18 05:26:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:26:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:26:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:26:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:04 DEBUG : two: Unchanged skipping 2022/05/18 05:26:04 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:26:04 INFO : one: Deleted 2022/05/18 05:26:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:26:05 DEBUG : one: md5 = ec9bb19f8622f360a05bffff24704683 OK 2022/05/18 05:26:05 INFO : one: Copied (new) 2022/05/18 05:26:05 DEBUG : Waiting for deletions to finish 2022/05/18 05:26:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:26:05 INFO : three.txt: Deleted 2022/05/18 05:26:05 INFO : three.txt: Moved into backup dir 2022/05/18 05:26:06 DEBUG : dst/three.txt: md5 = 4b70a05d5c0fef0c2975abdb304bf266 OK 2022/05/18 05:26:06 DEBUG : one.bak: Excluded 2022/05/18 05:26:06 DEBUG : three.txt.bak: Excluded 2022/05/18 05:26:06 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:26:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for checks to finish 2022/05/18 05:26:06 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:06 DEBUG : two: Unchanged skipping 2022/05/18 05:26:07 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:26:07 INFO : one: Deleted 2022/05/18 05:26:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst': Waiting for transfers to finish 2022/05/18 05:26:07 DEBUG : one: md5 = 57a1ad96ad8995a133cd70d31c4f67c9 OK 2022/05/18 05:26:07 INFO : one: Copied (new) 2022/05/18 05:26:07 DEBUG : Waiting for deletions to finish 2022/05/18 05:26:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:26:08 INFO : three.txt: Deleted 2022/05/18 05:26:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (8.55s) === RUN TestSyncSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:10 DEBUG : dst/one: md5 = 3dde6bca5c24335ce4af5cca3a088ac8 OK 2022/05/18 05:26:11 DEBUG : dst/two: md5 = 1c7f5cd045d87b8617c5bbc130da9963 OK 2022/05/18 05:26:11 DEBUG : dst/three.txt: md5 = 2adcd978f2d3dc591f41af0f571ad7a7 OK 2022/05/18 05:26:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:26:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:12 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:26:12 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:26:13 INFO : one: Deleted 2022/05/18 05:26:14 DEBUG : one: md5 = 50f75b678b8e89aabf54001a0625bbab OK 2022/05/18 05:26:14 INFO : one: Copied (new) 2022/05/18 05:26:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:14 DEBUG : two: Unchanged skipping 2022/05/18 05:26:14 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/05/18 05:26:14 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:26:14 INFO : three.txt: Deleted 2022/05/18 05:26:14 DEBUG : three.txt: md5 = 3115f57c8229b4306a4e2cd83b157d37 OK 2022/05/18 05:26:14 INFO : three.txt: Copied (new) 2022/05/18 05:26:15 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:26:16 INFO : one: Copied (server-side copy) to: one.bak 2022/05/18 05:26:16 INFO : one: Deleted 2022/05/18 05:26:16 DEBUG : one: md5 = ca7b7236c9644748e99b9223484972c8 OK 2022/05/18 05:26:16 INFO : one: Copied (new) 2022/05/18 05:26:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:16 DEBUG : two: Unchanged skipping 2022/05/18 05:26:16 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/05/18 05:26:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/05/18 05:26:16 INFO : three.txt: Deleted 2022/05/18 05:26:17 DEBUG : three.txt: md5 = cfa44dd01fa2c289932e6ddeb8db4951 OK 2022/05/18 05:26:17 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (9.71s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:20 DEBUG : dst/one: md5 = 43d441bf6e5f29ecb89b598afc441adf OK 2022/05/18 05:26:20 DEBUG : dst/two: md5 = f8df6f8ab7a8e2e64d29c073d2ac9410 OK 2022/05/18 05:26:21 DEBUG : dst/three.txt: md5 = 4922b534f117b890723d9afd274b5797 OK 2022/05/18 05:26:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst.bin" 2022/05/18 05:26:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lazoyod9caxureh6hedohas3/dst" 2022/05/18 05:26:22 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/05/18 05:26:22 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/05/18 05:26:23 INFO : one: Deleted 2022/05/18 05:26:23 DEBUG : one: md5 = 7e3078c14c5811a6255ccd991c8ee40d OK 2022/05/18 05:26:23 INFO : one: Copied (new) 2022/05/18 05:26:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:23 DEBUG : two: Unchanged skipping 2022/05/18 05:26:23 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/05/18 05:26:24 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/05/18 05:26:24 INFO : three.txt: Deleted 2022/05/18 05:26:25 DEBUG : three.txt: md5 = e4bfde7d515ee273434c31ef14ef0459 OK 2022/05/18 05:26:25 INFO : three.txt: Copied (new) 2022/05/18 05:26:26 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/05/18 05:26:27 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/05/18 05:26:27 INFO : one: Deleted 2022/05/18 05:26:28 DEBUG : one: md5 = cf68c36d7c6c308d4fb40e428a18070b OK 2022/05/18 05:26:28 INFO : one: Copied (new) 2022/05/18 05:26:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:28 DEBUG : two: Unchanged skipping 2022/05/18 05:26:28 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/05/18 05:26:28 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/05/18 05:26:28 INFO : three.txt: Deleted 2022/05/18 05:26:29 DEBUG : three.txt: md5 = 0594217f40a7ed54823bc6ee81c09b12 OK 2022/05/18 05:26:29 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (10.59s) === RUN TestSyncUTFNorm run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:31 DEBUG : Testêé: md5 = 9f32122609d639385a2c059aa1dcc09e OK 2022/05/18 05:26:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:26:31 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/05/18 05:26:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:26:31 DEBUG : Testêé: md5 = 4b78b58b242de5331810f43c7b3e27e4 OK 2022/05/18 05:26:31 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/05/18 05:26:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.99s) === RUN TestSyncImmutable run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:26:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:26:33 DEBUG : existing: md5 = 5557df9f98abc5335e01850c5101b94e OK 2022/05/18 05:26:33 INFO : existing: Copied (new) 2022/05/18 05:26:33 DEBUG : Waiting for deletions to finish 2022/05/18 05:26:33 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/05/18 05:26:33 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/05/18 05:26:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:26:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:26:33 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': not deleting files as there were IO errors 2022/05/18 05:26:33 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.51s) === RUN TestSyncIgnoreCase run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:34 DEBUG : EXISTING: md5 = 054abbf2d4532fb325b30fee4ef98757 OK 2022/05/18 05:26:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:26:35 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:26:35 DEBUG : existing: Unchanged skipping 2022/05/18 05:26:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:26:35 DEBUG : Waiting for deletions to finish 2022/05/18 05:26:35 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.56s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Soft run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1989: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.39s) --- SKIP: TestMaxTransfer/Hard (0.09s) --- SKIP: TestMaxTransfer/Soft (0.22s) --- SKIP: TestMaxTransfer/Cautious (0.09s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:26:37 DEBUG : both0: md5 = 053a81449cf69871505799600460426d OK 2022/05/18 05:26:37 DEBUG : only0: md5 = 2084a743bc00dbd6def9428e536562b5 OK 2022/05/18 05:26:38 DEBUG : both1: md5 = 4798a0c203a535bf74309cec53af6a84 OK 2022/05/18 05:26:38 DEBUG : only1: md5 = 528020a45fef82c2b8b60c046c85d9b0 OK 2022/05/18 05:26:39 DEBUG : both2: md5 = b10e80fc7820d8422df29eb0e67b4a48 OK 2022/05/18 05:26:39 DEBUG : only2: md5 = 8e91d767bfa0eac43f99927fcdfaf12a OK 2022/05/18 05:26:39 DEBUG : both3: md5 = 072dd1dcfe9b218e84846a916d8d8329 OK 2022/05/18 05:26:40 DEBUG : only3: md5 = 93492eb9599de25b8e2ddee8028bfa8f OK 2022/05/18 05:26:41 DEBUG : both4: md5 = 72f9ee3ae11b2088e0ede4eed81986ca OK 2022/05/18 05:26:42 DEBUG : only4: md5 = ec27e47fef8937faecee244dc101cb2a OK 2022/05/18 05:26:43 DEBUG : both5: md5 = df022f0f173a0e97b66b3db198f31e78 OK 2022/05/18 05:26:44 DEBUG : only5: md5 = ad26bf1c7011a65c793aefa3c737d932 OK 2022/05/18 05:26:45 DEBUG : both6: md5 = f18e7ddc31bb92f684e5d0efdf2f1146 OK 2022/05/18 05:26:45 DEBUG : only6: md5 = 5c380810e17080c14dd984b2c6a5bae3 OK 2022/05/18 05:26:46 DEBUG : both7: md5 = e41022e186623b2be7777143831e9ab5 OK 2022/05/18 05:26:47 DEBUG : only7: md5 = d95fb883d74119d75386fb8a9cef345b OK 2022/05/18 05:26:47 DEBUG : both8: md5 = f9f51f1eb0e9bfdaf3edf7f033caa112 OK 2022/05/18 05:26:48 DEBUG : only8: md5 = 2633109f9ee224610af51c39ae25dd71 OK 2022/05/18 05:26:48 DEBUG : both9: md5 = b887d011906f1bf5afddd2c3fc7f36b0 OK 2022/05/18 05:26:50 DEBUG : only9: md5 = 739f6db885c78de439ad804eaf3d6ebe OK 2022/05/18 05:26:51 DEBUG : both10: md5 = d8e5f3d34592190d2b1aa62e1e3189b0 OK 2022/05/18 05:26:52 DEBUG : only10: md5 = 5b7d36142d58dbe673395f9a99f2fa8d OK 2022/05/18 05:26:52 DEBUG : both11: md5 = 8d54e86233816e3cd94102e72d33fd08 OK 2022/05/18 05:26:53 DEBUG : only11: md5 = bf06d310bcb42e1e0427936a70bfa347 OK 2022/05/18 05:26:54 DEBUG : both12: md5 = f159247113c9f839fe033e3a5c177dee OK 2022/05/18 05:26:54 DEBUG : only12: md5 = 46d0e4d8617416dcf487345eb2675a3a OK 2022/05/18 05:26:55 DEBUG : both13: md5 = 0d4c5f63966708e1f7520a7e73b49ac6 OK 2022/05/18 05:26:56 DEBUG : only13: md5 = 23e8b6954bc5fb07ca23e610993a78ee OK 2022/05/18 05:26:56 DEBUG : both14: md5 = 0637ce16b073e1673b94a0538a6bc10f OK 2022/05/18 05:26:56 DEBUG : only14: md5 = 0f3b4ec01132348d2e855814baae1716 OK 2022/05/18 05:26:57 DEBUG : both15: md5 = 9d58ff7bdf40acb5a8d5ff931d7f5019 OK 2022/05/18 05:26:57 DEBUG : only15: md5 = 944e6c404209567b296b8a20a5a975ef OK 2022/05/18 05:26:58 DEBUG : both16: md5 = f4563b99bed581cd3ac3dc17e6059243 OK 2022/05/18 05:26:58 DEBUG : only16: md5 = b570d461baacbf7e27aeabe26da29b6a OK 2022/05/18 05:26:59 DEBUG : both17: md5 = 396a3be510957a904b6651d6a6fa2c91 OK 2022/05/18 05:27:00 DEBUG : only17: md5 = e6ea7cd0d63ecf121ea1a73259fb356b OK 2022/05/18 05:27:00 DEBUG : both18: md5 = 686834c924939c94f6c92ddd349c0aaa OK 2022/05/18 05:27:01 DEBUG : only18: md5 = dc6faa63ae04d0ea1675dcd33571fa88 OK 2022/05/18 05:27:01 DEBUG : both19: md5 = dd24cd823ca8b45ac2e363d02468a5b4 OK 2022/05/18 05:27:02 DEBUG : only19: md5 = ec58517900c59750fb3bf02b07eb54fd OK 2022/05/18 05:27:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:27:03 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:03 DEBUG : both1: Unchanged skipping 2022/05/18 05:27:03 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:03 DEBUG : both0: Unchanged skipping 2022/05/18 05:27:03 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:03 DEBUG : both10: Unchanged skipping 2022/05/18 05:27:03 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:03 DEBUG : both11: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both12: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both13: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both14: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both15: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both16: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both17: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both18: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both19: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both2: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both3: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both4: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both5: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both6: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both7: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both8: Unchanged skipping 2022/05/18 05:27:04 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:04 DEBUG : both9: Unchanged skipping 2022/05/18 05:27:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:27:04 DEBUG : Waiting for deletions to finish 2022/05/18 05:27:04 INFO : only15: Deleted 2022/05/18 05:27:04 INFO : only13: Deleted 2022/05/18 05:27:04 INFO : only17: Deleted 2022/05/18 05:27:04 INFO : only11: Deleted 2022/05/18 05:27:04 INFO : only14: Deleted 2022/05/18 05:27:04 INFO : only12: Deleted 2022/05/18 05:27:04 INFO : only16: Deleted 2022/05/18 05:27:04 INFO : only18: Deleted 2022/05/18 05:27:04 INFO : only5: Deleted 2022/05/18 05:27:04 INFO : only7: Deleted 2022/05/18 05:27:04 INFO : only4: Deleted 2022/05/18 05:27:04 INFO : only19: Deleted 2022/05/18 05:27:04 INFO : only9: Deleted 2022/05/18 05:27:04 INFO : only0: Deleted 2022/05/18 05:27:04 INFO : only10: Deleted 2022/05/18 05:27:04 INFO : only2: Deleted 2022/05/18 05:27:05 INFO : only3: Deleted 2022/05/18 05:27:05 INFO : only6: Deleted 2022/05/18 05:27:05 INFO : only1: Deleted 2022/05/18 05:27:05 INFO : only8: Deleted 2022/05/18 05:27:05 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (35.62s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3'", Local "Local file system at /tmp/rclone2890825392", Modify Window "1ns" 2022/05/18 05:27:12 DEBUG : both0: md5 = e3df67b0f97ddf91ca273ba39c89a197 OK 2022/05/18 05:27:12 DEBUG : only0: md5 = 931dfa33f20ca2ae2c6e32da49b26d31 OK 2022/05/18 05:27:13 DEBUG : both1: md5 = 2feff5c0f80370d69fe7571eb071681e OK 2022/05/18 05:27:13 DEBUG : only1: md5 = 426a359825c25afde56dc565857cc59a OK 2022/05/18 05:27:13 DEBUG : both2: md5 = 8e26b3612f4b83a7f02b0ed6eef1389e OK 2022/05/18 05:27:14 DEBUG : only2: md5 = 0e73eee65095baad30f109ff77a70f19 OK 2022/05/18 05:27:14 DEBUG : both3: md5 = 7f2ba2b57bb1e756a123989aa07f242f OK 2022/05/18 05:27:15 DEBUG : only3: md5 = 2dcfb5e3a6a31ee53c59f44bb684161a OK 2022/05/18 05:27:15 DEBUG : both4: md5 = 39ad8dfef68a8a02c091c7ec7cb23f82 OK 2022/05/18 05:27:16 DEBUG : only4: md5 = 136da7937d423951d00ce53ce9a03fac OK 2022/05/18 05:27:16 DEBUG : both5: md5 = 93bcec1d68b97086f3e8cf8b989b17da OK 2022/05/18 05:27:17 DEBUG : only5: md5 = 2400d87898dca7c1651a0a60c158728c OK 2022/05/18 05:27:18 DEBUG : both6: md5 = 123dd04a7dfcd415225f319510c1f2cb OK 2022/05/18 05:27:18 DEBUG : only6: md5 = 6e558d43094fb82ee9ba901ac3d5da80 OK 2022/05/18 05:27:19 DEBUG : both7: md5 = 5e825d7b5193abc3440cb8296c731d89 OK 2022/05/18 05:27:19 DEBUG : only7: md5 = 81f9b0fe69d42ad515b0094de6a6a569 OK 2022/05/18 05:27:20 DEBUG : both8: md5 = 756b4a4d41f942689fe5e911e6b79f48 OK 2022/05/18 05:27:21 DEBUG : only8: md5 = 4b5b5d7909ee8a8c607b295a7c8737ac OK 2022/05/18 05:27:21 DEBUG : both9: md5 = 8a9c794470983985488e859e466f3926 OK 2022/05/18 05:27:22 DEBUG : only9: md5 = 02e858939c4bb01894741f6ce7c37c7a OK 2022/05/18 05:27:22 DEBUG : both10: md5 = 032b676151afa168acef746f497a2b90 OK 2022/05/18 05:27:23 DEBUG : only10: md5 = 0c1694a48407d22dcedb95cc9ecb1d53 OK 2022/05/18 05:27:23 DEBUG : both11: md5 = c9cc577f10d53c60706516a31b1cee52 OK 2022/05/18 05:27:24 DEBUG : only11: md5 = bfde209123eb5459acba4fa9e374256c OK 2022/05/18 05:27:24 DEBUG : both12: md5 = 3c493d7f191412a69656f150d0c237ca OK 2022/05/18 05:27:25 DEBUG : only12: md5 = 779f031d75eb56ab36b0e5246e2c1d61 OK 2022/05/18 05:27:25 DEBUG : both13: md5 = 2a3bb2ca3c4ea590cf5663bf5958176b OK 2022/05/18 05:27:26 DEBUG : only13: md5 = e4f7a79e6daa8a86f61a168ed575ee15 OK 2022/05/18 05:27:26 DEBUG : both14: md5 = 8bd5070f23f8b64335ea57f63b5e6c00 OK 2022/05/18 05:27:27 DEBUG : only14: md5 = c208307106550ff1d33764974c189221 OK 2022/05/18 05:27:27 DEBUG : both15: md5 = 1621b2d926f75ccf7be7633aed5e71d7 OK 2022/05/18 05:27:28 DEBUG : only15: md5 = db7031e9dad2e4318be543132df53f43 OK 2022/05/18 05:27:29 DEBUG : both16: md5 = 1f96384512777286639edb398b363a91 OK 2022/05/18 05:27:29 DEBUG : only16: md5 = 66461e141fccce0bfb2aea00b6a1df0c OK 2022/05/18 05:27:29 DEBUG : both17: md5 = 820ee5b8abdd674cc1a02aa6591804a8 OK 2022/05/18 05:27:30 DEBUG : only17: md5 = f89613b6a318f25aef90d0f89fc36241 OK 2022/05/18 05:27:30 DEBUG : both18: md5 = 2200365fb7d166ccb2d8c922d69f3ab0 OK 2022/05/18 05:27:31 DEBUG : only18: md5 = 0d228606f6363ef82d0c0c0bf4525111 OK 2022/05/18 05:27:31 DEBUG : both19: md5 = e6a511b4f20400d0f18e04e0b6ce5754 OK 2022/05/18 05:27:31 DEBUG : only19: md5 = f1a78f191126a394ca49df8138ecff95 OK 2022/05/18 05:27:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for checks to finish 2022/05/18 05:27:33 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both0: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both1: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both11: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both10: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both12: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both15: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both14: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both13: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both16: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both17: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both19: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both18: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both2: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both4: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both3: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both5: Unchanged skipping 2022/05/18 05:27:33 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both8: Unchanged skipping 2022/05/18 05:27:33 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both7: Unchanged skipping 2022/05/18 05:27:33 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both6: Unchanged skipping 2022/05/18 05:27:33 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/05/18 05:27:33 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/05/18 05:27:33 DEBUG : both9: Unchanged skipping 2022/05/18 05:27:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Waiting for transfers to finish 2022/05/18 05:27:34 DEBUG : only0: md5 = be6322fae2de91622b39cc4b493889dd OK 2022/05/18 05:27:34 DEBUG : only10: md5 = bb6f3d10550e18b9f3d3a3f74225d2b6 OK 2022/05/18 05:27:34 INFO : only10: Copied (replaced existing) 2022/05/18 05:27:34 INFO : only0: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only1: md5 = 4f279936576b0f76cdec69bd776bc0e0 OK 2022/05/18 05:27:34 INFO : only1: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only11: md5 = 7ea6b2492260f06ba7cbd450053b17b2 OK 2022/05/18 05:27:34 INFO : only11: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only14: md5 = 719c9286e2d998fb64d5294eaa4f0633 OK 2022/05/18 05:27:34 INFO : only14: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only12: md5 = d5903119c4386fe6afc310bf1f733612 OK 2022/05/18 05:27:34 INFO : only12: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only16: md5 = e102a09ef0e853cb77381bb4788a88b1 OK 2022/05/18 05:27:34 INFO : only16: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only18: md5 = 74c6487b8fa67bf30c37d26206c9b3a0 OK 2022/05/18 05:27:34 INFO : only18: Copied (replaced existing) 2022/05/18 05:27:34 DEBUG : only17: md5 = eab1902b54644c674dcd46f40cabd246 OK 2022/05/18 05:27:34 INFO : only17: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only19: md5 = f83d632517fe1c411c1a5308681a941f OK 2022/05/18 05:27:35 INFO : only19: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only3: md5 = de6091a45811f252b56dd056d6ba70ab OK 2022/05/18 05:27:35 INFO : only3: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only2: md5 = 82a48da4a1c738e74a14a03a68902a08 OK 2022/05/18 05:27:35 INFO : only2: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only5: md5 = 1eaf60b0090951524f13d738e2ab2f2a OK 2022/05/18 05:27:35 INFO : only5: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only6: md5 = 2bf387a9a0d6f72384f0121c7d2d5694 OK 2022/05/18 05:27:35 INFO : only6: Copied (replaced existing) 2022/05/18 05:27:35 DEBUG : only4: md5 = bbc1949065342d232c0685ad9d3e8f4d OK 2022/05/18 05:27:35 DEBUG : only15: md5 = 1b650a61b8c257e34ddd9ea03d65b1d8 OK 2022/05/18 05:27:35 INFO : only15: Copied (replaced existing) 2022/05/18 05:27:35 INFO : only4: Copied (replaced existing) 2022/05/18 05:27:36 DEBUG : only8: md5 = 0c45c27af18ffb60ab30e9478d2c9746 OK 2022/05/18 05:27:36 INFO : only8: Copied (replaced existing) 2022/05/18 05:27:36 DEBUG : only7: md5 = 1df25d170048f14b896423e89be98ce2 OK 2022/05/18 05:27:36 INFO : only7: Copied (replaced existing) 2022/05/18 05:27:36 DEBUG : only9: md5 = b28b73e280f866e4536887f7fa4482ab OK 2022/05/18 05:27:36 INFO : only9: Copied (replaced existing) 2022/05/18 05:27:36 DEBUG : only13: md5 = d6ba988ae99f23d812cf7672656a3ed6 OK 2022/05/18 05:27:36 INFO : only13: Copied (replaced existing) 2022/05/18 05:27:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (36.24s) PASS 2022/05/18 05:27:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lazoyod9caxureh6hedohas3': Purge remote 2022/05/18 05:27:47 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 5m3.874891431s (try 1/5)