"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2022/03/02 05:00:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2" 2022/03/02 05:00:42 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/03/02 05:00:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2.bin" 2022/03/02 05:00:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2" 2022/03/02 05:00:43 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:00:44 DEBUG : Creating backend with remote "/tmp/rclone1696422540" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.41s) === 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-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:44 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/03/02 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.39s) === RUN TestCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:45 DEBUG : sub dir/hello world: md5 = 0cf75e1c95f2f19113d2a84b0e515eb1 OK 2022/03/02 05:00:45 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (2.52s) === RUN TestCopyMissingDirectory run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:47 DEBUG : Creating backend with remote "/non-existing" 2022/03/02 05:00:47 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/03/02 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.21s) === RUN TestCopyNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:48 DEBUG : sub dir/hello world: md5 = eb7aa14e8e1822a8d3718e10695d13fa OK 2022/03/02 05:00:48 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.73s) === RUN TestCopyCheckFirst run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Running all checks before starting transfers 2022/03/02 05:00:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Checks finished, now starting transfers 2022/03/02 05:00:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:49 DEBUG : sub dir/hello world: md5 = d47125b16094d3240a42a0094d60f6b6 OK 2022/03/02 05:00:49 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (0.63s) === RUN TestSyncNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:49 ERROR : Ignoring --no-traverse with sync 2022/03/02 05:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:50 DEBUG : sub dir/hello world: md5 = 4b821ddc37a5af245ca5ce828387327e OK 2022/03/02 05:00:50 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:00:50 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.70s) === RUN TestCopyWithDepth run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:52 DEBUG : hello world2: md5 = c1b89708d7a6a04c6855073209ae2fc3 OK 2022/03/02 05:00:52 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.26s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:52 DEBUG : hello world2: Excluded 2022/03/02 05:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:53 DEBUG : potato2: md5 = d1d4f26b169633f258df63106dd677f4 OK 2022/03/02 05:00:53 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.63s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:55 DEBUG : potato2: md5 = 26330bcdf2e2a0e2754eb33e8a7a4b11 OK 2022/03/02 05:00:55 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (1.58s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:56 DEBUG : sub dir2: Making directory 2022/03/02 05:00:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:57 DEBUG : sub dir/hello world: md5 = 0ae249c56242970030ff0c428a6cc919 OK 2022/03/02 05:00:57 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:00:57 DEBUG : sub dir2: Making directory 2022/03/02 05:00:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.68s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:57 DEBUG : sub dir2: Making directory 2022/03/02 05:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:58 DEBUG : sub dir/hello world: md5 = 82a9d2fa608c4c196256d19e49160b9a OK 2022/03/02 05:00:58 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:00:58 INFO : sub dir/hello world: Deleted 2022/03/02 05:00:58 DEBUG : sub dir: Making directory 2022/03/02 05:00:58 DEBUG : sub dir2: Making directory 2022/03/02 05:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.45s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:00:59 DEBUG : sub dir2: Making directory 2022/03/02 05:00:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:00:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:00:59 DEBUG : sub dir/hello world: md5 = 94413ee7fe9892cfce02d73ea016a38b OK 2022/03/02 05:00:59 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:00:59 DEBUG : sub dir2: Making directory 2022/03/02 05:00:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': copied 1 directories 2022/03/02 05:00:59 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.17s) === RUN TestServerSideCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:00 DEBUG : sub dir/hello world: md5 = f1568b06de31a0ace796402fefcd5220 OK 2022/03/02 05:01:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-rixizif3luvivas4robayij7" 2022/03/02 05:01:01 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:01:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rixizif3luvivas4robayij7.bin" 2022/03/02 05:01:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-rixizif3luvivas4robayij7" sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2' -> Encrypted drive 'TestCryptSwift:rclone-test-rixizif3luvivas4robayij7' 2022/03/02 05:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rixizif3luvivas4robayij7': Waiting for checks to finish 2022/03/02 05:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rixizif3luvivas4robayij7': Waiting for transfers to finish 2022/03/02 05:01:02 INFO : sub dir/hello world: Copied (server-side copy) 2022/03/02 05:01:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-rixizif3luvivas4robayij7': Purge remote 2022/03/02 05:01:03 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:04 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (4.44s) === RUN TestCopyAfterDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:05 DEBUG : sub dir/hello world: md5 = 9acdd3f202067936392f9179e60cc7be OK 2022/03/02 05:01:05 ERROR : : error listing: directory not found 2022/03/02 05:01:05 DEBUG : Local file system at /tmp/rclone1696422540: Making directory 2022/03/02 05:01:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.53s) === RUN TestCopyRedownload run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:06 DEBUG : sub dir/hello world: md5 = 8b0a63b94f8d017161c0a05807f96b44 OK 2022/03/02 05:01:08 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for checks to finish 2022/03/02 05:01:08 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for transfers to finish 2022/03/02 05:01:09 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (3.60s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:10 DEBUG : check sum: md5 = 68e49ee50b4c2af22f7f558eb06da0a3 OK 2022/03/02 05:01:10 INFO : check sum: Copied (new) 2022/03/02 05:01:10 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:10 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/03/02 05:01:10 DEBUG : check sum: Size of src and dst objects identical 2022/03/02 05:01:10 DEBUG : check sum: Unchanged skipping 2022/03/02 05:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:10 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:10 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.56s) === RUN TestSyncSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:12 DEBUG : sizeonly: md5 = 3360ff11d4e108947935ef7d615689cb OK 2022/03/02 05:01:12 INFO : sizeonly: Copied (new) 2022/03/02 05:01:12 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:12 DEBUG : sizeonly: Sizes identical 2022/03/02 05:01:12 DEBUG : sizeonly: Unchanged skipping 2022/03/02 05:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:12 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:12 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.36s) === RUN TestSyncIgnoreSize run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:14 DEBUG : ignore-size: md5 = f51ab98665c0694e8beefb029c8a0abe OK 2022/03/02 05:01:14 INFO : ignore-size: Copied (new) 2022/03/02 05:01:14 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:15 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:15 DEBUG : ignore-size: Unchanged skipping 2022/03/02 05:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:15 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:15 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.74s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:16 DEBUG : existing: md5 = bf94f95bef448dbf5c1eece262a32558 OK 2022/03/02 05:01:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:16 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:16 DEBUG : existing: Unchanged skipping 2022/03/02 05:01:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:16 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:16 INFO : There was nothing to transfer 2022/03/02 05:01:16 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/03/02 05:01:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:16 DEBUG : existing: md5 = 7e60af4a2008f69aa63aadfbf973713f OK 2022/03/02 05:01:16 INFO : existing: Copied (replaced existing) 2022/03/02 05:01:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.31s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:18 DEBUG : existing: md5 = 310683511fd25701df32d84c86642d53 OK 2022/03/02 05:01:18 INFO : existing: Copied (new) 2022/03/02 05:01:18 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:19 DEBUG : existing: Destination exists, skipping 2022/03/02 05:01:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:19 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:19 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (1.56s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:20 DEBUG : b/potato: md5 = c1f25bd307da60e999941a04e0b0c5cd OK 2022/03/02 05:01:20 DEBUG : c/non empty space: md5 = df311249939c9b4abd79e967191b260a OK 2022/03/02 05:01:20 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/03/02 05:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:21 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:21 DEBUG : c/non empty space: Unchanged skipping 2022/03/02 05:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:22 DEBUG : a/potato2: md5 = 3a0589b8336236c0f85dc637098338fc OK 2022/03/02 05:01:22 INFO : a/potato2: Copied (new) 2022/03/02 05:01:22 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:22 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (5.38s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:25 DEBUG : empty space: md5 = 3a14573d656b6577c201eec6ef6708ad OK 2022/03/02 05:01:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:25 DEBUG : empty space: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2022/03/02 05:01:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:25 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2022/03/02 05:01:25 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:26 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/03/02 05:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:26 DEBUG : empty space: md5 = 92c801c1d6500122cf3d73ddf858866c OK 2022/03/02 05:01:26 INFO : empty space: Copied (replaced existing) 2022/03/02 05:01:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (2.90s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.31s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:29 DEBUG : foo: md5 = 048c7f71ffb93f4f066e8ab3f3262590 OK 2022/03/02 05:01:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:29 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/03/02 05:01:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:29 DEBUG : foo: md5 = 717f1b64770aa5d99b0989530653ae59 OK 2022/03/02 05:01:29 INFO : foo: Copied (replaced existing) 2022/03/02 05:01:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.47s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:31 DEBUG : empty space: md5 = ec9b89c974ebb0c58f43713e067cf6e9 OK 2022/03/02 05:01:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:31 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:31 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:31 DEBUG : potato: md5 = 3484391ca844b38ed2691c67d7425731 OK 2022/03/02 05:01:31 INFO : potato: Copied (new) 2022/03/02 05:01:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.28s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:33 DEBUG : potato: md5 = d8837ad6c5d88ed023c7dd20e8b0eb6d OK 2022/03/02 05:01:33 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/03/02 05:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:33 DEBUG : potato: md5 = 4ef87888db2a89cafa02c0e8c2e55c62 OK 2022/03/02 05:01:33 INFO : potato: Copied (replaced existing) 2022/03/02 05:01:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (2.18s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:35 DEBUG : potato: md5 = b5721497b5f7b443f7f9fe0bbeac13c7 OK 2022/03/02 05:01:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:35 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/03/02 05:01:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:36 DEBUG : potato: md5 = 7a529585540b4605f37306a1e3df7486 OK 2022/03/02 05:01:36 INFO : potato: Copied (replaced existing) 2022/03/02 05:01:36 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.98s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:37 DEBUG : potato: md5 = 557d9624e139236dcf8968e6862ce2ab OK 2022/03/02 05:01:37 DEBUG : empty space: md5 = f7e8bd8af8d7ebcfa5ed536e264daa9d OK 2022/03/02 05:01:37 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/03/02 05:01:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:37 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:37 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:37 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (2.28s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:40 DEBUG : potato: md5 = faff874df79b6f6938f7ceb15f8ceaee OK 2022/03/02 05:01:40 DEBUG : empty space: md5 = e9bd9f846b9cc28104818435de2b72a4 OK 2022/03/02 05:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:40 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:41 DEBUG : potato2: md5 = 826611767ba211724a17d234bcf0e2ea OK 2022/03/02 05:01:41 INFO : potato2: Copied (new) 2022/03/02 05:01:41 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:41 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.55s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:43 DEBUG : b/potato: md5 = 515b0950578138582500592bb2e24987 OK 2022/03/02 05:01:43 DEBUG : c/non empty space: md5 = 45f26e2b3eccbf57a41c9bfd7dc0fa71 OK 2022/03/02 05:01:43 DEBUG : d: Making directory 2022/03/02 05:01:43 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/03/02 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:44 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:44 DEBUG : c/non empty space: Unchanged skipping 2022/03/02 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:44 DEBUG : a/potato2: md5 = 1843155751470b97427f30834696a12e OK 2022/03/02 05:01:44 INFO : a/potato2: Copied (new) 2022/03/02 05:01:44 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:44 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (3.01s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:46 DEBUG : b/potato: md5 = 89585d4c7231be5ac69a9b7f805eacc2 OK 2022/03/02 05:01:46 DEBUG : c/non empty space: md5 = 1f71fa74518b642ecbbf180097ee2f42 OK 2022/03/02 05:01:46 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/03/02 05:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:47 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:47 DEBUG : c/non empty space: Unchanged skipping 2022/03/02 05:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:47 DEBUG : a/potato2: md5 = 8eeb2175f90fffc7fb3bc8044886b1a9 OK 2022/03/02 05:01:47 INFO : a/potato2: Copied (new) 2022/03/02 05:01:47 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': not deleting files as there were IO errors 2022/03/02 05:01:47 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.09s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:49 DEBUG : potato: md5 = 80de36498ca1028d1114120e5ffd7e78 OK 2022/03/02 05:01:50 DEBUG : empty space: md5 = 6186a28024dd2f2d8cfebad1f6afb7e1 OK 2022/03/02 05:01:50 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:50 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:50 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:51 INFO : potato: Deleted 2022/03/02 05:01:51 DEBUG : potato2: md5 = ca95a0dd41775a1c453e9b85c4f448f9 OK 2022/03/02 05:01:51 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (3.17s) === RUN TestSyncDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:52 DEBUG : potato: md5 = 12c24eb42ee4e96123283d9b620b5354 OK 2022/03/02 05:01:53 DEBUG : empty space: md5 = abea73eee960da6f29e4152b97f09dca OK 2022/03/02 05:01:53 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:53 INFO : potato: Deleted 2022/03/02 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:53 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:53 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:54 DEBUG : potato2: md5 = 18e09cfc79dd50f74cf47fc325059f8c OK 2022/03/02 05:01:54 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.53s) === RUN TestCopyDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:56 DEBUG : potato: md5 = c3d630570f789e74cc67392933460f69 OK 2022/03/02 05:01:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:56 DEBUG : potato2: md5 = 5c0b0f61fd75f3a078c93581ef5253cb OK 2022/03/02 05:01:56 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (2.04s) === RUN TestSyncWithExclude run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:01:58 DEBUG : potato2: md5 = 3c13c43cac1fe2d295a74518c26e5b28 OK 2022/03/02 05:01:58 DEBUG : empty space: md5 = 9e34aa83440eb8b2ef7c1cfc9db50c07 OK 2022/03/02 05:01:58 DEBUG : enormous: Excluded 2022/03/02 05:01:58 DEBUG : potato2: Excluded 2022/03/02 05:01:59 DEBUG : potato2: Excluded 2022/03/02 05:01:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:01:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:59 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:01:59 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:59 INFO : There was nothing to transfer 2022/03/02 05:01:59 DEBUG : enormous: Excluded 2022/03/02 05:01:59 DEBUG : potato2: Excluded 2022/03/02 05:01:59 DEBUG : potato2: Excluded 2022/03/02 05:01:59 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for checks to finish 2022/03/02 05:01:59 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:01:59 DEBUG : empty space: Unchanged skipping 2022/03/02 05:01:59 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for transfers to finish 2022/03/02 05:01:59 DEBUG : Waiting for deletions to finish 2022/03/02 05:01:59 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.46s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:00 DEBUG : potato2: md5 = 54fdcf8dbfb64f26b71f2c65ebe8c6c5 OK 2022/03/02 05:02:00 DEBUG : empty space: md5 = 058a6896bac8961e7cd8de70e1804ab5 OK 2022/03/02 05:02:01 DEBUG : enormous: md5 = 6d3f7bebc0480a726e1c32231797cb60 OK 2022/03/02 05:02:01 DEBUG : enormous: Excluded 2022/03/02 05:02:01 DEBUG : potato2: Excluded 2022/03/02 05:02:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:02 DEBUG : empty space: Unchanged skipping 2022/03/02 05:02:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:02 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:02 INFO : enormous: Deleted 2022/03/02 05:02:02 INFO : potato2: Deleted 2022/03/02 05:02:02 INFO : There was nothing to transfer 2022/03/02 05:02:03 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for checks to finish 2022/03/02 05:02:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:03 DEBUG : empty space: Unchanged skipping 2022/03/02 05:02:03 DEBUG : Local file system at /tmp/rclone1696422540: Waiting for transfers to finish 2022/03/02 05:02:03 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:03 INFO : enormous: Deleted 2022/03/02 05:02:03 INFO : potato2: Deleted 2022/03/02 05:02:03 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (4.24s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:04 DEBUG : one: md5 = b754b05ddaa338cba1c56feb30946638 OK 2022/03/02 05:02:05 DEBUG : two: md5 = 876585c8563b9d434bb283b712b9d2d8 OK 2022/03/02 05:02:06 DEBUG : three: md5 = 8424a056530e89b7b87dac0f8ab8ecf1 OK 2022/03/02 05:02:06 DEBUG : four: md5 = aa5d264d856df2ed795b1fbfecc59fb6 OK 2022/03/02 05:02:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:07 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/03/02 05:02:07 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/03/02 05:02:07 DEBUG : three: Sizes identical 2022/03/02 05:02:07 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/03/02 05:02:07 DEBUG : one: Destination is newer than source, skipping 2022/03/02 05:02:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:07 DEBUG : four: md5 = 2a70c05e9ce6cf949086d81ad33a84b1 OK 2022/03/02 05:02:07 INFO : four: Copied (replaced existing) 2022/03/02 05:02:07 DEBUG : two: md5 = 8f85e9a6055593aa69b246847eceb23f OK 2022/03/02 05:02:07 INFO : two: Copied (replaced existing) 2022/03/02 05:02:08 DEBUG : five: md5 = 6870954474b7486d210248c83702839e OK 2022/03/02 05:02:08 INFO : five: Copied (new) 2022/03/02 05:02:08 DEBUG : Waiting for deletions to finish sync_test.go:992: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (6.65s) === 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-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" sync_test.go:1058: Can track renames: false 2022/03/02 05:02:11 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Ignoring --track-renames as the source and destination do not have a common hash 2022/03/02 05:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:11 DEBUG : potato: md5 = dd8de1039bd2b6792f7068675a8adc84 OK 2022/03/02 05:02:11 INFO : potato: Copied (new) 2022/03/02 05:02:11 DEBUG : yam: md5 = 17a11352a6d829efdeb2d7ed824861d7 OK 2022/03/02 05:02:11 INFO : yam: Copied (new) 2022/03/02 05:02:11 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:11 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Ignoring --track-renames as the source and destination do not have a common hash 2022/03/02 05:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:11 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:11 DEBUG : potato: Unchanged skipping 2022/03/02 05:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:12 DEBUG : yaml: md5 = 9caafd6addeaeb1bb37260634650e096 OK 2022/03/02 05:02:12 INFO : yaml: Copied (new) 2022/03/02 05:02:12 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:12 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (3.34s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/03/02 05:02:14 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Making map for --track-renames 2022/03/02 05:02:14 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Finished making map for --track-renames 2022/03/02 05:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for renames to finish 2022/03/02 05:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:14 DEBUG : potato: md5 = bac254f8cdd3a4b1e5cfe70a8e3800a5 OK 2022/03/02 05:02:14 INFO : potato: Copied (new) 2022/03/02 05:02:14 DEBUG : yam: md5 = a93d47fdac4ec430bc59f057625af53b OK 2022/03/02 05:02:14 INFO : yam: Copied (new) 2022/03/02 05:02:14 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:15 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Making map for --track-renames 2022/03/02 05:02:15 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Finished making map for --track-renames 2022/03/02 05:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:15 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:15 DEBUG : potato: Unchanged skipping 2022/03/02 05:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for renames to finish 2022/03/02 05:02:15 INFO : yam: Copied (server-side copy) to: yaml 2022/03/02 05:02:15 INFO : yam: Deleted 2022/03/02 05:02:15 INFO : yaml: Renamed from "yam" 2022/03/02 05:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (3.26s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/03/02 05:02:17 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Making map for --track-renames 2022/03/02 05:02:17 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Finished making map for --track-renames 2022/03/02 05:02:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for renames to finish 2022/03/02 05:02:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:18 DEBUG : sub/yam: md5 = c11a9115c1061937f795b6ab4bd6135c OK 2022/03/02 05:02:18 INFO : sub/yam: Copied (new) 2022/03/02 05:02:18 DEBUG : potato: md5 = 8b21f0a4d7b8016bda4e5879146dff75 OK 2022/03/02 05:02:18 INFO : potato: Copied (new) 2022/03/02 05:02:18 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:18 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:18 DEBUG : potato: Unchanged skipping 2022/03/02 05:02:18 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Making map for --track-renames 2022/03/02 05:02:18 INFO : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Finished making map for --track-renames 2022/03/02 05:02:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for renames to finish 2022/03/02 05:02:19 INFO : sub/yam: Copied (server-side copy) to: yam 2022/03/02 05:02:19 INFO : sub/yam: Deleted 2022/03/02 05:02:19 INFO : yam: Renamed from "sub/yam" 2022/03/02 05:02:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.82s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:21 DEBUG : sub dir/hello world: md5 = 13770ef028174568ae1b46f4648a2e42 OK 2022/03/02 05:02:21 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:02:21 INFO : sub dir/hello world: Deleted 2022/03/02 05:02:21 DEBUG : nested/sub dir/file: md5 = bc2237c65bf745894a05c25881e94d6c OK 2022/03/02 05:02:21 INFO : nested/sub dir/file: Copied (new) 2022/03/02 05:02:21 INFO : nested/sub dir/file: Deleted 2022/03/02 05:02:21 INFO : sub dir: Removing directory 2022/03/02 05:02:21 INFO : nested/sub dir: Removing directory 2022/03/02 05:02:21 INFO : nested: Removing directory 2022/03/02 05:02:21 DEBUG : Local file system at /tmp/rclone1696422540: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (2.24s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:23 DEBUG : sub dir/hello world: md5 = 93a8cb68588105bb96ef8a6a1dc7db4c OK 2022/03/02 05:02:23 INFO : sub dir/hello world: Copied (new) 2022/03/02 05:02:23 INFO : sub dir/hello world: Deleted 2022/03/02 05:02:23 DEBUG : nested/sub dir/file: md5 = 806bcbb733711a0555d3d02c21b739de OK 2022/03/02 05:02:23 INFO : nested/sub dir/file: Copied (new) 2022/03/02 05:02:23 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.83s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:25 DEBUG : existing: md5 = 68ba67f62645d9f613c2df2411e92078 OK 2022/03/02 05:02:25 INFO : existing: Copied (new) 2022/03/02 05:02:25 INFO : existing: Deleted 2022/03/02 05:02:25 DEBUG : existing-b: md5 = 4fc103ee84b8c21ecba465aa097b69d5 OK 2022/03/02 05:02:25 INFO : existing-b: Copied (new) 2022/03/02 05:02:25 INFO : existing-b: Deleted 2022/03/02 05:02:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:02:26 DEBUG : existing: Destination exists, skipping 2022/03/02 05:02:26 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/03/02 05:02:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:02:26 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (2.29s) === RUN TestServerSideMove run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-laqowad0waxoxan0hehuron4" 2022/03/02 05:02:26 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:02:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-laqowad0waxoxan0hehuron4.bin" 2022/03/02 05:02:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-laqowad0waxoxan0hehuron4" 2022/03/02 05:02:28 DEBUG : potato2: md5 = 2801b8de1be4f9e2b2840af511a78f7c OK 2022/03/02 05:02:28 DEBUG : empty space: md5 = a428d5f8ca02371807c022535b2288af OK 2022/03/02 05:02:29 DEBUG : potato3: md5 = b2f9b95ff52e7287716a56775488851a OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2' -> Encrypted drive 'TestCryptSwift:rclone-test-laqowad0waxoxan0hehuron4' 2022/03/02 05:02:30 DEBUG : empty space: md5 = 7b2445499f79b8afa563a44e1c40ecbc OK 2022/03/02 05:02:32 DEBUG : potato3: md5 = 90226a15cb026fc4e9888ed0e3356c7b OK 2022/03/02 05:02:32 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/02 05:02:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-laqowad0waxoxan0hehuron4': Waiting for checks to finish 2022/03/02 05:02:32 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:32 DEBUG : empty space: Unchanged skipping 2022/03/02 05:02:32 INFO : empty space: Deleted 2022/03/02 05:02:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-laqowad0waxoxan0hehuron4': Waiting for transfers to finish 2022/03/02 05:02:33 INFO : potato3: Copied (server-side copy) 2022/03/02 05:02:33 INFO : potato3: Deleted 2022/03/02 05:02:34 INFO : potato2: Copied (server-side copy) 2022/03/02 05:02:34 INFO : potato2: Deleted 2022/03/02 05:02:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-bivizoz6deyekow3xubefum1" 2022/03/02 05:02:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bivizoz6deyekow3xubefum1.bin" 2022/03/02 05:02:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-bivizoz6deyekow3xubefum1" 2022/03/02 05:02:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bivizoz6deyekow3xubefum1': Waiting for checks to finish 2022/03/02 05:02:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bivizoz6deyekow3xubefum1': Waiting for transfers to finish 2022/03/02 05:02:36 INFO : empty space: Copied (server-side copy) 2022/03/02 05:02:37 INFO : empty space: Deleted 2022/03/02 05:02:37 INFO : potato2: Copied (server-side copy) 2022/03/02 05:02:37 INFO : potato3: Copied (server-side copy) 2022/03/02 05:02:38 INFO : potato3: Deleted 2022/03/02 05:02:38 INFO : potato2: Deleted 2022/03/02 05:02:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-bivizoz6deyekow3xubefum1': Purge remote 2022/03/02 05:02:38 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:39 INFO : empty space.bin: Deleted 2022/03/02 05:02:39 INFO : potato2.bin: Deleted 2022/03/02 05:02:39 INFO : potato3.bin: Deleted 2022/03/02 05:02:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-laqowad0waxoxan0hehuron4': Purge remote 2022/03/02 05:02:39 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (12.85s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:39 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-galexez2betudir6jitewiv9" 2022/03/02 05:02:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-galexez2betudir6jitewiv9.bin" 2022/03/02 05:02:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-galexez2betudir6jitewiv9" 2022/03/02 05:02:41 DEBUG : potato2: md5 = 864aafaf68d399308ba837c4772f4bf5 OK 2022/03/02 05:02:41 DEBUG : empty space: md5 = 99fb741bc12aaac11a185131c540952c OK 2022/03/02 05:02:42 DEBUG : potato3: md5 = a9a13b2174926848683610870c415b49 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2' -> Encrypted drive 'TestCryptSwift:rclone-test-galexez2betudir6jitewiv9' 2022/03/02 05:02:44 DEBUG : empty space: md5 = f71ff0882fcc0cfb44e8724f7e2cd222 OK 2022/03/02 05:02:45 DEBUG : potato3: md5 = a0905f6b386b8ad3cd00077770239831 OK 2022/03/02 05:02:45 DEBUG : empty space: Excluded 2022/03/02 05:02:45 DEBUG : empty space: Excluded 2022/03/02 05:02:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/02 05:02:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galexez2betudir6jitewiv9': Waiting for checks to finish 2022/03/02 05:02:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galexez2betudir6jitewiv9': Waiting for transfers to finish 2022/03/02 05:02:45 INFO : potato3: Copied (server-side copy) 2022/03/02 05:02:45 INFO : potato2: Copied (server-side copy) 2022/03/02 05:02:46 INFO : potato3: Deleted 2022/03/02 05:02:46 INFO : potato2: Deleted 2022/03/02 05:02:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-pafezeg7yuxofuh4bohoviv4" 2022/03/02 05:02:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pafezeg7yuxofuh4bohoviv4.bin" 2022/03/02 05:02:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-pafezeg7yuxofuh4bohoviv4" 2022/03/02 05:02:48 DEBUG : empty space: Excluded 2022/03/02 05:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pafezeg7yuxofuh4bohoviv4': Waiting for checks to finish 2022/03/02 05:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pafezeg7yuxofuh4bohoviv4': Waiting for transfers to finish 2022/03/02 05:02:48 INFO : potato3: Copied (server-side copy) 2022/03/02 05:02:49 INFO : potato2: Copied (server-side copy) 2022/03/02 05:02:49 INFO : potato3: Deleted 2022/03/02 05:02:49 INFO : potato2: Deleted 2022/03/02 05:02:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-pafezeg7yuxofuh4bohoviv4': Purge remote 2022/03/02 05:02:49 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:50 INFO : potato2.bin: Deleted 2022/03/02 05:02:50 INFO : potato3.bin: Deleted 2022/03/02 05:02:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-galexez2betudir6jitewiv9': Purge remote 2022/03/02 05:02:50 DEBUG : Waiting for deletions to finish 2022/03/02 05:02:50 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (11.68s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:02:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-qukubog3hojucol2koneguw0" 2022/03/02 05:02:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qukubog3hojucol2koneguw0.bin" 2022/03/02 05:02:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-qukubog3hojucol2koneguw0" 2022/03/02 05:02:52 DEBUG : potato2: md5 = be7a8145ab6acb1e357478f96027390a OK 2022/03/02 05:02:53 DEBUG : empty space: md5 = 6ac9f92735c7253ac4f258f322bab42a OK 2022/03/02 05:02:54 DEBUG : potato3: md5 = 92374c7f8169bc7b6f6556280a5df75a OK 2022/03/02 05:02:54 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2' -> Encrypted drive 'TestCryptSwift:rclone-test-qukubog3hojucol2koneguw0' 2022/03/02 05:02:55 DEBUG : empty space: md5 = f38ff01faa285dce0c4bcb398f2217eb OK 2022/03/02 05:02:56 DEBUG : potato3: md5 = f393b2f6432e906545e3180b19583f29 OK 2022/03/02 05:02:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/03/02 05:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qukubog3hojucol2koneguw0': Waiting for checks to finish 2022/03/02 05:02:57 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:02:57 DEBUG : empty space: Unchanged skipping 2022/03/02 05:02:57 INFO : empty space: Deleted 2022/03/02 05:02:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qukubog3hojucol2koneguw0': Waiting for transfers to finish 2022/03/02 05:02:57 INFO : potato3: Copied (server-side copy) 2022/03/02 05:02:57 INFO : potato3: Deleted 2022/03/02 05:02:57 INFO : potato2: Copied (server-side copy) 2022/03/02 05:02:57 INFO : potato2: Deleted 2022/03/02 05:02:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-zuwitaz5kilebef5qasawiw5" 2022/03/02 05:02:58 DEBUG : Config file has changed externaly - reloading 2022/03/02 05:02:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zuwitaz5kilebef5qasawiw5.bin" 2022/03/02 05:02:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-zuwitaz5kilebef5qasawiw5" 2022/03/02 05:02:59 DEBUG : tomatoDir: Making directory 2022/03/02 05:02:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zuwitaz5kilebef5qasawiw5': Waiting for checks to finish 2022/03/02 05:02:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zuwitaz5kilebef5qasawiw5': Waiting for transfers to finish 2022/03/02 05:03:00 INFO : potato2: Copied (server-side copy) 2022/03/02 05:03:00 INFO : potato2: Deleted 2022/03/02 05:03:00 INFO : empty space: Copied (server-side copy) 2022/03/02 05:03:00 INFO : potato3: Copied (server-side copy) 2022/03/02 05:03:00 INFO : empty space: Deleted 2022/03/02 05:03:00 INFO : potato3: Deleted 2022/03/02 05:03:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-zuwitaz5kilebef5qasawiw5': Purge remote 2022/03/02 05:03:01 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:02 INFO : empty space.bin: Deleted 2022/03/02 05:03:02 INFO : potato3.bin: Deleted 2022/03/02 05:03:02 INFO : potato2.bin: Deleted 2022/03/02 05:03:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-qukubog3hojucol2koneguw0': Purge remote 2022/03/02 05:03:02 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (11.32s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/rclone-move-test" 2022/03/02 05:03:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/rclone-move-test.bin" 2022/03/02 05:03:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/rclone-move-test" 2022/03/02 05:03:04 DEBUG : potato2: md5 = a37d19b57f4ffdf64a030bc9a168467e OK --- PASS: TestServerSideMoveOverlap (2.63s) === RUN TestSyncOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/rclone-sync-test" 2022/03/02 05:03:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/rclone-sync-test.bin" 2022/03/02 05:03:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/rclone-sync-test" --- PASS: TestSyncOverlap (1.73s) === RUN TestSyncCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:03:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/CompareDest" 2022/03/02 05:03:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/CompareDest.bin" 2022/03/02 05:03:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/CompareDest" 2022/03/02 05:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:10 DEBUG : one: md5 = d6fbc2ae01c4cc4a79fe453e04b0ae6e OK 2022/03/02 05:03:10 INFO : one: Copied (new) 2022/03/02 05:03:10 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:10 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:11 DEBUG : one: md5 = b6c04e4af247aec3711534bac26639c0 OK 2022/03/02 05:03:11 INFO : one: Copied (replaced existing) 2022/03/02 05:03:11 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:12 DEBUG : dst/one: md5 = 4025da829ba83fa2f9e58585bfa69cd7 OK 2022/03/02 05:03:13 DEBUG : CompareDest/one: md5 = 38702a3390575bbb7f2c2b62b320b39e OK 2022/03/02 05:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:13 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/02 05:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:13 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:13 INFO : There was nothing to transfer 2022/03/02 05:03:14 DEBUG : CompareDest/two: md5 = 313b2e400be1e6dd043fbcb16e935585 OK 2022/03/02 05:03:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:15 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:15 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:15 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:15 INFO : There was nothing to transfer 2022/03/02 05:03:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:15 DEBUG : two: Destination found in --compare-dest, skipping 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:15 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:15 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:15 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/03/02 05:03:15 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:15 DEBUG : one: Destination found in --compare-dest, skipping 2022/03/02 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:16 DEBUG : two: md5 = 7daf9ce2b8c2ae9e6fcc84bdae32577d OK 2022/03/02 05:03:16 INFO : two: Copied (new) 2022/03/02 05:03:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (11.44s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:19 DEBUG : pre-dest1/1: md5 = 85265668109d015ba0ed1ec9a9927660 OK 2022/03/02 05:03:19 DEBUG : pre-dest2/2: md5 = 9324872ad7399f84e3fbe151945404d7 OK 2022/03/02 05:03:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dest" 2022/03/02 05:03:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dest.bin" 2022/03/02 05:03:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dest" 2022/03/02 05:03:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/pre-dest1" 2022/03/02 05:03:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/pre-dest1.bin" 2022/03/02 05:03:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/pre-dest1" 2022/03/02 05:03:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/pre-dest2" 2022/03/02 05:03:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/pre-dest2.bin" 2022/03/02 05:03:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/pre-dest2" 2022/03/02 05:03:23 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:23 DEBUG : 1: Destination found in --compare-dest, skipping 2022/03/02 05:03:23 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:23 DEBUG : 2: Destination found in --compare-dest, skipping 2022/03/02 05:03:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dest': Waiting for checks to finish 2022/03/02 05:03:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dest': Waiting for transfers to finish 2022/03/02 05:03:24 DEBUG : 3: md5 = 969907c056e2be4922321b24942656ab OK 2022/03/02 05:03:24 INFO : 3: Copied (new) 2022/03/02 05:03:24 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.55s) === RUN TestSyncCopyDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:03:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:26 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/CopyDest" 2022/03/02 05:03:26 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/CopyDest.bin" 2022/03/02 05:03:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/CopyDest" 2022/03/02 05:03:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:28 DEBUG : one: md5 = 7b1a9886497f5151c47466d40e0e902a OK 2022/03/02 05:03:28 INFO : one: Copied (new) 2022/03/02 05:03:28 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:29 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:03:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:29 DEBUG : one: md5 = fc1260f9d9492aa921cb873a1039aaef OK 2022/03/02 05:03:29 INFO : one: Copied (replaced existing) 2022/03/02 05:03:29 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:30 DEBUG : dst/one: md5 = f5f0bcd3b0578ff6b8e2d46db2177732 OK 2022/03/02 05:03:30 DEBUG : CopyDest/one: md5 = 9241439aaccf2cd151cfc3aae00c97c6 OK 2022/03/02 05:03:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/BackupDir" 2022/03/02 05:03:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/BackupDir.bin" 2022/03/02 05:03:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/BackupDir" 2022/03/02 05:03:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:32 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:32 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/03/02 05:03:33 INFO : one: Copied (server-side copy) 2022/03/02 05:03:33 INFO : one: Deleted 2022/03/02 05:03:33 INFO : one: Copied (server-side copy) 2022/03/02 05:03:33 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/03/02 05:03:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:33 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:34 DEBUG : CopyDest/two: md5 = 3c89a5a7e8455ba929d552fb71e08665 OK 2022/03/02 05:03:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:35 INFO : two: Copied (server-side copy) 2022/03/02 05:03:35 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/03/02 05:03:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:35 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:35 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:35 DEBUG : one: Unchanged skipping 2022/03/02 05:03:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:35 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:36 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:36 DEBUG : one: Unchanged skipping 2022/03/02 05:03:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:36 DEBUG : two: Unchanged skipping 2022/03/02 05:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:36 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:36 INFO : There was nothing to transfer 2022/03/02 05:03:38 DEBUG : CopyDest/three: md5 = 6cccad7756ee2a38fd7935335a87c12b OK 2022/03/02 05:03:39 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/03/02 05:03:39 DEBUG : three: Destination not found in --copy-dest 2022/03/02 05:03:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:39 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:39 DEBUG : one: Unchanged skipping 2022/03/02 05:03:39 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:39 DEBUG : two: Unchanged skipping 2022/03/02 05:03:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:40 DEBUG : three: md5 = 7fa2b461a86b98221019a877b6a1b8de OK 2022/03/02 05:03:40 INFO : three: Copied (new) 2022/03/02 05:03:40 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (16.17s) === RUN TestSyncBackupDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:42 DEBUG : dst/one: md5 = ee8d35842b078bf3377d2e2529c2e591 OK 2022/03/02 05:03:42 DEBUG : dst/two: md5 = b84a817167a03dd6a9d7254bb830408d OK 2022/03/02 05:03:43 DEBUG : dst/three.txt: md5 = 2c1a3d8943485dcb7e215c7d52520292 OK 2022/03/02 05:03:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:03:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:03:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup.bin" 2022/03/02 05:03:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:03:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:03:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:45 DEBUG : two: Unchanged skipping 2022/03/02 05:03:46 INFO : one: Copied (server-side copy) 2022/03/02 05:03:46 INFO : one: Deleted 2022/03/02 05:03:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:46 DEBUG : one: md5 = 796dbf60fca12ed92dae1ef87406ccad OK 2022/03/02 05:03:46 INFO : one: Copied (new) 2022/03/02 05:03:46 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:47 INFO : three.txt: Copied (server-side copy) 2022/03/02 05:03:47 INFO : three.txt: Deleted 2022/03/02 05:03:47 INFO : three.txt: Moved into backup dir 2022/03/02 05:03:48 DEBUG : dst/three.txt: md5 = 0428342bb76b13c370df070790a0ab9c OK 2022/03/02 05:03:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:48 DEBUG : two: Unchanged skipping 2022/03/02 05:03:48 INFO : one: Copied (server-side copy) 2022/03/02 05:03:49 INFO : one: Deleted 2022/03/02 05:03:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:49 DEBUG : one: md5 = 799f222ae0bcabc3512f051aee241e2b OK 2022/03/02 05:03:49 INFO : one: Copied (new) 2022/03/02 05:03:49 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:50 INFO : three.txt: Copied (server-side copy) 2022/03/02 05:03:50 INFO : three.txt: Deleted 2022/03/02 05:03:50 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (11.04s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:03:53 DEBUG : dst/one: md5 = d92b4f7db48d7d21327551a016f4cbfe OK 2022/03/02 05:03:54 DEBUG : dst/two: md5 = 45fdbe696f59037728d187d1a40bb389 OK 2022/03/02 05:03:54 DEBUG : dst/three.txt: md5 = 7d433bd138d5a60ae343402d35c07bca OK 2022/03/02 05:03:54 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:03:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:03:55 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:03:55 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup.bin" 2022/03/02 05:03:56 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:03:56 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:03:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:03:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:03:56 DEBUG : two: Unchanged skipping 2022/03/02 05:03:57 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:03:57 INFO : one: Deleted 2022/03/02 05:03:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:03:58 DEBUG : one: md5 = fb6556ba5c47cde9be7382da8306e559 OK 2022/03/02 05:03:58 INFO : one: Copied (new) 2022/03/02 05:03:58 DEBUG : Waiting for deletions to finish 2022/03/02 05:03:59 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:03:59 INFO : three.txt: Deleted 2022/03/02 05:03:59 INFO : three.txt: Moved into backup dir 2022/03/02 05:04:00 DEBUG : dst/three.txt: md5 = 69e0f400be4a364b82367573f05fa85b OK 2022/03/02 05:04:01 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:04:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:04:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:01 DEBUG : two: Unchanged skipping 2022/03/02 05:04:01 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:04:01 INFO : one: Deleted 2022/03/02 05:04:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:04:01 DEBUG : one: md5 = 7c3106dbafeadf4a88d62ccc62903892 OK 2022/03/02 05:04:01 INFO : one: Copied (new) 2022/03/02 05:04:01 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:04:02 INFO : three.txt: Deleted 2022/03/02 05:04:02 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (11.03s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:04 DEBUG : dst/one: md5 = 561c31dee779af449f6830b2f84ecc74 OK 2022/03/02 05:04:05 DEBUG : dst/two: md5 = 555ff090669b91806760fc25fadc2bed OK 2022/03/02 05:04:05 DEBUG : dst/three.txt: md5 = 45d67b9a90d85d250fd30fcd290b9622 OK 2022/03/02 05:04:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:04:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:06 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:04:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup.bin" 2022/03/02 05:04:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/backup" 2022/03/02 05:04:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:04:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:04:07 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:07 DEBUG : two: Unchanged skipping 2022/03/02 05:04:08 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/02 05:04:08 INFO : one: Deleted 2022/03/02 05:04:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:04:09 DEBUG : one: md5 = 6f80533d91e34b6daea6c2d32ea74ab3 OK 2022/03/02 05:04:09 INFO : one: Copied (new) 2022/03/02 05:04:09 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:10 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/02 05:04:10 INFO : three.txt: Deleted 2022/03/02 05:04:10 INFO : three.txt: Moved into backup dir 2022/03/02 05:04:11 DEBUG : dst/three.txt: md5 = 1dfb3aca28877f364d5db86871fc89d1 OK 2022/03/02 05:04:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:04:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:04:12 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:12 DEBUG : two: Unchanged skipping 2022/03/02 05:04:12 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/02 05:04:12 INFO : one: Deleted 2022/03/02 05:04:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:04:12 DEBUG : one: md5 = 55971437edd6e7b06a86fa1a5591aeb5 OK 2022/03/02 05:04:12 INFO : one: Copied (new) 2022/03/02 05:04:12 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:13 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/02 05:04:13 INFO : three.txt: Deleted 2022/03/02 05:04:13 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (11.29s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:16 DEBUG : dst/one: md5 = 74c2aecec5fc6df210ff7c9af1b3457d OK 2022/03/02 05:04:16 DEBUG : dst/two: md5 = 3ca677143e923e1a5403dfda2409fc6d OK 2022/03/02 05:04:16 DEBUG : dst/three.txt: md5 = ad0ec4ca50e031af5a65a3a2d7e922c3 OK 2022/03/02 05:04:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:04:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:04:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:04:18 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:18 DEBUG : two: Unchanged skipping 2022/03/02 05:04:18 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:04:18 INFO : one: Deleted 2022/03/02 05:04:18 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:04:19 DEBUG : one: md5 = 0aa622dd5dbb6f5b7586ceaef1fe93bd OK 2022/03/02 05:04:19 INFO : one: Copied (new) 2022/03/02 05:04:19 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:19 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:04:20 INFO : three.txt: Deleted 2022/03/02 05:04:20 INFO : three.txt: Moved into backup dir 2022/03/02 05:04:20 DEBUG : dst/three.txt: md5 = 576e2973671ee7c7544316954db9ebe4 OK 2022/03/02 05:04:21 DEBUG : one.bak: Excluded 2022/03/02 05:04:21 DEBUG : three.txt.bak: Excluded 2022/03/02 05:04:21 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:04:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for checks to finish 2022/03/02 05:04:21 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:21 DEBUG : two: Unchanged skipping 2022/03/02 05:04:21 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:04:22 INFO : one: Deleted 2022/03/02 05:04:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst': Waiting for transfers to finish 2022/03/02 05:04:22 DEBUG : one: md5 = c0210ae880707c5b0468f6ebba80fd71 OK 2022/03/02 05:04:22 INFO : one: Copied (new) 2022/03/02 05:04:22 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:22 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:04:22 INFO : three.txt: Deleted 2022/03/02 05:04:22 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (9.43s) === RUN TestSyncSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:25 DEBUG : dst/one: md5 = 81e26208e0d4949430283598172e93f0 OK 2022/03/02 05:04:26 DEBUG : dst/two: md5 = 282f15a1b408e0f45cfc6ae56b2f55d1 OK 2022/03/02 05:04:27 DEBUG : dst/three.txt: md5 = cee6790e5528518bb6e2f38abf6e51c5 OK 2022/03/02 05:04:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:04:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:28 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:04:28 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:04:28 INFO : one: Deleted 2022/03/02 05:04:28 DEBUG : one: md5 = 2f9d4157bbee8d46bb05dd04dad3c75d OK 2022/03/02 05:04:28 INFO : one: Copied (new) 2022/03/02 05:04:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:28 DEBUG : two: Unchanged skipping 2022/03/02 05:04:29 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/02 05:04:29 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:04:29 INFO : three.txt: Deleted 2022/03/02 05:04:29 DEBUG : three.txt: md5 = 157c249094b0d9979471992133583568 OK 2022/03/02 05:04:29 INFO : three.txt: Copied (new) 2022/03/02 05:04:30 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:04:31 INFO : one: Copied (server-side copy) to: one.bak 2022/03/02 05:04:31 INFO : one: Deleted 2022/03/02 05:04:32 DEBUG : one: md5 = 531ef8143e3492dd7903f4c7f38f7be0 OK 2022/03/02 05:04:32 INFO : one: Copied (new) 2022/03/02 05:04:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:32 DEBUG : two: Unchanged skipping 2022/03/02 05:04:32 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/03/02 05:04:32 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/03/02 05:04:32 INFO : three.txt: Deleted 2022/03/02 05:04:33 DEBUG : three.txt: md5 = 29389d40ff89ff38b8eae6cc0529d105 OK 2022/03/02 05:04:33 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (10.04s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:35 DEBUG : dst/one: md5 = fb6e3e39b27ceadbfdfbcce8f69182d6 OK 2022/03/02 05:04:35 DEBUG : dst/two: md5 = 2fb28f12d9b4dd7d91ca7ace8a791351 OK 2022/03/02 05:04:36 DEBUG : dst/three.txt: md5 = 05c506ae1f9220833759cb9e5538383b OK 2022/03/02 05:04:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst.bin" 2022/03/02 05:04:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-lovakos0kalajuf7qafalaz2/dst" 2022/03/02 05:04:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/03/02 05:04:37 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/02 05:04:38 INFO : one: Deleted 2022/03/02 05:04:38 DEBUG : one: md5 = 66a303ff83d364c3a763e191faa1da3c OK 2022/03/02 05:04:38 INFO : one: Copied (new) 2022/03/02 05:04:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:38 DEBUG : two: Unchanged skipping 2022/03/02 05:04:38 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/03/02 05:04:39 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/02 05:04:39 INFO : three.txt: Deleted 2022/03/02 05:04:40 DEBUG : three.txt: md5 = 63db951dc92d2f3ba8b4fdb0f3235184 OK 2022/03/02 05:04:40 INFO : three.txt: Copied (new) 2022/03/02 05:04:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/03/02 05:04:40 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/03/02 05:04:41 INFO : one: Deleted 2022/03/02 05:04:41 DEBUG : one: md5 = 5d3304f9683c84f9d4d14bfdab886600 OK 2022/03/02 05:04:41 INFO : one: Copied (new) 2022/03/02 05:04:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:41 DEBUG : two: Unchanged skipping 2022/03/02 05:04:41 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/03/02 05:04:42 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/03/02 05:04:42 INFO : three.txt: Deleted 2022/03/02 05:04:42 DEBUG : three.txt: md5 = f2ffabd49c86ebae020b7d70fe4e64c9 OK 2022/03/02 05:04:42 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.56s) === RUN TestSyncUTFNorm run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:45 DEBUG : Testêé: md5 = b993bd54b8e47725a2c7d5b86fc4a64e OK 2022/03/02 05:04:45 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/03/02 05:04:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:04:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:04:47 DEBUG : Testêé: md5 = f836d843b107fb89873bfb4922c9c8d1 OK 2022/03/02 05:04:47 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/03/02 05:04:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (3.73s) === RUN TestSyncImmutable run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:04:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:04:48 DEBUG : existing: md5 = 09d6bbc55ac2a43728a0ba174b59af49 OK 2022/03/02 05:04:48 INFO : existing: Copied (new) 2022/03/02 05:04:48 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:04:49 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/03/02 05:04:49 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/03/02 05:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:04:49 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': not deleting files as there were IO errors 2022/03/02 05:04:49 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.57s) === RUN TestSyncIgnoreCase run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:50 DEBUG : EXISTING: md5 = cd706a446f217f395a8794fe0b18ca3f OK 2022/03/02 05:04:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:04:51 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:04:51 DEBUG : existing: Unchanged skipping 2022/03/02 05:04:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:04:51 DEBUG : Waiting for deletions to finish 2022/03/02 05:04:51 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.77s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", 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-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", 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-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.72s) --- SKIP: TestMaxTransfer/Hard (0.28s) --- SKIP: TestMaxTransfer/Soft (0.08s) --- SKIP: TestMaxTransfer/Cautious (0.37s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:04:52 DEBUG : both0: md5 = 6b14baa724b99637eb3c8c8a176784d2 OK 2022/03/02 05:04:53 DEBUG : only0: md5 = 8d18d021a1b3e94c42d070e9624006bd OK 2022/03/02 05:04:53 DEBUG : both1: md5 = a0d1d9021e2fb954a2e391d121c775e9 OK 2022/03/02 05:04:54 DEBUG : only1: md5 = 4fba4fc50c6e47e3c852dad1ce3455bc OK 2022/03/02 05:04:54 DEBUG : both2: md5 = fffedb3037907a74336882ebc19fd818 OK 2022/03/02 05:04:55 DEBUG : only2: md5 = d1dd2236c5097dc3eb728842d90ce01b OK 2022/03/02 05:04:56 DEBUG : both3: md5 = 68eaae4a6f8071e362b1e7a23d059104 OK 2022/03/02 05:04:56 DEBUG : only3: md5 = b3dcd44ba79ebc799f9877991f6d1415 OK 2022/03/02 05:04:57 DEBUG : both4: md5 = 6608fa2cefb8b06624c880b41f825047 OK 2022/03/02 05:04:57 DEBUG : only4: md5 = ac479a6c1224bde52c2fa6496a870549 OK 2022/03/02 05:04:58 DEBUG : both5: md5 = 10b0017d50d5f2f14195e1d30bead3df OK 2022/03/02 05:04:59 DEBUG : only5: md5 = 87a27a2a38e2f24deec709e7180c2bb4 OK 2022/03/02 05:04:59 DEBUG : both6: md5 = 22ba8f5c8d57f30df2101b070c2d91fb OK 2022/03/02 05:05:00 DEBUG : only6: md5 = 79aed22199ba891bd2787c48c4dd0444 OK 2022/03/02 05:05:00 DEBUG : both7: md5 = 4a3a6925f332c407839130f1f49be906 OK 2022/03/02 05:05:01 DEBUG : only7: md5 = 3a58d8f361884da17bb58abe6903c89d OK 2022/03/02 05:05:02 DEBUG : both8: md5 = 620110e52dc216c849999442d4a2bbd0 OK 2022/03/02 05:05:02 DEBUG : only8: md5 = 19bbcb822763e8b7f71c124bfe149603 OK 2022/03/02 05:05:03 DEBUG : both9: md5 = 9f8540947425d52ec1627b99b06cd5c0 OK 2022/03/02 05:05:04 DEBUG : only9: md5 = 09f475f7ff193da119537ee23c059c5e OK 2022/03/02 05:05:04 DEBUG : both10: md5 = 6f61e74d74e8853f5b8516904a88d565 OK 2022/03/02 05:05:05 DEBUG : only10: md5 = 120e38be8f5447324dd4c956c762e61d OK 2022/03/02 05:05:05 DEBUG : both11: md5 = bc316b342d84a457ea53d438ecefb0b8 OK 2022/03/02 05:05:06 DEBUG : only11: md5 = 518fc928ac50b4b2616926641d05fa15 OK 2022/03/02 05:05:07 DEBUG : both12: md5 = 9dfbbc0daae76322f7006560fbc5b617 OK 2022/03/02 05:05:08 DEBUG : only12: md5 = 99eb66da430dd0057bd292f8d81ff5ef OK 2022/03/02 05:05:09 DEBUG : both13: md5 = 850faa7118408bccad0d43bcfc7f9476 OK 2022/03/02 05:05:10 DEBUG : only13: md5 = 427389063336651cf33225864620b128 OK 2022/03/02 05:05:10 DEBUG : both14: md5 = 833594a4a7258628d00ffc94d07ff247 OK 2022/03/02 05:05:11 DEBUG : only14: md5 = 3245ff5845f37a1217294582c83b024f OK 2022/03/02 05:05:12 DEBUG : both15: md5 = 63e2323f35781d666dc56e6c5449a339 OK 2022/03/02 05:05:12 DEBUG : only15: md5 = bc083e82b456f7be2d28929a5e2eae28 OK 2022/03/02 05:05:13 DEBUG : both16: md5 = b079022707b6014517da2efc57bda2e7 OK 2022/03/02 05:05:14 DEBUG : only16: md5 = 3dd9d37b5e8823541ca0cf8b2fdb9e02 OK 2022/03/02 05:05:15 DEBUG : both17: md5 = 47dff9bc6b9dc21d699e7aed3c07a076 OK 2022/03/02 05:05:15 DEBUG : only17: md5 = 737e10f41ec757f4c79a6776fcd503ae OK 2022/03/02 05:05:17 DEBUG : both18: md5 = b8c8f80e809e6c0d039fa77c1375519a OK 2022/03/02 05:05:17 DEBUG : only18: md5 = 142343587382f43dbbe46e18caba5eb8 OK 2022/03/02 05:05:18 DEBUG : both19: md5 = 4cf621d367118c9069f3e5b89f7fe130 OK 2022/03/02 05:05:18 DEBUG : only19: md5 = 5aed74c049ba69458c4658406137758f OK 2022/03/02 05:05:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:05:20 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both0: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both1: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both10: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both11: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both12: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both13: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both14: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both15: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both16: Unchanged skipping 2022/03/02 05:05:20 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:20 DEBUG : both17: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both18: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both19: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both2: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both3: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both4: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both5: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both6: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both7: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both8: Unchanged skipping 2022/03/02 05:05:21 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:21 DEBUG : both9: Unchanged skipping 2022/03/02 05:05:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:05:21 DEBUG : Waiting for deletions to finish 2022/03/02 05:05:21 INFO : only16: Deleted 2022/03/02 05:05:21 INFO : only2: Deleted 2022/03/02 05:05:21 INFO : only8: Deleted 2022/03/02 05:05:21 INFO : only15: Deleted 2022/03/02 05:05:21 INFO : only19: Deleted 2022/03/02 05:05:21 INFO : only11: Deleted 2022/03/02 05:05:21 INFO : only14: Deleted 2022/03/02 05:05:22 INFO : only7: Deleted 2022/03/02 05:05:22 INFO : only4: Deleted 2022/03/02 05:05:22 INFO : only17: Deleted 2022/03/02 05:05:22 INFO : only12: Deleted 2022/03/02 05:05:22 INFO : only18: Deleted 2022/03/02 05:05:22 INFO : only9: Deleted 2022/03/02 05:05:22 INFO : only1: Deleted 2022/03/02 05:05:22 INFO : only10: Deleted 2022/03/02 05:05:22 INFO : only3: Deleted 2022/03/02 05:05:22 INFO : only5: Deleted 2022/03/02 05:05:22 INFO : only6: Deleted 2022/03/02 05:05:23 INFO : only0: Deleted 2022/03/02 05:05:23 INFO : only13: Deleted 2022/03/02 05:05:23 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (36.91s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2'", Local "Local file system at /tmp/rclone1696422540", Modify Window "1ns" 2022/03/02 05:05:30 DEBUG : both0: md5 = 4ff6e0191bd419f3935abe3e3eae48ae OK 2022/03/02 05:05:30 DEBUG : only0: md5 = 419617581691566eb1e6d6a6fbd1a3ba OK 2022/03/02 05:05:30 DEBUG : both1: md5 = ae7e08964126e2030d4dae95b092f00c OK 2022/03/02 05:05:31 DEBUG : only1: md5 = edf549424779fb6a13a5221e5be0673d OK 2022/03/02 05:05:32 DEBUG : both2: md5 = 3bf1c6284af4d4c4846999a4654cbec6 OK 2022/03/02 05:05:33 DEBUG : only2: md5 = 3cef357dff39ab5dd1524a63a18fa829 OK 2022/03/02 05:05:33 DEBUG : both3: md5 = 756ff5456a72e303a17053dad6dd798d OK 2022/03/02 05:05:34 DEBUG : only3: md5 = 45bf77b4c89028fd03f846abf1b161f3 OK 2022/03/02 05:05:34 DEBUG : both4: md5 = 59cac8b781fde53fed04c48d66492cb7 OK 2022/03/02 05:05:35 DEBUG : only4: md5 = b295dcb2b9b1dc159e773980310ed4c6 OK 2022/03/02 05:05:35 DEBUG : both5: md5 = 3375d4eff1878bfc961f0a8857770587 OK 2022/03/02 05:05:36 DEBUG : only5: md5 = 6d2a08dffc8b0798ec132fb679551d7e OK 2022/03/02 05:05:36 DEBUG : both6: md5 = efadb28d7760c341d6a56326dd90a44d OK 2022/03/02 05:05:36 DEBUG : only6: md5 = cbee1b767fcb03a0f0a34371afd39248 OK 2022/03/02 05:05:37 DEBUG : both7: md5 = f057e006d489d561a6be8a1e54067597 OK 2022/03/02 05:05:37 DEBUG : only7: md5 = 28eef169aa3a3d0e75bf90660b558ef4 OK 2022/03/02 05:05:38 DEBUG : both8: md5 = dbae44885614895f8c06d6120fbab99f OK 2022/03/02 05:05:38 DEBUG : only8: md5 = e3cf3feee49741fef2e7a64cd89e2d77 OK 2022/03/02 05:05:39 DEBUG : both9: md5 = 29c1318b47cc9626ea2d04ed83154520 OK 2022/03/02 05:05:39 DEBUG : only9: md5 = fbd5ef5520152f70df122fed91e7b0ee OK 2022/03/02 05:05:39 DEBUG : both10: md5 = 7817c7f0ea7086bfe44ec0fe79c9df66 OK 2022/03/02 05:05:40 DEBUG : only10: md5 = 193048681714b5020d783c6f0837b81d OK 2022/03/02 05:05:40 DEBUG : both11: md5 = b5adc8196f94af6fed02c27d38420135 OK 2022/03/02 05:05:40 DEBUG : only11: md5 = 62e53b6bebd587c1caafe0c3f37908db OK 2022/03/02 05:05:41 DEBUG : both12: md5 = 372db02b458da6c5c53cc23942947667 OK 2022/03/02 05:05:41 DEBUG : only12: md5 = 1ff58dda1f50fd5bc066b7d57b857aae OK 2022/03/02 05:05:42 DEBUG : both13: md5 = a9b96d97b58a20304c2822323fdd9f99 OK 2022/03/02 05:05:42 DEBUG : only13: md5 = d5c3249bec5703b5e4b4bc204634e096 OK 2022/03/02 05:05:43 DEBUG : both14: md5 = 2ab248a7234a9f8c6768914b323db7a2 OK 2022/03/02 05:05:43 DEBUG : only14: md5 = b3d8baf021b571c84d983e1a9baba6d6 OK 2022/03/02 05:05:44 DEBUG : both15: md5 = c307381357fb5332fcc9521a1b5fee73 OK 2022/03/02 05:05:44 DEBUG : only15: md5 = 5791865c47f1bc0a74262d11d9e34c83 OK 2022/03/02 05:05:45 DEBUG : both16: md5 = fd189dacdce3840bb28969ca583e88a1 OK 2022/03/02 05:05:46 DEBUG : only16: md5 = 018594beec6bc7f416ef54b91b501de3 OK 2022/03/02 05:05:46 DEBUG : both17: md5 = 90085d6b415b20f76ba85f57b0c35923 OK 2022/03/02 05:05:47 DEBUG : only17: md5 = 684d016648d6ec2429d307ab102a3b10 OK 2022/03/02 05:05:47 DEBUG : both18: md5 = 8a6c15749b9f73e97c7042d52b209cae OK 2022/03/02 05:05:48 DEBUG : only18: md5 = 68007f119da4fbbaec39a2762ee99b7f OK 2022/03/02 05:05:49 DEBUG : both19: md5 = 32fb388a58f1f074eb161d323c01b7ca OK 2022/03/02 05:05:49 DEBUG : only19: md5 = 817ab97ad4a89ea92e3881889568c344 OK 2022/03/02 05:05:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for checks to finish 2022/03/02 05:05:51 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both0: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both10: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both11: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both1: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both13: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both12: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both14: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both15: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both16: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both17: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both18: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both19: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both2: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both3: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both4: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both5: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both6: Unchanged skipping 2022/03/02 05:05:51 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/03/02 05:05:51 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both7: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both8: Unchanged skipping 2022/03/02 05:05:51 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/03/02 05:05:51 DEBUG : both9: Unchanged skipping 2022/03/02 05:05:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Waiting for transfers to finish 2022/03/02 05:05:51 DEBUG : only0: md5 = 3b0a6952d6684b66d77d921a793f2003 OK 2022/03/02 05:05:51 INFO : only0: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only1: md5 = 8798f686e7ae7717a236d141658cdf99 OK 2022/03/02 05:05:52 INFO : only1: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only11: md5 = a5f0e3941ec1af55406ace7be1775214 OK 2022/03/02 05:05:52 INFO : only11: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only12: md5 = f8b87d1a4d3a9e486154efd377a02667 OK 2022/03/02 05:05:52 INFO : only12: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only13: md5 = 4f97210b073df71c4e55cfe742f5ff0e OK 2022/03/02 05:05:52 INFO : only13: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only14: md5 = 69461c6bfedd8cad68dc75b3993d632c OK 2022/03/02 05:05:52 INFO : only14: Copied (replaced existing) 2022/03/02 05:05:52 DEBUG : only10: md5 = 6dc1f214b0c0f10e159a066738507288 OK 2022/03/02 05:05:52 INFO : only10: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only15: md5 = b091db417b0f7f4a22a74471115f9101 OK 2022/03/02 05:05:53 INFO : only15: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only18: md5 = 52be905d50d742e5b955e6260341fc41 OK 2022/03/02 05:05:53 INFO : only18: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only16: md5 = 23e4401e3c72b1db2b7512f285b7ce6d OK 2022/03/02 05:05:53 INFO : only16: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only19: md5 = a924df4db051c8fc7ee48bb6c11d99f8 OK 2022/03/02 05:05:53 INFO : only19: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only17: md5 = 3b33f63bf26d828dc6eddbac8899e41e OK 2022/03/02 05:05:53 INFO : only17: Copied (replaced existing) 2022/03/02 05:05:53 DEBUG : only3: md5 = e5f0463a013e5158049c4212bb6caa63 OK 2022/03/02 05:05:53 INFO : only3: Copied (replaced existing) 2022/03/02 05:05:54 DEBUG : only2: md5 = bca561988064fa6db3188ae61b55be1d OK 2022/03/02 05:05:54 INFO : only2: Copied (replaced existing) 2022/03/02 05:05:54 DEBUG : only4: md5 = 677d074307918f080f289e0b91b50754 OK 2022/03/02 05:05:54 INFO : only4: Copied (replaced existing) 2022/03/02 05:05:54 DEBUG : only6: md5 = 5a165c14f935dcec28ee6bb322510229 OK 2022/03/02 05:05:54 INFO : only6: Copied (replaced existing) 2022/03/02 05:05:54 DEBUG : only8: md5 = 3b643881bf30697191c1025c21dc9aa7 OK 2022/03/02 05:05:54 INFO : only8: Copied (replaced existing) 2022/03/02 05:05:55 DEBUG : only5: md5 = 18704d9e6ddb0ea43951dcf8b5d12aa0 OK 2022/03/02 05:05:55 INFO : only5: Copied (replaced existing) 2022/03/02 05:05:55 DEBUG : only7: md5 = b13bdcb2f0ee5482e787b80503b52ca2 OK 2022/03/02 05:05:55 INFO : only7: Copied (replaced existing) 2022/03/02 05:05:55 DEBUG : only9: md5 = 040e60f675657e6b35a93397593f5ee2 OK 2022/03/02 05:05:55 INFO : only9: Copied (replaced existing) 2022/03/02 05:05:55 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (34.78s) PASS 2022/03/02 05:06:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-lovakos0kalajuf7qafalaz2': Purge remote 2022/03/02 05:06:04 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 5m22.653981246s (try 1/5)