"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2022/01/12 05:30:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8" 2022/01/12 05:30:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/01/12 05:30:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8.bin" 2022/01/12 05:30:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8" 2022/01/12 05:30:03 DEBUG : Creating backend with remote "/tmp/rclone1144767036" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:03 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/01/12 05:30:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.39s) === RUN TestCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:04 DEBUG : sub dir/hello world: md5 = 79aaf6d16f9c7a7ae5d5bcbbdcaa1aec OK 2022/01/12 05:30:04 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.04s) === RUN TestCopyMissingDirectory run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:06 DEBUG : Creating backend with remote "/non-existing" 2022/01/12 05:30:06 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/01/12 05:30:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.31s) === RUN TestCopyNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:06 DEBUG : sub dir/hello world: md5 = 1d756025631e2b2e2dcabc224be28116 OK 2022/01/12 05:30:06 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.18s) === RUN TestCopyCheckFirst run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Running all checks before starting transfers 2022/01/12 05:30:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:07 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Checks finished, now starting transfers 2022/01/12 05:30:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:07 DEBUG : sub dir/hello world: md5 = d0ef1e23ddb9d94707fa5d734a5ef328 OK 2022/01/12 05:30:07 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.21s) === RUN TestSyncNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:08 ERROR : Ignoring --no-traverse with sync 2022/01/12 05:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:09 DEBUG : sub dir/hello world: md5 = 4b705328ecad774c3ecf93d0db79282a OK 2022/01/12 05:30:09 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:30:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (2.73s) === RUN TestCopyWithDepth run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:13 DEBUG : hello world2: md5 = a21b1b5fb43b59ef188ab20498f903ad OK 2022/01/12 05:30:13 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (2.23s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:13 DEBUG : hello world2: Excluded 2022/01/12 05:30:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:14 DEBUG : potato2: md5 = c1d22d161c5743bf6f4be12724a95b7d OK 2022/01/12 05:30:14 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.45s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:15 DEBUG : potato2: md5 = fa25b92fc4dca753ac86fbc20923fa14 OK 2022/01/12 05:30:15 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.93s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:16 DEBUG : sub dir2: Making directory 2022/01/12 05:30:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:16 DEBUG : sub dir/hello world: md5 = f274125c2da9177991550bc3446ab48a OK 2022/01/12 05:30:16 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:30:16 DEBUG : sub dir2: Making directory 2022/01/12 05:30:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (0.90s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:16 DEBUG : sub dir2: Making directory 2022/01/12 05:30:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:17 DEBUG : sub dir/hello world: md5 = 0f43a10e7caae3a26b2f995328164328 OK 2022/01/12 05:30:17 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:30:17 INFO : sub dir/hello world: Deleted 2022/01/12 05:30:17 DEBUG : sub dir2: Making directory 2022/01/12 05:30:17 DEBUG : sub dir: Making directory 2022/01/12 05:30:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.22s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:18 DEBUG : sub dir2: Making directory 2022/01/12 05:30:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:18 DEBUG : sub dir/hello world: md5 = f652dfe42a51fdae0b0aec834d8b728f OK 2022/01/12 05:30:18 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:30:18 DEBUG : sub dir2: Making directory 2022/01/12 05:30:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': copied 1 directories 2022/01/12 05:30:18 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (0.96s) === RUN TestServerSideCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:19 DEBUG : sub dir/hello world: md5 = 4e6a91b99d13eb7067934c82ee3c26b3 OK 2022/01/12 05:30:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-hosaqac0wibuluf6nojonal3" 2022/01/12 05:30:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hosaqac0wibuluf6nojonal3.bin" 2022/01/12 05:30:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-hosaqac0wibuluf6nojonal3" sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8' -> Encrypted drive 'TestCryptSwift:rclone-test-hosaqac0wibuluf6nojonal3' 2022/01/12 05:30:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hosaqac0wibuluf6nojonal3': Waiting for checks to finish 2022/01/12 05:30:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hosaqac0wibuluf6nojonal3': Waiting for transfers to finish 2022/01/12 05:30:21 INFO : sub dir/hello world: Copied (server-side copy) 2022/01/12 05:30:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-hosaqac0wibuluf6nojonal3': Purge remote 2022/01/12 05:30:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:22 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (3.58s) === RUN TestCopyAfterDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:23 DEBUG : sub dir/hello world: md5 = dde710e0326ee66c9da794b16348f773 OK 2022/01/12 05:30:23 ERROR : : error listing: directory not found 2022/01/12 05:30:23 DEBUG : Local file system at /tmp/rclone1144767036: Making directory 2022/01/12 05:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.12s) === RUN TestCopyRedownload run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:24 DEBUG : sub dir/hello world: md5 = 06c01489676b60f78844a8aede4e6fde OK 2022/01/12 05:30:24 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for checks to finish 2022/01/12 05:30:24 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for transfers to finish 2022/01/12 05:30:24 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.67s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:26 DEBUG : check sum: md5 = 6ba0c7e6b4a7c2c9f362a7cead0e925d OK 2022/01/12 05:30:26 INFO : check sum: Copied (new) 2022/01/12 05:30:26 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:26 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/01/12 05:30:26 DEBUG : check sum: Size of src and dst objects identical 2022/01/12 05:30:26 DEBUG : check sum: Unchanged skipping 2022/01/12 05:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:26 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:26 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.94s) === RUN TestSyncSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:27 DEBUG : sizeonly: md5 = 168f92a266682297be68345daa980738 OK 2022/01/12 05:30:27 INFO : sizeonly: Copied (new) 2022/01/12 05:30:27 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:28 DEBUG : sizeonly: Sizes identical 2022/01/12 05:30:28 DEBUG : sizeonly: Unchanged skipping 2022/01/12 05:30:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:28 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.40s) === RUN TestSyncIgnoreSize run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:29 DEBUG : ignore-size: md5 = 6238e5916e5f6138abedcea0182c8f1a OK 2022/01/12 05:30:29 INFO : ignore-size: Copied (new) 2022/01/12 05:30:29 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:29 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:30:29 DEBUG : ignore-size: Unchanged skipping 2022/01/12 05:30:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:29 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:29 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.35s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:30 DEBUG : existing: md5 = 22d9c92d71aa43a95f22f61020d7879a OK 2022/01/12 05:30:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:31 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:30:31 DEBUG : existing: Unchanged skipping 2022/01/12 05:30:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:31 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:31 INFO : There was nothing to transfer 2022/01/12 05:30:31 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/01/12 05:30:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:31 DEBUG : existing: md5 = 98b8596fb844f61c9f4b104695e14c87 OK 2022/01/12 05:30:31 INFO : existing: Copied (replaced existing) 2022/01/12 05:30:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (1.92s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:32 DEBUG : existing: md5 = 9716dcb48bc16295532078737861d390 OK 2022/01/12 05:30:32 INFO : existing: Copied (new) 2022/01/12 05:30:32 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:32 DEBUG : existing: Destination exists, skipping 2022/01/12 05:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:32 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:32 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.26s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:33 DEBUG : b/potato: md5 = d437c6b7da2337994919b983c8efcaa9 OK 2022/01/12 05:30:34 DEBUG : c/non empty space: md5 = 72d02ad94c127793aedb2e430e0a2d8f OK 2022/01/12 05:30:34 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:34 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:30:34 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:30:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:35 DEBUG : a/potato2: md5 = c254645d30075ee1aa4873f6197f4a67 OK 2022/01/12 05:30:35 INFO : a/potato2: Copied (new) 2022/01/12 05:30:35 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:35 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (12.88s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:46 DEBUG : empty space: md5 = 2110477d45810932b5fca115eb426f16 OK 2022/01/12 05:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:46 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/01/12 05:30:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:46 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2022/01/12 05:30:46 DEBUG : Waiting for deletions to finish 2022/01/12 05:30:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:47 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/01/12 05:30:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:47 DEBUG : empty space: md5 = e33d4dbe1bc6f53b4d1c6c01a03408c2 OK 2022/01/12 05:30:47 INFO : empty space: Copied (replaced existing) 2022/01/12 05:30:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (1.60s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.15s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:53 DEBUG : foo: md5 = 997d3f89bedceab08cbb954c3cdb8166 OK 2022/01/12 05:30:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:53 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/01/12 05:30:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:53 DEBUG : foo: md5 = 54e4df0766fa9ea20d91d0afb25ff0dc OK 2022/01/12 05:30:53 INFO : foo: Copied (replaced existing) 2022/01/12 05:30:53 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (6.48s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:54 DEBUG : empty space: md5 = 5778c316e4405ea581f1a053147bd57a OK 2022/01/12 05:30:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:30:55 DEBUG : empty space: Unchanged skipping 2022/01/12 05:30:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:55 DEBUG : potato: md5 = d10712e7ceea05180389c6685d934b2b OK 2022/01/12 05:30:55 INFO : potato: Copied (new) 2022/01/12 05:30:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.83s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:57 DEBUG : potato: md5 = 5460e99bbeb4cbe27f0dc719c98b840d OK 2022/01/12 05:30:57 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/01/12 05:30:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:30:58 DEBUG : potato: md5 = e43f9b2eab90b6f7d98ad4d5153a6ca0 OK 2022/01/12 05:30:58 INFO : potato: Copied (replaced existing) 2022/01/12 05:30:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.78s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:30:59 DEBUG : potato: md5 = 72abf2224f4056497939b2f0a2e8b8d1 OK 2022/01/12 05:30:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:30:59 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2022/01/12 05:30:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:00 DEBUG : potato: md5 = cf9f7ccab3255e8c2441f55ce79a7cf8 OK 2022/01/12 05:31:00 INFO : potato: Copied (replaced existing) 2022/01/12 05:31:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (2.18s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:01 DEBUG : potato: md5 = d29947b43e46b7cca8c41b30c1137bd0 OK 2022/01/12 05:31:02 DEBUG : empty space: md5 = 4531df5edfa00600f0e9278b076bdf6e OK 2022/01/12 05:31:02 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/01/12 05:31:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:02 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:02 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.61s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:03 DEBUG : potato: md5 = 617fbafc24d8853d147b84ed84a9e986 OK 2022/01/12 05:31:03 DEBUG : empty space: md5 = 544a90558695d44009154ee76b6cef9d OK 2022/01/12 05:31:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:04 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:04 DEBUG : potato2: md5 = 33f8ccc13fe78fae8572714042a29583 OK 2022/01/12 05:31:04 INFO : potato2: Copied (new) 2022/01/12 05:31:04 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:04 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.52s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:05 DEBUG : b/potato: md5 = f6634488e3a5ee70dd2becc1af004f16 OK 2022/01/12 05:31:06 DEBUG : c/non empty space: md5 = 43b196fb53fb4eff320986bb596fa4f2 OK 2022/01/12 05:31:06 DEBUG : d: Making directory 2022/01/12 05:31:06 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/01/12 05:31:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:06 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:06 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:31:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:06 DEBUG : a/potato2: md5 = b48f6ab9088a09258479a93a0d458565 OK 2022/01/12 05:31:06 INFO : a/potato2: Copied (new) 2022/01/12 05:31:06 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:06 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.26s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:07 DEBUG : b/potato: md5 = bfcc81389908afa0f3c758f5b7bb62db OK 2022/01/12 05:31:08 DEBUG : c/non empty space: md5 = f28bcc90a15aaa4b0367a70e37dee578 OK 2022/01/12 05:31:08 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/01/12 05:31:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:08 DEBUG : c/non empty space: Unchanged skipping 2022/01/12 05:31:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:08 DEBUG : a/potato2: md5 = ebc4d6d6706fde32395bd229ad34b24d OK 2022/01/12 05:31:08 INFO : a/potato2: Copied (new) 2022/01/12 05:31:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': not deleting files as there were IO errors 2022/01/12 05:31:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.13s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:10 DEBUG : potato: md5 = ef1fe0a2ae498395f910e008bde5a625 OK 2022/01/12 05:31:10 DEBUG : empty space: md5 = c8dca4983a4b23f002023c696c66f6df OK 2022/01/12 05:31:10 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:10 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:11 INFO : potato: Deleted 2022/01/12 05:31:11 DEBUG : potato2: md5 = 5f13a35a3afa9d485bedff5882dc0069 OK 2022/01/12 05:31:11 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.19s) === RUN TestSyncDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:12 DEBUG : potato: md5 = 6be2fd31bfb7bbb443f16a2fbe8a4ff4 OK 2022/01/12 05:31:12 DEBUG : empty space: md5 = 850b907a391f6ee345399fba7d668aff OK 2022/01/12 05:31:12 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:13 INFO : potato: Deleted 2022/01/12 05:31:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:13 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:13 DEBUG : potato2: md5 = c9dd488201b90ec8688cacc338443292 OK 2022/01/12 05:31:13 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (2.24s) === RUN TestCopyDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:14 DEBUG : potato: md5 = 85c0052879ab244d10ff683a4ad8ac8f OK 2022/01/12 05:31:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:15 DEBUG : potato2: md5 = fda4c4ccb2244ed4d07504a92c25b628 OK 2022/01/12 05:31:15 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (1.53s) === RUN TestSyncWithExclude run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:16 DEBUG : potato2: md5 = 3aee6a565b5d4961fdeae33460b660b7 OK 2022/01/12 05:31:16 DEBUG : empty space: md5 = 36ccc6101a7d3afee90a8740313a2b12 OK 2022/01/12 05:31:16 DEBUG : enormous: Excluded 2022/01/12 05:31:16 DEBUG : potato2: Excluded 2022/01/12 05:31:16 DEBUG : potato2: Excluded 2022/01/12 05:31:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:16 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:16 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:16 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:16 INFO : There was nothing to transfer 2022/01/12 05:31:17 DEBUG : enormous: Excluded 2022/01/12 05:31:17 DEBUG : potato2: Excluded 2022/01/12 05:31:17 DEBUG : potato2: Excluded 2022/01/12 05:31:17 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for checks to finish 2022/01/12 05:31:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:17 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:17 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for transfers to finish 2022/01/12 05:31:17 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:17 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.06s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:18 DEBUG : potato2: md5 = cfd1a2917483950fda0ca0480a7ea4eb OK 2022/01/12 05:31:18 DEBUG : empty space: md5 = d9df1174b9b7d667af687bfeec2797ad OK 2022/01/12 05:31:18 DEBUG : enormous: md5 = cea92ed54d7082bbc9d7e12a64373e7d OK 2022/01/12 05:31:19 DEBUG : enormous: Excluded 2022/01/12 05:31:19 DEBUG : potato2: Excluded 2022/01/12 05:31:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:19 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:19 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:19 INFO : enormous: Deleted 2022/01/12 05:31:19 INFO : potato2: Deleted 2022/01/12 05:31:19 INFO : There was nothing to transfer 2022/01/12 05:31:20 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for checks to finish 2022/01/12 05:31:20 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:20 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:20 DEBUG : Local file system at /tmp/rclone1144767036: Waiting for transfers to finish 2022/01/12 05:31:20 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:20 INFO : enormous: Deleted 2022/01/12 05:31:20 INFO : potato2: Deleted 2022/01/12 05:31:20 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.55s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:21 DEBUG : one: md5 = 4c6267e889b1c3fce6c18707e4bba721 OK 2022/01/12 05:31:22 DEBUG : two: md5 = 7ea59d64a5211c3117e604a52028a896 OK 2022/01/12 05:31:22 DEBUG : three: md5 = 035057a1996fe25d0dec96a7cc2975c8 OK 2022/01/12 05:31:22 DEBUG : four: md5 = 8eb553af59421d554101e2e01d905f28 OK 2022/01/12 05:31:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:23 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/01/12 05:31:23 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/01/12 05:31:23 DEBUG : three: Sizes identical 2022/01/12 05:31:23 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/01/12 05:31:23 DEBUG : one: Destination is newer than source, skipping 2022/01/12 05:31:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:23 DEBUG : four: md5 = e0bbfb38024215fd6c77f6e9b1489ea0 OK 2022/01/12 05:31:23 INFO : four: Copied (replaced existing) 2022/01/12 05:31:24 DEBUG : two: md5 = a52c760fe67f3f5af0691598efafc792 OK 2022/01/12 05:31:24 INFO : two: Copied (replaced existing) 2022/01/12 05:31:24 DEBUG : five: md5 = 90f4512d13d3c6e38113d4ba98791ae1 OK 2022/01/12 05:31:24 INFO : five: Copied (new) 2022/01/12 05:31:24 DEBUG : Waiting for deletions to finish sync_test.go:992: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (7.00s) === 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-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" sync_test.go:1058: Can track renames: false 2022/01/12 05:31:27 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Ignoring --track-renames as the source and destination do not have a common hash 2022/01/12 05:31:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:27 DEBUG : potato: md5 = 102f95b6e81319e1de741c69872ed20b OK 2022/01/12 05:31:27 INFO : potato: Copied (new) 2022/01/12 05:31:28 DEBUG : yam: md5 = fcecf7e0a64eb112af44310360cdd21a OK 2022/01/12 05:31:28 INFO : yam: Copied (new) 2022/01/12 05:31:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:28 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Ignoring --track-renames as the source and destination do not have a common hash 2022/01/12 05:31:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:28 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:28 DEBUG : potato: Unchanged skipping 2022/01/12 05:31:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:28 DEBUG : yaml: md5 = 416286f247fb21b5ad28bb9daf937aec OK 2022/01/12 05:31:28 INFO : yaml: Copied (new) 2022/01/12 05:31:28 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:28 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (2.36s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/01/12 05:31:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Making map for --track-renames 2022/01/12 05:31:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Finished making map for --track-renames 2022/01/12 05:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for renames to finish 2022/01/12 05:31:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:30 DEBUG : yam: md5 = d227037948a486c243ed1e813fa4a079 OK 2022/01/12 05:31:30 INFO : yam: Copied (new) 2022/01/12 05:31:30 DEBUG : potato: md5 = 3c940c63c0b2a4300be4d43d154c1c35 OK 2022/01/12 05:31:30 INFO : potato: Copied (new) 2022/01/12 05:31:30 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Making map for --track-renames 2022/01/12 05:31:30 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Finished making map for --track-renames 2022/01/12 05:31:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:30 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:30 DEBUG : potato: Unchanged skipping 2022/01/12 05:31:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for renames to finish 2022/01/12 05:31:31 INFO : yam: Copied (server-side copy) to: yaml 2022/01/12 05:31:31 INFO : yam: Deleted 2022/01/12 05:31:31 INFO : yaml: Renamed from "yam" 2022/01/12 05:31:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.48s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/01/12 05:31:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Making map for --track-renames 2022/01/12 05:31:32 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Finished making map for --track-renames 2022/01/12 05:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for renames to finish 2022/01/12 05:31:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:32 DEBUG : potato: md5 = ae7268ceaca7b89fdb57d65de2d4f4f5 OK 2022/01/12 05:31:32 INFO : potato: Copied (new) 2022/01/12 05:31:33 DEBUG : sub/yam: md5 = ec78ca4326ccf2449afcc87c4ff5d910 OK 2022/01/12 05:31:33 INFO : sub/yam: Copied (new) 2022/01/12 05:31:33 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:33 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:33 DEBUG : potato: Unchanged skipping 2022/01/12 05:31:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Making map for --track-renames 2022/01/12 05:31:33 INFO : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Finished making map for --track-renames 2022/01/12 05:31:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for renames to finish 2022/01/12 05:31:34 INFO : sub/yam: Copied (server-side copy) to: yam 2022/01/12 05:31:34 INFO : sub/yam: Deleted 2022/01/12 05:31:34 INFO : yam: Renamed from "sub/yam" 2022/01/12 05:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:34 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.57s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:35 DEBUG : sub dir/hello world: md5 = bad8975f2ed15360b1b06f39bc39be60 OK 2022/01/12 05:31:35 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:31:35 INFO : sub dir/hello world: Deleted 2022/01/12 05:31:37 DEBUG : nested/sub dir/file: md5 = 8bfb2a27e2358f38cc8b235e4e8eb277 OK 2022/01/12 05:31:37 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:31:37 INFO : nested/sub dir/file: Deleted 2022/01/12 05:31:37 INFO : sub dir: Removing directory 2022/01/12 05:31:37 INFO : nested/sub dir: Removing directory 2022/01/12 05:31:37 INFO : nested: Removing directory 2022/01/12 05:31:37 DEBUG : Local file system at /tmp/rclone1144767036: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (3.12s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:38 DEBUG : sub dir/hello world: md5 = 9ca1b1f0eb779d51cef54f8445430de1 OK 2022/01/12 05:31:38 INFO : sub dir/hello world: Copied (new) 2022/01/12 05:31:38 INFO : sub dir/hello world: Deleted 2022/01/12 05:31:38 DEBUG : nested/sub dir/file: md5 = 133bf713a2b1a26795bfc2017088de9a OK 2022/01/12 05:31:38 INFO : nested/sub dir/file: Copied (new) 2022/01/12 05:31:38 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.58s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:39 DEBUG : existing: md5 = 0a4998532f04278a4374b34cfb136db0 OK 2022/01/12 05:31:39 INFO : existing: Copied (new) 2022/01/12 05:31:39 INFO : existing: Deleted 2022/01/12 05:31:40 DEBUG : existing-b: md5 = 198ab09f6c62563cbd2bc5ad1d072cbc OK 2022/01/12 05:31:40 INFO : existing-b: Copied (new) 2022/01/12 05:31:40 INFO : existing-b: Deleted 2022/01/12 05:31:40 DEBUG : existing: Destination exists, skipping 2022/01/12 05:31:40 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/01/12 05:31:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:31:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:31:40 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (1.57s) === RUN TestServerSideMove run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:41 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-pawahiz3bayozid0yasiyom9" 2022/01/12 05:31:41 DEBUG : Config file has changed externaly - reloading 2022/01/12 05:31:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pawahiz3bayozid0yasiyom9.bin" 2022/01/12 05:31:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pawahiz3bayozid0yasiyom9" 2022/01/12 05:31:43 DEBUG : potato2: md5 = b3d078fc5e64db31d548ef420d6cdab8 OK 2022/01/12 05:31:43 DEBUG : empty space: md5 = 695ee0772fa1c88a05e92cca233bffb9 OK 2022/01/12 05:31:44 DEBUG : potato3: md5 = 39949c8157bcf477d0de371624fdbf85 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8' -> Encrypted drive 'TestCryptSwift:rclone-test-pawahiz3bayozid0yasiyom9' 2022/01/12 05:31:46 DEBUG : empty space: md5 = 0d78cdad4b548979f2c25898556231a6 OK 2022/01/12 05:31:46 DEBUG : potato3: md5 = e2c61ee8f9f13879741a349456df4d96 OK 2022/01/12 05:31:47 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:31:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pawahiz3bayozid0yasiyom9': Waiting for checks to finish 2022/01/12 05:31:47 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:31:47 DEBUG : empty space: Unchanged skipping 2022/01/12 05:31:47 INFO : potato2: Copied (server-side copy) 2022/01/12 05:31:47 INFO : empty space: Deleted 2022/01/12 05:31:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pawahiz3bayozid0yasiyom9': Waiting for transfers to finish 2022/01/12 05:31:47 INFO : potato2: Deleted 2022/01/12 05:31:48 INFO : potato3: Copied (server-side copy) 2022/01/12 05:31:48 INFO : potato3: Deleted 2022/01/12 05:31:48 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-vatesit5mezezor4kiseyay9" 2022/01/12 05:31:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vatesit5mezezor4kiseyay9.bin" 2022/01/12 05:31:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-vatesit5mezezor4kiseyay9" 2022/01/12 05:31:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vatesit5mezezor4kiseyay9': Waiting for checks to finish 2022/01/12 05:31:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vatesit5mezezor4kiseyay9': Waiting for transfers to finish 2022/01/12 05:31:50 INFO : potato2: Copied (server-side copy) 2022/01/12 05:31:50 INFO : potato2: Deleted 2022/01/12 05:31:50 INFO : empty space: Copied (server-side copy) 2022/01/12 05:31:50 INFO : empty space: Deleted 2022/01/12 05:31:51 INFO : potato3: Copied (server-side copy) 2022/01/12 05:31:51 INFO : potato3: Deleted 2022/01/12 05:31:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-vatesit5mezezor4kiseyay9': Purge remote 2022/01/12 05:31:52 DEBUG : Waiting for deletions to finish 2022/01/12 05:31:52 INFO : potato3.bin: Deleted 2022/01/12 05:31:52 INFO : potato2.bin: Deleted 2022/01/12 05:31:52 INFO : empty space.bin: Deleted 2022/01/12 05:31:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pawahiz3bayozid0yasiyom9': Purge remote 2022/01/12 05:31:52 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (12.18s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:31:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-suzapoc8puvogax7cupaluk7" 2022/01/12 05:31:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-suzapoc8puvogax7cupaluk7.bin" 2022/01/12 05:31:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-suzapoc8puvogax7cupaluk7" 2022/01/12 05:31:54 DEBUG : potato2: md5 = 593d2d76b3dce1f91e597e099970245f OK 2022/01/12 05:31:55 DEBUG : empty space: md5 = 984cfc8bb3f45ee8a984302d1a5823e5 OK 2022/01/12 05:31:55 DEBUG : potato3: md5 = 40e118ffc6dae50283376fe4480e589e OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8' -> Encrypted drive 'TestCryptSwift:rclone-test-suzapoc8puvogax7cupaluk7' 2022/01/12 05:31:56 DEBUG : empty space: md5 = e0316cf4863715e2e92aac427953aa6a OK 2022/01/12 05:31:57 DEBUG : potato3: md5 = c1300b81469d11e8cf0e2e0eeba1e038 OK 2022/01/12 05:31:57 DEBUG : empty space: Excluded 2022/01/12 05:31:57 DEBUG : empty space: Excluded 2022/01/12 05:31:57 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:31:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-suzapoc8puvogax7cupaluk7': Waiting for checks to finish 2022/01/12 05:31:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-suzapoc8puvogax7cupaluk7': Waiting for transfers to finish 2022/01/12 05:31:58 INFO : potato3: Copied (server-side copy) 2022/01/12 05:31:58 INFO : potato3: Deleted 2022/01/12 05:31:58 INFO : potato2: Copied (server-side copy) 2022/01/12 05:31:58 INFO : potato2: Deleted 2022/01/12 05:31:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-licomaj6sekoqug3hariveb8" 2022/01/12 05:31:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-licomaj6sekoqug3hariveb8.bin" 2022/01/12 05:31:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-licomaj6sekoqug3hariveb8" 2022/01/12 05:32:00 DEBUG : empty space: Excluded 2022/01/12 05:32:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-licomaj6sekoqug3hariveb8': Waiting for checks to finish 2022/01/12 05:32:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-licomaj6sekoqug3hariveb8': Waiting for transfers to finish 2022/01/12 05:32:01 INFO : potato2: Copied (server-side copy) 2022/01/12 05:32:01 INFO : potato3: Copied (server-side copy) 2022/01/12 05:32:01 INFO : potato3: Deleted 2022/01/12 05:32:01 INFO : potato2: Deleted 2022/01/12 05:32:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-licomaj6sekoqug3hariveb8': Purge remote 2022/01/12 05:32:02 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:02 INFO : potato3.bin: Deleted 2022/01/12 05:32:02 INFO : potato2.bin: Deleted 2022/01/12 05:32:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-suzapoc8puvogax7cupaluk7': Purge remote 2022/01/12 05:32:02 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:03 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (10.44s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tinomab8manepaq0kitomal6" 2022/01/12 05:32:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tinomab8manepaq0kitomal6.bin" 2022/01/12 05:32:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tinomab8manepaq0kitomal6" 2022/01/12 05:32:05 DEBUG : potato2: md5 = 9f6a4f9bd66a4778c8be803051ce5ae6 OK 2022/01/12 05:32:05 DEBUG : empty space: md5 = 32349962a9cdcc677877738eefadc8e2 OK 2022/01/12 05:32:05 DEBUG : potato3: md5 = ac3235135008782334f0d60ec389bd02 OK 2022/01/12 05:32:05 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8' -> Encrypted drive 'TestCryptSwift:rclone-test-tinomab8manepaq0kitomal6' 2022/01/12 05:32:06 DEBUG : empty space: md5 = 89fc327df0f461756e0f636c596a4b85 OK 2022/01/12 05:32:07 DEBUG : potato3: md5 = 1bc37220a507e470b838576f0018dfaa OK 2022/01/12 05:32:07 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/01/12 05:32:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tinomab8manepaq0kitomal6': Waiting for checks to finish 2022/01/12 05:32:07 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:07 DEBUG : empty space: Unchanged skipping 2022/01/12 05:32:07 INFO : empty space: Deleted 2022/01/12 05:32:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tinomab8manepaq0kitomal6': Waiting for transfers to finish 2022/01/12 05:32:07 INFO : potato2: Copied (server-side copy) 2022/01/12 05:32:08 INFO : potato3: Copied (server-side copy) 2022/01/12 05:32:08 INFO : potato2: Deleted 2022/01/12 05:32:08 INFO : potato3: Deleted 2022/01/12 05:32:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-novibuj2zihiyel9mecaquf2" 2022/01/12 05:32:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-novibuj2zihiyel9mecaquf2.bin" 2022/01/12 05:32:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-novibuj2zihiyel9mecaquf2" 2022/01/12 05:32:09 DEBUG : tomatoDir: Making directory 2022/01/12 05:32:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-novibuj2zihiyel9mecaquf2': Waiting for checks to finish 2022/01/12 05:32:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-novibuj2zihiyel9mecaquf2': Waiting for transfers to finish 2022/01/12 05:32:10 INFO : empty space: Copied (server-side copy) 2022/01/12 05:32:10 INFO : potato2: Copied (server-side copy) 2022/01/12 05:32:10 INFO : empty space: Deleted 2022/01/12 05:32:10 INFO : potato2: Deleted 2022/01/12 05:32:10 INFO : potato3: Copied (server-side copy) 2022/01/12 05:32:10 INFO : potato3: Deleted 2022/01/12 05:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-novibuj2zihiyel9mecaquf2': Purge remote 2022/01/12 05:32:12 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:12 INFO : potato3.bin: Deleted 2022/01/12 05:32:12 INFO : potato2.bin: Deleted 2022/01/12 05:32:12 INFO : empty space.bin: Deleted 2022/01/12 05:32:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tinomab8manepaq0kitomal6': Purge remote 2022/01/12 05:32:12 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (9.10s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:12 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/rclone-move-test" 2022/01/12 05:32:12 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/rclone-move-test.bin" 2022/01/12 05:32:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/rclone-move-test" 2022/01/12 05:32:14 DEBUG : potato2: md5 = 5325d2424c4f6c3f9557194757443aa3 OK --- PASS: TestServerSideMoveOverlap (1.90s) === RUN TestSyncOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/rclone-sync-test" 2022/01/12 05:32:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/rclone-sync-test.bin" 2022/01/12 05:32:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/rclone-sync-test" --- PASS: TestSyncOverlap (1.16s) === RUN TestSyncCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:32:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/CompareDest" 2022/01/12 05:32:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/CompareDest.bin" 2022/01/12 05:32:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/CompareDest" 2022/01/12 05:32:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:18 DEBUG : one: md5 = 1ef3011a5bfd56d68b23144372aef5c9 OK 2022/01/12 05:32:18 INFO : one: Copied (new) 2022/01/12 05:32:18 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:19 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:32:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:19 DEBUG : one: md5 = 04f63bee8c51e43d621e5e65b3514476 OK 2022/01/12 05:32:19 INFO : one: Copied (replaced existing) 2022/01/12 05:32:19 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:20 DEBUG : dst/one: md5 = a8f48e5f3af28a2fb4f55937bc4f768d OK 2022/01/12 05:32:20 DEBUG : CompareDest/one: md5 = dbef3eceedb52929d49bb6ff75f36ac0 OK 2022/01/12 05:32:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:21 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:21 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:32:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:21 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:21 INFO : There was nothing to transfer 2022/01/12 05:32:21 DEBUG : CompareDest/two: md5 = 1cd5b6e1298be90ff996368ac6ac994d OK 2022/01/12 05:32:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:22 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:22 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:22 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:22 INFO : There was nothing to transfer 2022/01/12 05:32:22 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:22 DEBUG : two: Destination found in --compare-dest, skipping 2022/01/12 05:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:22 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:22 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:32:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:22 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:22 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/01/12 05:32:23 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/01/12 05:32:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:23 DEBUG : one: Destination found in --compare-dest, skipping 2022/01/12 05:32:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:23 DEBUG : two: md5 = 5c08beacaf73850da0626c84130df47d OK 2022/01/12 05:32:23 INFO : two: Copied (new) 2022/01/12 05:32:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (8.92s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:25 DEBUG : pre-dest1/1: md5 = 9ac1755f4263ecfd872ade980e097efd OK 2022/01/12 05:32:25 DEBUG : pre-dest2/2: md5 = 4cb8894a986bc431fc6758a7ec4f2599 OK 2022/01/12 05:32:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dest" 2022/01/12 05:32:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dest.bin" 2022/01/12 05:32:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dest" 2022/01/12 05:32:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/pre-dest1" 2022/01/12 05:32:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/pre-dest1.bin" 2022/01/12 05:32:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/pre-dest1" 2022/01/12 05:32:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/pre-dest2" 2022/01/12 05:32:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/pre-dest2.bin" 2022/01/12 05:32:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/pre-dest2" 2022/01/12 05:32:29 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:29 DEBUG : 1: Destination found in --compare-dest, skipping 2022/01/12 05:32:29 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:29 DEBUG : 2: Destination found in --compare-dest, skipping 2022/01/12 05:32:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dest': Waiting for checks to finish 2022/01/12 05:32:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dest': Waiting for transfers to finish 2022/01/12 05:32:30 DEBUG : 3: md5 = 0e57f447d84e1dbe6b48b9c66a384bc7 OK 2022/01/12 05:32:30 INFO : 3: Copied (new) 2022/01/12 05:32:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (6.43s) === RUN TestSyncCopyDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:32:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/CopyDest" 2022/01/12 05:32:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/CopyDest.bin" 2022/01/12 05:32:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/CopyDest" 2022/01/12 05:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:33 DEBUG : one: md5 = 4f53ed05a36bbae4a706805ab2658ff0 OK 2022/01/12 05:32:33 INFO : one: Copied (new) 2022/01/12 05:32:33 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:33 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:34 DEBUG : one: md5 = 2757e2241af53421ef36af94076935c0 OK 2022/01/12 05:32:34 INFO : one: Copied (replaced existing) 2022/01/12 05:32:34 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:34 DEBUG : dst/one: md5 = 8906ebe5b7bed33d5490f4bdc521b13c OK 2022/01/12 05:32:35 DEBUG : CopyDest/one: md5 = 5d73a1c0d80e4bff0473bc1e041892c2 OK 2022/01/12 05:32:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/BackupDir" 2022/01/12 05:32:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/BackupDir.bin" 2022/01/12 05:32:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/BackupDir" 2022/01/12 05:32:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:36 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/01/12 05:32:37 INFO : one: Copied (server-side copy) 2022/01/12 05:32:37 INFO : one: Deleted 2022/01/12 05:32:37 INFO : one: Copied (server-side copy) 2022/01/12 05:32:37 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/01/12 05:32:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:37 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:38 DEBUG : CopyDest/two: md5 = fe065137e7c7b33cbe189429ff9bec24 OK 2022/01/12 05:32:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 INFO : two: Copied (server-side copy) 2022/01/12 05:32:39 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/01/12 05:32:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : one: Unchanged skipping 2022/01/12 05:32:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:39 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : one: Unchanged skipping 2022/01/12 05:32:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:39 DEBUG : two: Unchanged skipping 2022/01/12 05:32:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:39 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:39 INFO : There was nothing to transfer 2022/01/12 05:32:40 DEBUG : CopyDest/three: md5 = 1d4d18c9ffeb0e07276fb034df22fed1 OK 2022/01/12 05:32:41 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/01/12 05:32:41 DEBUG : three: Destination not found in --copy-dest 2022/01/12 05:32:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:41 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:41 DEBUG : one: Unchanged skipping 2022/01/12 05:32:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:41 DEBUG : two: Unchanged skipping 2022/01/12 05:32:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:41 DEBUG : three: md5 = 289cd6ee7c8026d3e66ceb35822c1b12 OK 2022/01/12 05:32:41 INFO : three: Copied (new) 2022/01/12 05:32:41 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (12.30s) === RUN TestSyncBackupDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:43 DEBUG : dst/one: md5 = 7f27ce6e196bcd015ef2e73b75a736cf OK 2022/01/12 05:32:44 DEBUG : dst/two: md5 = 223f3cde4c9ce7d8f2873c975781226c OK 2022/01/12 05:32:44 DEBUG : dst/three.txt: md5 = 5ac1d8c16f593035e4cb33864ea95304 OK 2022/01/12 05:32:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:32:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:32:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup.bin" 2022/01/12 05:32:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:32:47 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:32:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:47 DEBUG : two: Unchanged skipping 2022/01/12 05:32:47 INFO : one: Copied (server-side copy) 2022/01/12 05:32:47 INFO : one: Deleted 2022/01/12 05:32:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:48 DEBUG : one: md5 = 0f4079b7e403890925d44eb8a2714ebc OK 2022/01/12 05:32:48 INFO : one: Copied (new) 2022/01/12 05:32:48 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:48 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:32:49 INFO : three.txt: Deleted 2022/01/12 05:32:49 INFO : three.txt: Moved into backup dir 2022/01/12 05:32:50 DEBUG : dst/three.txt: md5 = e150728bd42baf1a11bb90d4b03dc4e5 OK 2022/01/12 05:32:50 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:32:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:50 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:50 DEBUG : two: Unchanged skipping 2022/01/12 05:32:50 INFO : one: Copied (server-side copy) 2022/01/12 05:32:50 INFO : one: Deleted 2022/01/12 05:32:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:51 DEBUG : one: md5 = 8c001e62010c414f3b70a261bf3f658c OK 2022/01/12 05:32:51 INFO : one: Copied (new) 2022/01/12 05:32:51 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:51 INFO : three.txt: Copied (server-side copy) 2022/01/12 05:32:51 INFO : three.txt: Deleted 2022/01/12 05:32:51 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (9.78s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:32:53 DEBUG : dst/one: md5 = 62412147d011f0318bfbdf1c349e942c OK 2022/01/12 05:32:53 DEBUG : dst/two: md5 = 23d6a0b1ce4a517fdd3161ea2d84fada OK 2022/01/12 05:32:54 DEBUG : dst/three.txt: md5 = 4974e874a066a116f7733b2e1379b577 OK 2022/01/12 05:32:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:32:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:32:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:32:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup.bin" 2022/01/12 05:32:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:32:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:32:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:56 DEBUG : two: Unchanged skipping 2022/01/12 05:32:56 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:32:56 INFO : one: Deleted 2022/01/12 05:32:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:32:57 DEBUG : one: md5 = 91c472df34234c13f2f03105205b2a3d OK 2022/01/12 05:32:57 INFO : one: Copied (new) 2022/01/12 05:32:57 DEBUG : Waiting for deletions to finish 2022/01/12 05:32:57 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:32:58 INFO : three.txt: Deleted 2022/01/12 05:32:58 INFO : three.txt: Moved into backup dir 2022/01/12 05:32:58 DEBUG : dst/three.txt: md5 = 03e0b25e8e76d81235961c0913e5cefb OK 2022/01/12 05:32:59 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:32:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:32:59 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:32:59 DEBUG : two: Unchanged skipping 2022/01/12 05:32:59 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:32:59 INFO : one: Deleted 2022/01/12 05:32:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:33:00 DEBUG : one: md5 = f35455fc5030413b1afd2d8d710201e3 OK 2022/01/12 05:33:00 INFO : one: Copied (new) 2022/01/12 05:33:00 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:00 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:33:00 INFO : three.txt: Deleted 2022/01/12 05:33:00 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (8.37s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:02 DEBUG : dst/one: md5 = db20df4c4c76ef7bb358350ead3434a2 OK 2022/01/12 05:33:02 DEBUG : dst/two: md5 = 83852ae3246cd21f2a769d8ec99d628d OK 2022/01/12 05:33:02 DEBUG : dst/three.txt: md5 = f2ebf2b3750dbc3303b66112eb370b4b OK 2022/01/12 05:33:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:33:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:33:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup.bin" 2022/01/12 05:33:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/backup" 2022/01/12 05:33:04 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:33:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:33:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:04 DEBUG : two: Unchanged skipping 2022/01/12 05:33:05 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:33:05 INFO : one: Deleted 2022/01/12 05:33:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:33:05 DEBUG : one: md5 = 1b04ae3e01110e5262399984cb286681 OK 2022/01/12 05:33:05 INFO : one: Copied (new) 2022/01/12 05:33:05 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:06 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:33:06 INFO : three.txt: Deleted 2022/01/12 05:33:06 INFO : three.txt: Moved into backup dir 2022/01/12 05:33:07 DEBUG : dst/three.txt: md5 = bc70ab4d9d6c291ce4381629d69370c9 OK 2022/01/12 05:33:07 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:33:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:33:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:07 DEBUG : two: Unchanged skipping 2022/01/12 05:33:08 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:33:08 INFO : one: Deleted 2022/01/12 05:33:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:33:08 DEBUG : one: md5 = fcf7cc3efea32698722ede97913f0ea4 OK 2022/01/12 05:33:08 INFO : one: Copied (new) 2022/01/12 05:33:08 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:08 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:33:08 INFO : three.txt: Deleted 2022/01/12 05:33:08 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (8.40s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:10 DEBUG : dst/one: md5 = a153e88fcf17204bcba13fe5bb10b763 OK 2022/01/12 05:33:10 DEBUG : dst/two: md5 = 5e2d9465fe89a1a0e5d9c29f35b9457c OK 2022/01/12 05:33:10 DEBUG : dst/three.txt: md5 = 652bf5a4bf6a81e2286cd91918cc5fce OK 2022/01/12 05:33:11 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:33:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:11 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:33:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:33:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:12 DEBUG : two: Unchanged skipping 2022/01/12 05:33:12 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:33:12 INFO : one: Deleted 2022/01/12 05:33:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:33:12 DEBUG : one: md5 = 35fb8a415df28a3195f155d60e22d59d OK 2022/01/12 05:33:12 INFO : one: Copied (new) 2022/01/12 05:33:12 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:13 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:33:13 INFO : three.txt: Deleted 2022/01/12 05:33:13 INFO : three.txt: Moved into backup dir 2022/01/12 05:33:14 DEBUG : dst/three.txt: md5 = a1003bb282f6191f22fb2203549e0cd1 OK 2022/01/12 05:33:14 DEBUG : one.bak: Excluded 2022/01/12 05:33:14 DEBUG : three.txt.bak: Excluded 2022/01/12 05:33:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for checks to finish 2022/01/12 05:33:14 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:33:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:14 DEBUG : two: Unchanged skipping 2022/01/12 05:33:16 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:33:16 INFO : one: Deleted 2022/01/12 05:33:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst': Waiting for transfers to finish 2022/01/12 05:33:16 DEBUG : one: md5 = 513da417ad60eb03069c07e191be8640 OK 2022/01/12 05:33:16 INFO : one: Copied (new) 2022/01/12 05:33:16 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:16 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:33:17 INFO : three.txt: Deleted 2022/01/12 05:33:17 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (8.70s) === RUN TestSyncSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:19 DEBUG : dst/one: md5 = ed527773ac669222d8d41de206fc7cb6 OK 2022/01/12 05:33:19 DEBUG : dst/two: md5 = b9c77ed2efd14afd1ad35f3bf24deae5 OK 2022/01/12 05:33:19 DEBUG : dst/three.txt: md5 = 05e79462471c1db0a627291f3ad5fe1c OK 2022/01/12 05:33:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:33:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:20 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:33:20 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:33:21 INFO : one: Deleted 2022/01/12 05:33:21 DEBUG : one: md5 = 9153403802f8e2737201a3aed28ecce6 OK 2022/01/12 05:33:21 INFO : one: Copied (new) 2022/01/12 05:33:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:21 DEBUG : two: Unchanged skipping 2022/01/12 05:33:21 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:33:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:33:22 INFO : three.txt: Deleted 2022/01/12 05:33:22 DEBUG : three.txt: md5 = 477309e7b65dab22a7820fcfe730830e OK 2022/01/12 05:33:22 INFO : three.txt: Copied (new) 2022/01/12 05:33:23 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:33:24 INFO : one: Copied (server-side copy) to: one.bak 2022/01/12 05:33:24 INFO : one: Deleted 2022/01/12 05:33:24 DEBUG : one: md5 = ff18505ba209675ab0768136bfa32828 OK 2022/01/12 05:33:24 INFO : one: Copied (new) 2022/01/12 05:33:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:24 DEBUG : two: Unchanged skipping 2022/01/12 05:33:24 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:33:25 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/01/12 05:33:25 INFO : three.txt: Deleted 2022/01/12 05:33:25 DEBUG : three.txt: md5 = f51b3418fc6b124c1f44f22d08632c4f OK 2022/01/12 05:33:25 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (8.54s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:27 DEBUG : dst/one: md5 = 8a0c74192074361b4ececcbd9a7fb22d OK 2022/01/12 05:33:28 DEBUG : dst/two: md5 = 473bd94b38b46cc2e703c36900c4f74e OK 2022/01/12 05:33:28 DEBUG : dst/three.txt: md5 = 201b1a68092240617cf7c7f4ab6a250e OK 2022/01/12 05:33:28 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst.bin" 2022/01/12 05:33:29 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tisened6vujilar6bogahek8/dst" 2022/01/12 05:33:29 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/01/12 05:33:31 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:33:31 INFO : one: Deleted 2022/01/12 05:33:31 DEBUG : one: md5 = 509215d14d1f50b4a79f28cbd7c2c48a OK 2022/01/12 05:33:31 INFO : one: Copied (new) 2022/01/12 05:33:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:31 DEBUG : two: Unchanged skipping 2022/01/12 05:33:31 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/01/12 05:33:32 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:33:32 INFO : three.txt: Deleted 2022/01/12 05:33:32 DEBUG : three.txt: md5 = 788d6a23e86d7f577d58c0077f26c395 OK 2022/01/12 05:33:32 INFO : three.txt: Copied (new) 2022/01/12 05:33:33 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/01/12 05:33:33 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/01/12 05:33:33 INFO : one: Deleted 2022/01/12 05:33:34 DEBUG : one: md5 = 10043fc52f0e8562c8059523377c0874 OK 2022/01/12 05:33:34 INFO : one: Copied (new) 2022/01/12 05:33:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:34 DEBUG : two: Unchanged skipping 2022/01/12 05:33:34 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/01/12 05:33:34 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/01/12 05:33:34 INFO : three.txt: Deleted 2022/01/12 05:33:35 DEBUG : three.txt: md5 = 8c9547fdd6a4825abe7eca4a3dee1a5d OK 2022/01/12 05:33:35 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.33s) === RUN TestSyncUTFNorm run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:37 DEBUG : Testêé: md5 = 98de4f87863d0d944f263203766fb499 OK 2022/01/12 05:33:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:33:37 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/01/12 05:33:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:33:38 DEBUG : Testêé: md5 = 005fc5c5d965406327a1715891356711 OK 2022/01/12 05:33:38 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/01/12 05:33:38 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.82s) === RUN TestSyncImmutable run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:33:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:33:40 DEBUG : existing: md5 = 33346106bf07b16b89c79aa896fa0e51 OK 2022/01/12 05:33:40 INFO : existing: Copied (new) 2022/01/12 05:33:40 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:33:40 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/01/12 05:33:40 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/01/12 05:33:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:33:40 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': not deleting files as there were IO errors 2022/01/12 05:33:40 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.40s) === RUN TestSyncIgnoreCase run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:41 DEBUG : EXISTING: md5 = 20e84010bb1c99eccf7bb0881dcf566b OK 2022/01/12 05:33:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:33:41 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:33:41 DEBUG : existing: Unchanged skipping 2022/01/12 05:33:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:33:41 DEBUG : Waiting for deletions to finish 2022/01/12 05:33:41 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.24s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", 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-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", 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-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.43s) --- SKIP: TestMaxTransfer/Hard (0.18s) --- SKIP: TestMaxTransfer/Soft (0.09s) --- SKIP: TestMaxTransfer/Cautious (0.16s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:33:43 DEBUG : both0: md5 = 31fe3bdbc90579011ea9208afe7a7eff OK 2022/01/12 05:33:44 DEBUG : only0: md5 = 18468f894614c96170e482c57ccc2a3b OK 2022/01/12 05:33:44 DEBUG : both1: md5 = ba33ce3a4317edebc139e1679565ebd0 OK 2022/01/12 05:33:45 DEBUG : only1: md5 = 57d0e0c73596267e9399bff2d615fb15 OK 2022/01/12 05:33:45 DEBUG : both2: md5 = 33d5f4a5347771eb0e279c19647a168c OK 2022/01/12 05:33:46 DEBUG : only2: md5 = 0bfd6419bf837f67d34d042acab25ce0 OK 2022/01/12 05:33:46 DEBUG : both3: md5 = 28e3b6c490dc57085739422ae29ff444 OK 2022/01/12 05:33:47 DEBUG : only3: md5 = 086c347aa301bbea29f640185cc13106 OK 2022/01/12 05:33:47 DEBUG : both4: md5 = f880e02165e8793926eef3fff32c6f17 OK 2022/01/12 05:33:48 DEBUG : only4: md5 = cb6328e6780d0a26ba166b019daaee25 OK 2022/01/12 05:33:48 DEBUG : both5: md5 = a0598167b389616428d04d13bf68525a OK 2022/01/12 05:33:49 DEBUG : only5: md5 = 3d554df2155c0d40d7f146da13677e3b OK 2022/01/12 05:33:49 DEBUG : both6: md5 = 8675e99087165b125e5ce3bebe24cfc8 OK 2022/01/12 05:33:50 DEBUG : only6: md5 = 2c05dcc49a261187f8e8b4e2c4516f1e OK 2022/01/12 05:33:50 DEBUG : both7: md5 = e1eef19f59e661133b6747c6660af922 OK 2022/01/12 05:33:51 DEBUG : only7: md5 = 8f749e5ec29653ae6cf27bb9506f38b6 OK 2022/01/12 05:33:52 DEBUG : both8: md5 = c933e1e44209030dd295abebc3050518 OK 2022/01/12 05:33:52 DEBUG : only8: md5 = 34330e245b1303ea2826a8037d1f9abe OK 2022/01/12 05:33:53 DEBUG : both9: md5 = 33ac074e73872bf70837dad365dc3907 OK 2022/01/12 05:33:53 DEBUG : only9: md5 = f1d3c6fbfe3c42fb387772dac48ff927 OK 2022/01/12 05:33:54 DEBUG : both10: md5 = 0b8c03084e975b03ef0ea0b943675002 OK 2022/01/12 05:33:55 DEBUG : only10: md5 = c93cdd3dfa767be09d135f8abab635b1 OK 2022/01/12 05:33:55 DEBUG : both11: md5 = b2fbf1472a6faf5ac51055c3f25976ec OK 2022/01/12 05:33:56 DEBUG : only11: md5 = 22cb55a998b817fe96fd81f598626c85 OK 2022/01/12 05:33:56 DEBUG : both12: md5 = b63c6667834d6359ff9a01462f350379 OK 2022/01/12 05:33:57 DEBUG : only12: md5 = 3bf6419c6f2b9734c04f46ef1eb74ae0 OK 2022/01/12 05:33:58 DEBUG : both13: md5 = 2e6e7b297560cd6041a4d8f9a85a4af7 OK 2022/01/12 05:33:58 DEBUG : only13: md5 = 48bb324ce25144fdf2d9c9e19937d2b6 OK 2022/01/12 05:33:58 DEBUG : both14: md5 = 8b77e605565c8368f010e68429c94649 OK 2022/01/12 05:33:59 DEBUG : only14: md5 = 6dfce3953059e4dc148b130bc6c42ddb OK 2022/01/12 05:33:59 DEBUG : both15: md5 = d64c9797f200698392ea548badbf00bd OK 2022/01/12 05:34:00 DEBUG : only15: md5 = dcc64d96135dbc2e3937f7db629e5126 OK 2022/01/12 05:34:00 DEBUG : both16: md5 = 978117bb08aa4721152e5db9c46a1b90 OK 2022/01/12 05:34:01 DEBUG : only16: md5 = bfc9c948b639073adfabb024b52e61d6 OK 2022/01/12 05:34:01 DEBUG : both17: md5 = 735efbf7bb689610d162274e9f4ae263 OK 2022/01/12 05:34:03 DEBUG : only17: md5 = 38d134bb804473e983d7338b251ea152 OK 2022/01/12 05:34:04 DEBUG : both18: md5 = 4f702514dc5ea19625dc79759bebc1c3 OK 2022/01/12 05:34:05 DEBUG : only18: md5 = e31a6661bd30aa646e00e804da1a026c OK 2022/01/12 05:34:05 DEBUG : both19: md5 = 809da7ef1d01fd59407f524b614a62f9 OK 2022/01/12 05:34:06 DEBUG : only19: md5 = b745dfa9cee23db8e479041faed36176 OK 2022/01/12 05:34:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:34:08 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both0: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both1: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both10: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both11: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both12: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both13: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both14: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both15: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both16: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both17: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both18: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both19: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both2: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both3: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both4: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both5: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both6: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both7: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both8: Unchanged skipping 2022/01/12 05:34:08 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:08 DEBUG : both9: Unchanged skipping 2022/01/12 05:34:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:34:08 DEBUG : Waiting for deletions to finish 2022/01/12 05:34:08 INFO : only1: Deleted 2022/01/12 05:34:08 INFO : only4: Deleted 2022/01/12 05:34:08 INFO : only8: Deleted 2022/01/12 05:34:08 INFO : only9: Deleted 2022/01/12 05:34:08 INFO : only2: Deleted 2022/01/12 05:34:08 INFO : only14: Deleted 2022/01/12 05:34:08 INFO : only16: Deleted 2022/01/12 05:34:08 INFO : only6: Deleted 2022/01/12 05:34:08 INFO : only5: Deleted 2022/01/12 05:34:08 INFO : only13: Deleted 2022/01/12 05:34:08 INFO : only12: Deleted 2022/01/12 05:34:08 INFO : only19: Deleted 2022/01/12 05:34:08 INFO : only17: Deleted 2022/01/12 05:34:08 INFO : only3: Deleted 2022/01/12 05:34:08 INFO : only11: Deleted 2022/01/12 05:34:08 INFO : only0: Deleted 2022/01/12 05:34:08 INFO : only10: Deleted 2022/01/12 05:34:09 INFO : only7: Deleted 2022/01/12 05:34:09 INFO : only15: Deleted 2022/01/12 05:34:09 INFO : only18: Deleted 2022/01/12 05:34:09 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (32.57s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8'", Local "Local file system at /tmp/rclone1144767036", Modify Window "1ns" 2022/01/12 05:34:15 DEBUG : both0: md5 = afa68a3c0810d245d1509a650525a34f OK 2022/01/12 05:34:15 DEBUG : only0: md5 = d783e105fe4e36035966f9e922c0d0da OK 2022/01/12 05:34:15 DEBUG : both1: md5 = e1902a3d1f057f0395e0c7a918741e94 OK 2022/01/12 05:34:16 DEBUG : only1: md5 = 6d0f9d6943e5a28f2dd8be0c96aeda22 OK 2022/01/12 05:34:16 DEBUG : both2: md5 = f58effbf40bc43cbf3dfc04fd5dc2f38 OK 2022/01/12 05:34:16 DEBUG : only2: md5 = d554898b631fb8fde9302e691420bf0c OK 2022/01/12 05:34:17 DEBUG : both3: md5 = 0c74955421677d90314b7b5d7881b6dc OK 2022/01/12 05:34:17 DEBUG : only3: md5 = 5ccb86d87688fce23c067080005cd890 OK 2022/01/12 05:34:18 DEBUG : both4: md5 = 140242b3e04a0ccebf356f24463b2a1f OK 2022/01/12 05:34:18 DEBUG : only4: md5 = 8d4546bbcd259a30165ccd69d668b8a7 OK 2022/01/12 05:34:18 DEBUG : both5: md5 = 13197c74c80b4e433e16c41fb945275e OK 2022/01/12 05:34:19 DEBUG : only5: md5 = 2a13524b6295622bf66761ee9ab3e83e OK 2022/01/12 05:34:19 DEBUG : both6: md5 = 81225b2bd46a6225c7106ba64c414654 OK 2022/01/12 05:34:19 DEBUG : only6: md5 = ba6244d9d77440f7ed383870defe19e9 OK 2022/01/12 05:34:20 DEBUG : both7: md5 = e82cbb23d21c625039ccf31a9ca2d983 OK 2022/01/12 05:34:21 DEBUG : only7: md5 = 3cc32d44b0a15bf1a9be665181701716 OK 2022/01/12 05:34:21 DEBUG : both8: md5 = b61b4d911ed69c8b3aaa150e91be0e80 OK 2022/01/12 05:34:22 DEBUG : only8: md5 = 3101c16466317b89bf84fa5c34e5d386 OK 2022/01/12 05:34:22 DEBUG : both9: md5 = 94ea46819e1c59971be94d6a20b4f178 OK 2022/01/12 05:34:22 DEBUG : only9: md5 = 3b9ba2dfdb48e189a65ba77a3478b5ad OK 2022/01/12 05:34:23 DEBUG : both10: md5 = 2be0b7364147ac7efe6db8cbfbe95aaa OK 2022/01/12 05:34:23 DEBUG : only10: md5 = f406b0cee92af5db3c5146522fbc752a OK 2022/01/12 05:34:23 DEBUG : both11: md5 = 1cfefdf9e730340dcda131c08b9090c3 OK 2022/01/12 05:34:24 DEBUG : only11: md5 = f763ed5d6bf49a7891fd80b97622c548 OK 2022/01/12 05:34:24 DEBUG : both12: md5 = f3e22ca2d2316b79d70a89139491e7bf OK 2022/01/12 05:34:25 DEBUG : only12: md5 = a9e888332366a3398be10d322cadf284 OK 2022/01/12 05:34:25 DEBUG : both13: md5 = 69bcdc5c1c18c0644f87d9d23eba932d OK 2022/01/12 05:34:25 DEBUG : only13: md5 = 46f25c0ea4560e3397c0b707696fdedd OK 2022/01/12 05:34:25 DEBUG : both14: md5 = 384eb1e202527c5392d8721a6aa5af5f OK 2022/01/12 05:34:26 DEBUG : only14: md5 = bf8479e1a119212c51d0a4a97d90e45e OK 2022/01/12 05:34:26 DEBUG : both15: md5 = d249f2f8928a40fd6f428077fa294aae OK 2022/01/12 05:34:26 DEBUG : only15: md5 = f1f916aa9b546a664a00b17e85df23f8 OK 2022/01/12 05:34:28 DEBUG : both16: md5 = b1ba38fa8747bd367e6e047cb05f4f25 OK 2022/01/12 05:34:28 DEBUG : only16: md5 = f26c5cd74e7974b6e9893df3621c31f3 OK 2022/01/12 05:34:28 DEBUG : both17: md5 = 0ea613f2f74079738bbbc449a356b671 OK 2022/01/12 05:34:29 DEBUG : only17: md5 = 33ec3d4c071e74091eda3a96378f8449 OK 2022/01/12 05:34:30 DEBUG : both18: md5 = f26df8ae6fea15f4feeff53875b3336f OK 2022/01/12 05:34:30 DEBUG : only18: md5 = 8912605fcc3b16582d2675ceb64bc890 OK 2022/01/12 05:34:31 DEBUG : both19: md5 = 725ad75f10f84ddc86ebb9fc5bb6a443 OK 2022/01/12 05:34:31 DEBUG : only19: md5 = f6d00f6ab99acc7493b4582a9a0374da OK 2022/01/12 05:34:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for checks to finish 2022/01/12 05:34:33 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both1: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both0: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both11: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both10: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both12: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both13: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both14: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both15: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both17: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both16: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both18: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both19: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both2: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both3: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both4: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both5: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both7: Unchanged skipping 2022/01/12 05:34:33 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/01/12 05:34:33 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both6: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both8: Unchanged skipping 2022/01/12 05:34:33 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/01/12 05:34:33 DEBUG : both9: Unchanged skipping 2022/01/12 05:34:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Waiting for transfers to finish 2022/01/12 05:34:34 DEBUG : only11: md5 = f01b5f4b12ee0b8be01156517fd2bb39 OK 2022/01/12 05:34:34 INFO : only11: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only1: md5 = 4c1b15d4f22855172a233feeb6a6e242 OK 2022/01/12 05:34:34 INFO : only1: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only10: md5 = 7be73baaeb8dc5f130971956df31be18 OK 2022/01/12 05:34:34 INFO : only10: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only0: md5 = 197e4146dd765e0647955bde372ee4b0 OK 2022/01/12 05:34:34 INFO : only0: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only14: md5 = fa1cf06765d7693a585822d1c22045cb OK 2022/01/12 05:34:34 INFO : only14: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only13: md5 = 92412ebd495449a87b4355f25c7b8341 OK 2022/01/12 05:34:34 INFO : only13: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only15: md5 = 139be6d0a34bd7bc1713d62250fdac6b OK 2022/01/12 05:34:34 INFO : only15: Copied (replaced existing) 2022/01/12 05:34:34 DEBUG : only12: md5 = a4a2c2fdbbe52fbd1eaaea0637bd4306 OK 2022/01/12 05:34:34 INFO : only12: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only16: md5 = f3d3b425a758d028f1982106def398aa OK 2022/01/12 05:34:35 INFO : only16: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only17: md5 = b7bb37734ffd552202adae70fd67db1e OK 2022/01/12 05:34:35 INFO : only17: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only18: md5 = 271de37660b791d05246655ef83931fc OK 2022/01/12 05:34:35 INFO : only18: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only19: md5 = e41cefe268cba3fe838596c1ac051207 OK 2022/01/12 05:34:35 INFO : only19: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only3: md5 = ed60f49f321741e58977b8160472ba02 OK 2022/01/12 05:34:35 INFO : only3: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only2: md5 = 282946cd626cc62fba15c4526372dabf OK 2022/01/12 05:34:35 INFO : only2: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only4: md5 = be4414c0ac9cdca538a8468277bfb649 OK 2022/01/12 05:34:35 INFO : only4: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only6: md5 = cb07c4c847b85a30e4a7ecab30e7dfe4 OK 2022/01/12 05:34:35 INFO : only6: Copied (replaced existing) 2022/01/12 05:34:35 DEBUG : only5: md5 = 930e2a580ac7607717b740ffe0bb9a2a OK 2022/01/12 05:34:35 INFO : only5: Copied (replaced existing) 2022/01/12 05:34:36 DEBUG : only7: md5 = d0d16ff2cf27c84f1b9610d820e30a22 OK 2022/01/12 05:34:36 INFO : only7: Copied (replaced existing) 2022/01/12 05:34:36 DEBUG : only8: md5 = 5a46b21d29e0d40082f1d6b0166e4be2 OK 2022/01/12 05:34:36 INFO : only8: Copied (replaced existing) 2022/01/12 05:34:36 DEBUG : only9: md5 = 9211ad0d323ad14105f2e3d48c35f3b4 OK 2022/01/12 05:34:36 INFO : only9: Copied (replaced existing) 2022/01/12 05:34:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (29.81s) PASS 2022/01/12 05:34:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tisened6vujilar6bogahek8': Purge remote 2022/01/12 05:34:44 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m42.356310163s (try 1/5)