"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2022/04/09 06:32:25 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1" 2022/04/09 06:32:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/04/09 06:32:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1.bin" 2022/04/09 06:32:25 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1" 2022/04/09 06:32:26 DEBUG : Creating backend with remote "/tmp/rclone1425601831" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.02s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:26 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/04/09 06:32:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.40s) === RUN TestCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:27 DEBUG : sub dir/hello world: md5 = 5a69683fad95a052055abc59b9b56523 OK 2022/04/09 06:32:27 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (1.10s) === RUN TestCopyMissingDirectory run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:27 DEBUG : Creating backend with remote "/non-existing" 2022/04/09 06:32:27 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/04/09 06:32:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.21s) === RUN TestCopyNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:28 DEBUG : sub dir/hello world: md5 = dc030f10522a5ab0c963217d630927ea OK 2022/04/09 06:32:28 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (0.83s) === RUN TestCopyCheckFirst run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:28 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Running all checks before starting transfers 2022/04/09 06:32:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:28 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Checks finished, now starting transfers 2022/04/09 06:32:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:29 DEBUG : sub dir/hello world: md5 = 30af48f3416ea9d4e258d13b0cf373a2 OK 2022/04/09 06:32:29 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.45s) === RUN TestSyncNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:30 ERROR : Ignoring --no-traverse with sync 2022/04/09 06:32:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:30 DEBUG : sub dir/hello world: md5 = eeb9173885fd69f5558e0a1eab350804 OK 2022/04/09 06:32:30 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:32:30 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (0.95s) === RUN TestCopyWithDepth run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:31 DEBUG : hello world2: md5 = 46daadccaf4fcb4a19a3be3c67dea4ef OK 2022/04/09 06:32:31 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.06s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:32 DEBUG : hello world2: Excluded 2022/04/09 06:32:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:33 DEBUG : potato2: md5 = 88f3ab92bc2ef004d99bfd7b869e6204 OK 2022/04/09 06:32:33 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (1.66s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:34 DEBUG : potato2: md5 = 585e5e6b576137c380f9924c259e9787 OK 2022/04/09 06:32:34 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (0.83s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:34 DEBUG : sub dir2: Making directory 2022/04/09 06:32:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:35 DEBUG : sub dir/hello world: md5 = a29269b9ea699d9080c72dab183c2481 OK 2022/04/09 06:32:35 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:32:35 DEBUG : sub dir2: Making directory 2022/04/09 06:32:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (0.80s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:35 DEBUG : sub dir2: Making directory 2022/04/09 06:32:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:35 DEBUG : sub dir/hello world: md5 = 9b56f7a317a37d89f6bf260e62ba4649 OK 2022/04/09 06:32:35 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:32:35 INFO : sub dir/hello world: Deleted 2022/04/09 06:32:35 DEBUG : sub dir: Making directory 2022/04/09 06:32:35 DEBUG : sub dir2: Making directory 2022/04/09 06:32:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (0.79s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:36 DEBUG : sub dir2: Making directory 2022/04/09 06:32:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:36 DEBUG : sub dir/hello world: md5 = 46c7639196192ce056a451462196fe8b OK 2022/04/09 06:32:36 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:32:36 DEBUG : sub dir2: Making directory 2022/04/09 06:32:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': copied 1 directories 2022/04/09 06:32:36 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (0.96s) === RUN TestServerSideCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:37 DEBUG : sub dir/hello world: md5 = ed310ed3c0ae4d0e123d8ef876ba410c OK 2022/04/09 06:32:37 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-boxasoy0nuzeyuk7quyajuk8" 2022/04/09 06:32:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-boxasoy0nuzeyuk7quyajuk8.bin" 2022/04/09 06:32:38 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-boxasoy0nuzeyuk7quyajuk8" sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1' -> Encrypted drive 'TestCryptSwift:rclone-test-boxasoy0nuzeyuk7quyajuk8' 2022/04/09 06:32:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-boxasoy0nuzeyuk7quyajuk8': Waiting for checks to finish 2022/04/09 06:32:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-boxasoy0nuzeyuk7quyajuk8': Waiting for transfers to finish 2022/04/09 06:32:39 INFO : sub dir/hello world: Copied (server-side copy) 2022/04/09 06:32:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-boxasoy0nuzeyuk7quyajuk8': Purge remote 2022/04/09 06:32:39 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:39 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (2.96s) === RUN TestCopyAfterDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:40 DEBUG : sub dir/hello world: md5 = a55a94ca4afc9fdd7dbd0ea05b442c9f OK 2022/04/09 06:32:40 ERROR : : error listing: directory not found 2022/04/09 06:32:40 DEBUG : Local file system at /tmp/rclone1425601831: Making directory 2022/04/09 06:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.09s) === RUN TestCopyRedownload run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:41 DEBUG : sub dir/hello world: md5 = 8214199b68b57ca01fdf02f649eaddd4 OK 2022/04/09 06:32:41 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for checks to finish 2022/04/09 06:32:41 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for transfers to finish 2022/04/09 06:32:41 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (0.95s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:42 DEBUG : check sum: md5 = fe80672ca95bc24b0fa0f576b675744d OK 2022/04/09 06:32:42 INFO : check sum: Copied (new) 2022/04/09 06:32:42 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:43 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/04/09 06:32:43 DEBUG : check sum: Size of src and dst objects identical 2022/04/09 06:32:43 DEBUG : check sum: Unchanged skipping 2022/04/09 06:32:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:43 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:43 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.16s) === RUN TestSyncSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:44 DEBUG : sizeonly: md5 = c9a249299e9564f7e45b112dacceefce OK 2022/04/09 06:32:44 INFO : sizeonly: Copied (new) 2022/04/09 06:32:44 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:44 DEBUG : sizeonly: Sizes identical 2022/04/09 06:32:44 DEBUG : sizeonly: Unchanged skipping 2022/04/09 06:32:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:44 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:44 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (1.52s) === RUN TestSyncIgnoreSize run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:45 DEBUG : ignore-size: md5 = df110747145b0b7dcb075e3b153f2343 OK 2022/04/09 06:32:45 INFO : ignore-size: Copied (new) 2022/04/09 06:32:45 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:45 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:32:45 DEBUG : ignore-size: Unchanged skipping 2022/04/09 06:32:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:45 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:45 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.18s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:46 DEBUG : existing: md5 = acfb359f6b6562d4570c44f0f2407bbf OK 2022/04/09 06:32:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:46 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:32:46 DEBUG : existing: Unchanged skipping 2022/04/09 06:32:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:46 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:46 INFO : There was nothing to transfer 2022/04/09 06:32:46 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/04/09 06:32:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:47 DEBUG : existing: md5 = 9da73874ae2ef9b05c7f39f03d052d00 OK 2022/04/09 06:32:47 INFO : existing: Copied (replaced existing) 2022/04/09 06:32:47 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (1.59s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:48 DEBUG : existing: md5 = acffe73d9b1332c88cd94996fc3700ff OK 2022/04/09 06:32:48 INFO : existing: Copied (new) 2022/04/09 06:32:48 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:48 DEBUG : existing: Destination exists, skipping 2022/04/09 06:32:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:48 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:48 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.82s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:48 DEBUG : b/potato: md5 = f843c0120708a16763ba7fd5c5e386c4 OK 2022/04/09 06:32:49 DEBUG : c/non empty space: md5 = f5f25c03fb792df61c08c3f8e93e1913 OK 2022/04/09 06:32:49 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/04/09 06:32:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:49 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:32:49 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 06:32:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:49 DEBUG : a/potato2: md5 = a944c7596d2a6dd36426182b2de633f0 OK 2022/04/09 06:32:49 INFO : a/potato2: Copied (new) 2022/04/09 06:32:49 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:50 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (2.36s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:51 DEBUG : empty space: md5 = 6dd302ae5b84f2924fb35bc8597c5606 OK 2022/04/09 06:32:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:51 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/04/09 06:32:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:51 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2022/04/09 06:32:51 DEBUG : Waiting for deletions to finish 2022/04/09 06:32:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:51 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/04/09 06:32:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:52 DEBUG : empty space: md5 = 6ebc1a510d945c7625847a38348979b3 OK 2022/04/09 06:32:52 INFO : empty space: Copied (replaced existing) 2022/04/09 06:32:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (1.74s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.26s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:53 DEBUG : foo: md5 = 8b892de02f7121e6254666df08ee08f2 OK 2022/04/09 06:32:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:54 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/04/09 06:32:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:54 DEBUG : foo: md5 = 7cda21a3b23102f6abfc90d9af9c492a OK 2022/04/09 06:32:54 INFO : foo: Copied (replaced existing) 2022/04/09 06:32:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (2.68s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:55 DEBUG : empty space: md5 = 9a72470c397ae3fa08352b6da423bdcb OK 2022/04/09 06:32:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:32:56 DEBUG : empty space: Unchanged skipping 2022/04/09 06:32:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:56 DEBUG : potato: md5 = d6e3e8a437650f01c7152f954a42d8b2 OK 2022/04/09 06:32:56 INFO : potato: Copied (new) 2022/04/09 06:32:56 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (1.89s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:58 DEBUG : potato: md5 = aea297b86baf4e1a2c7fe59641aab976 OK 2022/04/09 06:32:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:32:58 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/04/09 06:32:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:32:58 DEBUG : potato: md5 = 3ebea4a97cb0ba7ec33fbaa45a89ed33 OK 2022/04/09 06:32:58 INFO : potato: Copied (replaced existing) 2022/04/09 06:32:58 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.86s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:32:59 DEBUG : potato: md5 = bad10609bb6903a08b50f2091f80fc4d OK 2022/04/09 06:33:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:00 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/04/09 06:33:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:00 DEBUG : potato: md5 = cdbf3a3f36a0339cf39b2da80f292f72 OK 2022/04/09 06:33:00 INFO : potato: Copied (replaced existing) 2022/04/09 06:33:00 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.95s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:01 DEBUG : potato: md5 = 38256b374e9a0a164ebba82ea6bfb066 OK 2022/04/09 06:33:01 DEBUG : empty space: md5 = e7ce67ec7c409a8ce400ce72fc80daa6 OK 2022/04/09 06:33:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:01 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/04/09 06:33:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:02 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:02 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.44s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:03 DEBUG : potato: md5 = 619c8321364a1f0ee02057c951b81114 OK 2022/04/09 06:33:03 DEBUG : empty space: md5 = 7a4e2bf729f4eed677021def582835cb OK 2022/04/09 06:33:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:03 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:03 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:04 DEBUG : potato2: md5 = 441373ba8abbed6969e996610428cb1e OK 2022/04/09 06:33:04 INFO : potato2: Copied (new) 2022/04/09 06:33:04 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:04 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.22s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:05 DEBUG : b/potato: md5 = e2a0900e8a5aea58cc8bd0727eed07be OK 2022/04/09 06:33:05 DEBUG : c/non empty space: md5 = aabb626ece30b3979666a8885ee95d74 OK 2022/04/09 06:33:05 DEBUG : d: Making directory 2022/04/09 06:33:05 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/04/09 06:33:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:05 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:05 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 06:33:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:05 DEBUG : a/potato2: md5 = 302da23fdfdb40dd7190ce5e70313289 OK 2022/04/09 06:33:05 INFO : a/potato2: Copied (new) 2022/04/09 06:33:05 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:06 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (1.87s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:07 DEBUG : b/potato: md5 = 0c5d5e48d256ebf8d54d607e75578b09 OK 2022/04/09 06:33:07 DEBUG : c/non empty space: md5 = fd5175966da7887c0d1667c4d24df0b2 OK 2022/04/09 06:33:07 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/04/09 06:33:08 DEBUG : a/potato2: md5 = 6c56cec2677bd99869abdd04f39ee463 OK 2022/04/09 06:33:08 INFO : a/potato2: Copied (new) 2022/04/09 06:33:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:08 DEBUG : c/non empty space: Unchanged skipping 2022/04/09 06:33:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': not deleting files as there were IO errors 2022/04/09 06:33:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.70s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:09 DEBUG : potato: md5 = 4bac05e2a8958095fedc3132fe9c9d72 OK 2022/04/09 06:33:10 DEBUG : empty space: md5 = d00f577705178a9990bb355dbaaf0899 OK 2022/04/09 06:33:10 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:10 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:10 INFO : potato: Deleted 2022/04/09 06:33:10 DEBUG : potato2: md5 = 9cb4a380b041995ac9ce9306fe0be301 OK 2022/04/09 06:33:10 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteDuring (2.15s) === RUN TestSyncDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:11 DEBUG : potato: md5 = 13a85750891ccf952177a04d2269c5c4 OK 2022/04/09 06:33:12 DEBUG : empty space: md5 = 92e03bb28df56d2a3e2be040a73cc443 OK 2022/04/09 06:33:12 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:13 INFO : potato: Deleted 2022/04/09 06:33:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:13 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:13 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:13 DEBUG : potato2: md5 = 0916ef2018948d49164ecd75b0ace74f OK 2022/04/09 06:33:13 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.09s) === RUN TestCopyDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:15 DEBUG : potato: md5 = 2e8edae3653fefcdc4141e7b2013c244 OK 2022/04/09 06:33:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:15 DEBUG : potato2: md5 = c4fdf2ddb0d7a328410b8b3c1dbb8659 OK 2022/04/09 06:33:15 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (1.70s) === RUN TestSyncWithExclude run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:16 DEBUG : potato2: md5 = 6d0377047a9878f80648f3a30d9c2ad1 OK 2022/04/09 06:33:16 DEBUG : empty space: md5 = 613f9d0c81451de20dd8e5b88bcf8793 OK 2022/04/09 06:33:17 DEBUG : enormous: Excluded 2022/04/09 06:33:17 DEBUG : potato2: Excluded 2022/04/09 06:33:17 DEBUG : potato2: Excluded 2022/04/09 06:33:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:17 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:17 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:17 INFO : There was nothing to transfer 2022/04/09 06:33:17 DEBUG : enormous: Excluded 2022/04/09 06:33:17 DEBUG : potato2: Excluded 2022/04/09 06:33:17 DEBUG : potato2: Excluded 2022/04/09 06:33:17 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for checks to finish 2022/04/09 06:33:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:17 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:17 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for transfers to finish 2022/04/09 06:33:17 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:17 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (1.47s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:18 DEBUG : potato2: md5 = bc9ffc3fb32dd063a5284008f482f7af OK 2022/04/09 06:33:18 DEBUG : empty space: md5 = 23062b484ba5b11db1d1202b48d4872d OK 2022/04/09 06:33:18 DEBUG : enormous: md5 = 0f53ffbfc8dc32b6f0d2b1f39a87b3b5 OK 2022/04/09 06:33:18 DEBUG : enormous: Excluded 2022/04/09 06:33:18 DEBUG : potato2: Excluded 2022/04/09 06:33:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:19 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:19 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:19 INFO : potato2: Deleted 2022/04/09 06:33:19 INFO : enormous: Deleted 2022/04/09 06:33:19 INFO : There was nothing to transfer 2022/04/09 06:33:19 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for checks to finish 2022/04/09 06:33:19 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:19 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:19 DEBUG : Local file system at /tmp/rclone1425601831: Waiting for transfers to finish 2022/04/09 06:33:19 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:19 INFO : enormous: Deleted 2022/04/09 06:33:19 INFO : potato2: Deleted 2022/04/09 06:33:19 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.14s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:20 DEBUG : one: md5 = ad346b8c46998a43bf53114b598df884 OK 2022/04/09 06:33:20 DEBUG : two: md5 = 62001cd0f614077aec799b713eb6364d OK 2022/04/09 06:33:21 DEBUG : three: md5 = 361afd9cf6475c9abe4ac32814641b5f OK 2022/04/09 06:33:21 DEBUG : four: md5 = 2838fa327950f5cb93f307e5889263b3 OK 2022/04/09 06:33:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:22 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/04/09 06:33:22 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/04/09 06:33:22 DEBUG : one: Destination is newer than source, skipping 2022/04/09 06:33:22 DEBUG : three: Sizes identical 2022/04/09 06:33:22 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/04/09 06:33:22 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:22 DEBUG : four: md5 = 5e75374dc9fd3ab96114f761707419e4 OK 2022/04/09 06:33:22 INFO : four: Copied (replaced existing) 2022/04/09 06:33:22 DEBUG : five: md5 = c97912f46fd8725c3980688a6c0a6ebf OK 2022/04/09 06:33:22 INFO : five: Copied (new) 2022/04/09 06:33:22 DEBUG : two: md5 = 4d53e4f5fbd9b24226107dac6ceb5c4a OK 2022/04/09 06:33:22 INFO : two: Copied (replaced existing) 2022/04/09 06:33:22 DEBUG : Waiting for deletions to finish sync_test.go:992: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (4.08s) === 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-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" sync_test.go:1058: Can track renames: false 2022/04/09 06:33:24 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Ignoring --track-renames as the source and destination do not have a common hash 2022/04/09 06:33:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:24 DEBUG : potato: md5 = c27abff7a38bc1c5428cf119ba22b7fe OK 2022/04/09 06:33:24 INFO : potato: Copied (new) 2022/04/09 06:33:24 DEBUG : yam: md5 = f5e26af6adef1882e23348957de82dde OK 2022/04/09 06:33:24 INFO : yam: Copied (new) 2022/04/09 06:33:24 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:25 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Ignoring --track-renames as the source and destination do not have a common hash 2022/04/09 06:33:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:25 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:25 DEBUG : potato: Unchanged skipping 2022/04/09 06:33:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:25 DEBUG : yaml: md5 = f214add022d205249128eb73618e0a40 OK 2022/04/09 06:33:25 INFO : yaml: Copied (new) 2022/04/09 06:33:25 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:25 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (2.33s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/04/09 06:33:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Making map for --track-renames 2022/04/09 06:33:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Finished making map for --track-renames 2022/04/09 06:33:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for renames to finish 2022/04/09 06:33:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:26 DEBUG : yam: md5 = 440034feb18a242df50fdd6315db803f OK 2022/04/09 06:33:26 INFO : yam: Copied (new) 2022/04/09 06:33:26 DEBUG : potato: md5 = d39a7ea52501f51dfc4083004c1e3d54 OK 2022/04/09 06:33:26 INFO : potato: Copied (new) 2022/04/09 06:33:26 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Making map for --track-renames 2022/04/09 06:33:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Finished making map for --track-renames 2022/04/09 06:33:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:27 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:27 DEBUG : potato: Unchanged skipping 2022/04/09 06:33:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for renames to finish 2022/04/09 06:33:27 INFO : yam: Copied (server-side copy) to: yaml 2022/04/09 06:33:29 INFO : yam: Deleted 2022/04/09 06:33:29 INFO : yaml: Renamed from "yam" 2022/04/09 06:33:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:29 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (3.44s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/04/09 06:33:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Making map for --track-renames 2022/04/09 06:33:29 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Finished making map for --track-renames 2022/04/09 06:33:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for renames to finish 2022/04/09 06:33:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:30 DEBUG : potato: md5 = 6f9d16fed63a101eee8d74e18c48e0e2 OK 2022/04/09 06:33:30 INFO : potato: Copied (new) 2022/04/09 06:33:30 DEBUG : sub/yam: md5 = 694d3bcd3067ff2d56d870b7e1dd64ae OK 2022/04/09 06:33:30 INFO : sub/yam: Copied (new) 2022/04/09 06:33:30 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:31 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:31 DEBUG : potato: Unchanged skipping 2022/04/09 06:33:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Making map for --track-renames 2022/04/09 06:33:31 INFO : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Finished making map for --track-renames 2022/04/09 06:33:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for renames to finish 2022/04/09 06:33:31 INFO : sub/yam: Copied (server-side copy) to: yam 2022/04/09 06:33:31 INFO : sub/yam: Deleted 2022/04/09 06:33:31 INFO : yam: Renamed from "sub/yam" 2022/04/09 06:33:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:31 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.50s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:32 DEBUG : nested/sub dir/file: md5 = 403347c96ae11a061e9ece58efb1e2d9 OK 2022/04/09 06:33:32 INFO : nested/sub dir/file: Copied (new) 2022/04/09 06:33:32 INFO : nested/sub dir/file: Deleted 2022/04/09 06:33:32 DEBUG : sub dir/hello world: md5 = 150a7491e9d95c0b2d879774ca1be0cf OK 2022/04/09 06:33:32 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:33:32 INFO : sub dir/hello world: Deleted 2022/04/09 06:33:32 INFO : sub dir: Removing directory 2022/04/09 06:33:32 INFO : nested/sub dir: Removing directory 2022/04/09 06:33:32 INFO : nested: Removing directory 2022/04/09 06:33:32 DEBUG : Local file system at /tmp/rclone1425601831: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (0.95s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:33 DEBUG : sub dir/hello world: md5 = 7e0a99c7f3c3eb1978a3657495ca32b5 OK 2022/04/09 06:33:33 INFO : sub dir/hello world: Copied (new) 2022/04/09 06:33:33 INFO : sub dir/hello world: Deleted 2022/04/09 06:33:33 DEBUG : nested/sub dir/file: md5 = 79c619a8cb6b25768740b6e00b065e19 OK 2022/04/09 06:33:33 INFO : nested/sub dir/file: Copied (new) 2022/04/09 06:33:33 INFO : nested/sub dir/file: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.00s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:34 DEBUG : existing: md5 = 4ff881f74e572248515c956a9df2a58e OK 2022/04/09 06:33:34 INFO : existing: Copied (new) 2022/04/09 06:33:34 INFO : existing: Deleted 2022/04/09 06:33:34 DEBUG : existing-b: md5 = 5a88331eb366ee2efeb2a1b54fbf6db6 OK 2022/04/09 06:33:34 INFO : existing-b: Copied (new) 2022/04/09 06:33:34 INFO : existing-b: Deleted 2022/04/09 06:33:35 DEBUG : existing: Destination exists, skipping 2022/04/09 06:33:35 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/04/09 06:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:33:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:33:35 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (1.29s) === RUN TestServerSideMove run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-ruqagow5mezihim0vikinin8" 2022/04/09 06:33:35 DEBUG : Config file has changed externaly - reloading 2022/04/09 06:33:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ruqagow5mezihim0vikinin8.bin" 2022/04/09 06:33:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-ruqagow5mezihim0vikinin8" 2022/04/09 06:33:37 DEBUG : potato2: md5 = 05f7745d6b4dbd52246960ae8b0529df OK 2022/04/09 06:33:37 DEBUG : empty space: md5 = 3dcb2b3f6f6e3f56f1b3a7d7f027461d OK 2022/04/09 06:33:38 DEBUG : potato3: md5 = 52c398d91a584060e4709c921faa3372 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1' -> Encrypted drive 'TestCryptSwift:rclone-test-ruqagow5mezihim0vikinin8' 2022/04/09 06:33:39 DEBUG : empty space: md5 = e786814984778472f40c5ab386a26ad8 OK 2022/04/09 06:33:39 DEBUG : potato3: md5 = a3797b860cf0d0f9ea4e26d922b15247 OK 2022/04/09 06:33:39 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 06:33:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ruqagow5mezihim0vikinin8': Waiting for checks to finish 2022/04/09 06:33:40 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:40 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:40 INFO : empty space: Deleted 2022/04/09 06:33:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ruqagow5mezihim0vikinin8': Waiting for transfers to finish 2022/04/09 06:33:40 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:40 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:40 INFO : potato2: Deleted 2022/04/09 06:33:40 INFO : potato3: Deleted 2022/04/09 06:33:40 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-dirativ3gisopek8hipoqus9" 2022/04/09 06:33:40 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dirativ3gisopek8hipoqus9.bin" 2022/04/09 06:33:41 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-dirativ3gisopek8hipoqus9" 2022/04/09 06:33:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dirativ3gisopek8hipoqus9': Waiting for checks to finish 2022/04/09 06:33:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dirativ3gisopek8hipoqus9': Waiting for transfers to finish 2022/04/09 06:33:42 INFO : empty space: Copied (server-side copy) 2022/04/09 06:33:42 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:42 INFO : empty space: Deleted 2022/04/09 06:33:42 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:42 INFO : potato2: Deleted 2022/04/09 06:33:42 INFO : potato3: Deleted 2022/04/09 06:33:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-dirativ3gisopek8hipoqus9': Purge remote 2022/04/09 06:33:43 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:43 INFO : empty space.bin: Deleted 2022/04/09 06:33:43 INFO : potato3.bin: Deleted 2022/04/09 06:33:43 INFO : potato2.bin: Deleted 2022/04/09 06:33:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-ruqagow5mezihim0vikinin8': Purge remote 2022/04/09 06:33:43 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (8.60s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-sopavid4fipexib8rotujuj5" 2022/04/09 06:33:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sopavid4fipexib8rotujuj5.bin" 2022/04/09 06:33:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-sopavid4fipexib8rotujuj5" 2022/04/09 06:33:45 DEBUG : potato2: md5 = 0d8d3138f283b76a4dab81309db3f388 OK 2022/04/09 06:33:46 DEBUG : empty space: md5 = 144b2e7e5929b06396271513f2b6b95c OK 2022/04/09 06:33:46 DEBUG : potato3: md5 = d2378f1fce6aa57f71353d32d1f07c43 OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1' -> Encrypted drive 'TestCryptSwift:rclone-test-sopavid4fipexib8rotujuj5' 2022/04/09 06:33:47 DEBUG : empty space: md5 = b0be9a0aea5e48c13269042a3d20dea6 OK 2022/04/09 06:33:47 DEBUG : potato3: md5 = 8a45d67a82ee01fe554ebbae74eefa70 OK 2022/04/09 06:33:48 DEBUG : empty space: Excluded 2022/04/09 06:33:48 DEBUG : empty space: Excluded 2022/04/09 06:33:48 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 06:33:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sopavid4fipexib8rotujuj5': Waiting for checks to finish 2022/04/09 06:33:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sopavid4fipexib8rotujuj5': Waiting for transfers to finish 2022/04/09 06:33:48 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:48 INFO : potato3: Deleted 2022/04/09 06:33:48 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:48 INFO : potato2: Deleted 2022/04/09 06:33:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xojahux1jacemug6pepukag6" 2022/04/09 06:33:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xojahux1jacemug6pepukag6.bin" 2022/04/09 06:33:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xojahux1jacemug6pepukag6" 2022/04/09 06:33:50 DEBUG : empty space: Excluded 2022/04/09 06:33:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xojahux1jacemug6pepukag6': Waiting for checks to finish 2022/04/09 06:33:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xojahux1jacemug6pepukag6': Waiting for transfers to finish 2022/04/09 06:33:51 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:51 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:51 INFO : potato2: Deleted 2022/04/09 06:33:51 INFO : potato3: Deleted 2022/04/09 06:33:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xojahux1jacemug6pepukag6': Purge remote 2022/04/09 06:33:51 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:52 INFO : potato3.bin: Deleted 2022/04/09 06:33:52 INFO : potato2.bin: Deleted 2022/04/09 06:33:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-sopavid4fipexib8rotujuj5': Purge remote 2022/04/09 06:33:52 DEBUG : Waiting for deletions to finish 2022/04/09 06:33:52 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (8.78s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:33:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-canidub1tahuhaw9lixamuk6" 2022/04/09 06:33:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-canidub1tahuhaw9lixamuk6.bin" 2022/04/09 06:33:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-canidub1tahuhaw9lixamuk6" 2022/04/09 06:33:54 DEBUG : potato2: md5 = 670658c354f1c787e669bfa33045c031 OK 2022/04/09 06:33:54 DEBUG : empty space: md5 = 49cf1ab30e8761b919c2f17516cc6138 OK 2022/04/09 06:33:54 DEBUG : potato3: md5 = 4a6913b12db2449553263ba9f4991c08 OK 2022/04/09 06:33:54 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1' -> Encrypted drive 'TestCryptSwift:rclone-test-canidub1tahuhaw9lixamuk6' 2022/04/09 06:33:55 DEBUG : empty space: md5 = 2e0820ed0ac2128189a158458a2ce411 OK 2022/04/09 06:33:56 DEBUG : potato3: md5 = e4f201eb26d1b26bdfa172fbe7ea497d OK 2022/04/09 06:33:56 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/04/09 06:33:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-canidub1tahuhaw9lixamuk6': Waiting for checks to finish 2022/04/09 06:33:56 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:33:56 DEBUG : empty space: Unchanged skipping 2022/04/09 06:33:56 INFO : empty space: Deleted 2022/04/09 06:33:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-canidub1tahuhaw9lixamuk6': Waiting for transfers to finish 2022/04/09 06:33:56 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:56 INFO : potato2: Deleted 2022/04/09 06:33:57 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:57 INFO : potato3: Deleted 2022/04/09 06:33:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-quqoqiv5sejepog5xibebiw7" 2022/04/09 06:33:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quqoqiv5sejepog5xibebiw7.bin" 2022/04/09 06:33:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-quqoqiv5sejepog5xibebiw7" 2022/04/09 06:33:58 DEBUG : tomatoDir: Making directory 2022/04/09 06:33:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqoqiv5sejepog5xibebiw7': Waiting for checks to finish 2022/04/09 06:33:59 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqoqiv5sejepog5xibebiw7': Waiting for transfers to finish 2022/04/09 06:33:59 INFO : empty space: Copied (server-side copy) 2022/04/09 06:33:59 INFO : potato3: Copied (server-side copy) 2022/04/09 06:33:59 INFO : potato2: Copied (server-side copy) 2022/04/09 06:33:59 INFO : potato2: Deleted 2022/04/09 06:33:59 INFO : potato3: Deleted 2022/04/09 06:33:59 INFO : empty space: Deleted 2022/04/09 06:34:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-quqoqiv5sejepog5xibebiw7': Purge remote 2022/04/09 06:34:00 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:00 INFO : potato3.bin: Deleted 2022/04/09 06:34:00 INFO : empty space.bin: Deleted 2022/04/09 06:34:00 INFO : potato2.bin: Deleted 2022/04/09 06:34:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-canidub1tahuhaw9lixamuk6': Purge remote 2022/04/09 06:34:00 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (7.67s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/rclone-move-test" 2022/04/09 06:34:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/rclone-move-test.bin" 2022/04/09 06:34:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/rclone-move-test" 2022/04/09 06:34:01 DEBUG : potato2: md5 = 9fbc77eb1a4bb50b115aeb441ece8869 OK --- PASS: TestServerSideMoveOverlap (1.77s) === RUN TestSyncOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/rclone-sync-test" 2022/04/09 06:34:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/rclone-sync-test.bin" 2022/04/09 06:34:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/rclone-sync-test" --- PASS: TestSyncOverlap (1.39s) === RUN TestSyncCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:03 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:34:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/CompareDest" 2022/04/09 06:34:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/CompareDest.bin" 2022/04/09 06:34:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/CompareDest" 2022/04/09 06:34:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:06 DEBUG : one: md5 = 69b6e1956e4d8cb24362fe41164f0360 OK 2022/04/09 06:34:06 INFO : one: Copied (new) 2022/04/09 06:34:06 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 06:34:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:07 DEBUG : one: md5 = 7748a63990c5505b2d216ae3b4dc1e7d OK 2022/04/09 06:34:07 INFO : one: Copied (replaced existing) 2022/04/09 06:34:07 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:07 DEBUG : dst/one: md5 = a9955dd1fbdf46ad6383db3b5908661e OK 2022/04/09 06:34:08 DEBUG : CompareDest/one: md5 = ebc018f93ef2c3c4ce219a69850954f6 OK 2022/04/09 06:34:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:08 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:08 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 06:34:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:08 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:08 INFO : There was nothing to transfer 2022/04/09 06:34:09 DEBUG : CompareDest/two: md5 = eb50c52db6e83e71f2b27dc63e1e05a2 OK 2022/04/09 06:34:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:09 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 06:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:09 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 06:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:09 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:09 INFO : There was nothing to transfer 2022/04/09 06:34:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:09 DEBUG : two: Destination found in --compare-dest, skipping 2022/04/09 06:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:09 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:09 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 06:34:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:09 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:09 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/04/09 06:34:10 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/04/09 06:34:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:10 DEBUG : one: Destination found in --compare-dest, skipping 2022/04/09 06:34:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:10 DEBUG : two: md5 = af5b9830d9bc5f863e71cd04667d415a OK 2022/04/09 06:34:10 INFO : two: Copied (new) 2022/04/09 06:34:10 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (8.60s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:12 DEBUG : pre-dest1/1: md5 = 79f1c0ed3d08a356ee88e9c86b086efd OK 2022/04/09 06:34:13 DEBUG : pre-dest2/2: md5 = de33f8ee69eba52fa2ae9dc5ebeb49be OK 2022/04/09 06:34:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dest" 2022/04/09 06:34:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dest.bin" 2022/04/09 06:34:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dest" 2022/04/09 06:34:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/pre-dest1" 2022/04/09 06:34:14 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/pre-dest1.bin" 2022/04/09 06:34:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/pre-dest1" 2022/04/09 06:34:15 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/pre-dest2" 2022/04/09 06:34:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/pre-dest2.bin" 2022/04/09 06:34:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/pre-dest2" 2022/04/09 06:34:16 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:16 DEBUG : 1: Destination found in --compare-dest, skipping 2022/04/09 06:34:16 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:16 DEBUG : 2: Destination found in --compare-dest, skipping 2022/04/09 06:34:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dest': Waiting for checks to finish 2022/04/09 06:34:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dest': Waiting for transfers to finish 2022/04/09 06:34:17 DEBUG : 3: md5 = 3ae31690d032039a6770c12520c3c22e OK 2022/04/09 06:34:17 INFO : 3: Copied (new) 2022/04/09 06:34:17 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (5.76s) === RUN TestSyncCopyDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:34:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/CopyDest" 2022/04/09 06:34:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/CopyDest.bin" 2022/04/09 06:34:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/CopyDest" 2022/04/09 06:34:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:20 DEBUG : one: md5 = 1c9add33e4b33546b490dd85d5f78825 OK 2022/04/09 06:34:20 INFO : one: Copied (new) 2022/04/09 06:34:20 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:21 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 06:34:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:21 DEBUG : one: md5 = 8734715537ab16c2e2c90224bbbf9b94 OK 2022/04/09 06:34:21 INFO : one: Copied (replaced existing) 2022/04/09 06:34:21 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:21 DEBUG : dst/one: md5 = 77f1d9ab75836c1dd0a99fcc0071bab8 OK 2022/04/09 06:34:22 DEBUG : CopyDest/one: md5 = e66f3a15af0c1d578a6ff7d8128fae49 OK 2022/04/09 06:34:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/BackupDir" 2022/04/09 06:34:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/BackupDir.bin" 2022/04/09 06:34:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/BackupDir" 2022/04/09 06:34:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:23 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:23 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/04/09 06:34:24 INFO : one: Copied (server-side copy) 2022/04/09 06:34:24 INFO : one: Deleted 2022/04/09 06:34:24 INFO : one: Copied (server-side copy) 2022/04/09 06:34:24 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/04/09 06:34:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:24 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:25 DEBUG : CopyDest/two: md5 = eb7e363c8398048387ae71d73a6c2150 OK 2022/04/09 06:34:25 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 INFO : two: Copied (server-side copy) 2022/04/09 06:34:26 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/04/09 06:34:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : one: Unchanged skipping 2022/04/09 06:34:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:26 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : one: Unchanged skipping 2022/04/09 06:34:26 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:26 DEBUG : two: Unchanged skipping 2022/04/09 06:34:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:26 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:26 INFO : There was nothing to transfer 2022/04/09 06:34:27 DEBUG : CopyDest/three: md5 = 64f816977f2ac05063f180ab69643801 OK 2022/04/09 06:34:28 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/04/09 06:34:28 DEBUG : three: Destination not found in --copy-dest 2022/04/09 06:34:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:28 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:28 DEBUG : two: Unchanged skipping 2022/04/09 06:34:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:28 DEBUG : one: Unchanged skipping 2022/04/09 06:34:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:28 DEBUG : three: md5 = 3a5b9df094683a378e767c37fdf51386 OK 2022/04/09 06:34:28 INFO : three: Copied (new) 2022/04/09 06:34:28 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (12.99s) === RUN TestSyncBackupDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:31 DEBUG : dst/one: md5 = 22dc8010e2bccea21e70f7076948389b OK 2022/04/09 06:34:31 DEBUG : dst/two: md5 = 1e60c9b78b5f5aa16c3e6f8d92801e26 OK 2022/04/09 06:34:32 DEBUG : dst/three.txt: md5 = 34111ed591899457443d91140e2e9de5 OK 2022/04/09 06:34:32 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:34:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup.bin" 2022/04/09 06:34:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:34 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:34:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:34 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:34 DEBUG : two: Unchanged skipping 2022/04/09 06:34:34 INFO : one: Copied (server-side copy) 2022/04/09 06:34:36 INFO : one: Deleted 2022/04/09 06:34:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:36 DEBUG : one: md5 = 064a53d262fbbb15535fd05e87dcc7ba OK 2022/04/09 06:34:36 INFO : one: Copied (new) 2022/04/09 06:34:36 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:36 INFO : three.txt: Copied (server-side copy) 2022/04/09 06:34:36 INFO : three.txt: Deleted 2022/04/09 06:34:36 INFO : three.txt: Moved into backup dir 2022/04/09 06:34:37 DEBUG : dst/three.txt: md5 = dbb584409b11e5420760a186d213b577 OK 2022/04/09 06:34:38 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:34:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:38 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:38 DEBUG : two: Unchanged skipping 2022/04/09 06:34:38 INFO : one: Copied (server-side copy) 2022/04/09 06:34:38 INFO : one: Deleted 2022/04/09 06:34:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:39 DEBUG : one: md5 = 5e4900e2d8329c5c0d31410c851a37d5 OK 2022/04/09 06:34:39 INFO : one: Copied (new) 2022/04/09 06:34:39 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:39 INFO : three.txt: Copied (server-side copy) 2022/04/09 06:34:39 INFO : three.txt: Deleted 2022/04/09 06:34:39 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (10.29s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:41 DEBUG : dst/one: md5 = 3595290f0abcdaac7f0ee84cbc965f4d OK 2022/04/09 06:34:42 DEBUG : dst/two: md5 = 1abf7a96d6cd7bd09c2146bb47d7cbaa OK 2022/04/09 06:34:42 DEBUG : dst/three.txt: md5 = f1f6b3e26041459eb280b501fff571c3 OK 2022/04/09 06:34:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:34:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup.bin" 2022/04/09 06:34:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:44 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:34:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:44 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:44 DEBUG : two: Unchanged skipping 2022/04/09 06:34:45 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:34:45 INFO : one: Deleted 2022/04/09 06:34:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:45 DEBUG : one: md5 = 22c32cd99af155a717bebc3129adcff5 OK 2022/04/09 06:34:45 INFO : one: Copied (new) 2022/04/09 06:34:45 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:46 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:34:46 INFO : three.txt: Deleted 2022/04/09 06:34:46 INFO : three.txt: Moved into backup dir 2022/04/09 06:34:46 DEBUG : dst/three.txt: md5 = a18ab8abbbabedbb8e3549d00ee01c4f OK 2022/04/09 06:34:47 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:34:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:47 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:47 DEBUG : two: Unchanged skipping 2022/04/09 06:34:47 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:34:47 INFO : one: Deleted 2022/04/09 06:34:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:48 DEBUG : one: md5 = bce795aaf819d049722c1648e1039003 OK 2022/04/09 06:34:48 INFO : one: Copied (new) 2022/04/09 06:34:48 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:48 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:34:48 INFO : three.txt: Deleted 2022/04/09 06:34:48 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (9.06s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:50 DEBUG : dst/one: md5 = f2293316f4404ccfb422fea7d4f106a7 OK 2022/04/09 06:34:51 DEBUG : dst/two: md5 = 0b71929aa78438ad43d61465a2bee9b2 OK 2022/04/09 06:34:51 DEBUG : dst/three.txt: md5 = 3b210ef046be3af2243aeb86887622d6 OK 2022/04/09 06:34:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:34:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:34:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup.bin" 2022/04/09 06:34:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/backup" 2022/04/09 06:34:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:34:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:53 DEBUG : two: Unchanged skipping 2022/04/09 06:34:55 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/09 06:34:55 INFO : one: Deleted 2022/04/09 06:34:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:55 DEBUG : one: md5 = e1dd4577547ed65404329d4b08d656e8 OK 2022/04/09 06:34:55 INFO : one: Copied (new) 2022/04/09 06:34:55 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:56 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/09 06:34:56 INFO : three.txt: Deleted 2022/04/09 06:34:56 INFO : three.txt: Moved into backup dir 2022/04/09 06:34:56 DEBUG : dst/three.txt: md5 = e7a23c532602a440ec3f4650d9a75523 OK 2022/04/09 06:34:57 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:34:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:34:57 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:34:57 DEBUG : two: Unchanged skipping 2022/04/09 06:34:57 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/09 06:34:57 INFO : one: Deleted 2022/04/09 06:34:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:34:58 DEBUG : one: md5 = 1e9c51f0d8a61dcbacfae74dd3fd4a8f OK 2022/04/09 06:34:58 INFO : one: Copied (new) 2022/04/09 06:34:58 DEBUG : Waiting for deletions to finish 2022/04/09 06:34:58 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/09 06:34:58 INFO : three.txt: Deleted 2022/04/09 06:34:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (8.77s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:34:59 DEBUG : dst/one: md5 = 12bc85f68e30f5d1f3b34c1ea86339da OK 2022/04/09 06:34:59 DEBUG : dst/two: md5 = cc8bb43f4218492417accc1ba1a2fd48 OK 2022/04/09 06:35:00 DEBUG : dst/three.txt: md5 = 42400aa1bf3009e2fce0e6e8f7e54694 OK 2022/04/09 06:35:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:35:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:01 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:35:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:35:01 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:01 DEBUG : two: Unchanged skipping 2022/04/09 06:35:02 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:35:02 INFO : one: Deleted 2022/04/09 06:35:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:35:02 DEBUG : one: md5 = 0efef9d533ba5bb626575324bbebc7af OK 2022/04/09 06:35:02 INFO : one: Copied (new) 2022/04/09 06:35:02 DEBUG : Waiting for deletions to finish 2022/04/09 06:35:03 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:35:03 INFO : three.txt: Deleted 2022/04/09 06:35:03 INFO : three.txt: Moved into backup dir 2022/04/09 06:35:03 DEBUG : dst/three.txt: md5 = b678834c6d04ba0836c1abb3ce1d300f OK 2022/04/09 06:35:04 DEBUG : one.bak: Excluded 2022/04/09 06:35:04 DEBUG : three.txt.bak: Excluded 2022/04/09 06:35:04 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:35:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for checks to finish 2022/04/09 06:35:04 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:04 DEBUG : two: Unchanged skipping 2022/04/09 06:35:04 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:35:04 INFO : one: Deleted 2022/04/09 06:35:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst': Waiting for transfers to finish 2022/04/09 06:35:05 DEBUG : one: md5 = 36b920b9675cbb43daafd5d034fc1241 OK 2022/04/09 06:35:05 INFO : one: Copied (new) 2022/04/09 06:35:05 DEBUG : Waiting for deletions to finish 2022/04/09 06:35:05 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:35:05 INFO : three.txt: Deleted 2022/04/09 06:35:05 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (7.79s) === RUN TestSyncSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:07 DEBUG : dst/one: md5 = 66434640696ac97276234fa58e1cda17 OK 2022/04/09 06:35:07 DEBUG : dst/two: md5 = cdfde5cdd74e0bc1f22d78c14af40ee9 OK 2022/04/09 06:35:08 DEBUG : dst/three.txt: md5 = 70efc2e15c7f56d6ddfd6adb2a3f763b OK 2022/04/09 06:35:08 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:35:08 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:09 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:35:09 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:35:09 INFO : one: Deleted 2022/04/09 06:35:10 DEBUG : one: md5 = ef7077f3f271ffff85e99c07fc0d0cf1 OK 2022/04/09 06:35:10 INFO : one: Copied (new) 2022/04/09 06:35:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:10 DEBUG : two: Unchanged skipping 2022/04/09 06:35:10 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/09 06:35:10 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:35:11 INFO : three.txt: Deleted 2022/04/09 06:35:11 DEBUG : three.txt: md5 = f923dd5db427bbe3b61ba5bddc1257d3 OK 2022/04/09 06:35:11 INFO : three.txt: Copied (new) 2022/04/09 06:35:12 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:35:12 INFO : one: Copied (server-side copy) to: one.bak 2022/04/09 06:35:13 INFO : one: Deleted 2022/04/09 06:35:13 DEBUG : one: md5 = ba00d0226d8aa2a174f2b744e3326984 OK 2022/04/09 06:35:13 INFO : one: Copied (new) 2022/04/09 06:35:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:13 DEBUG : two: Unchanged skipping 2022/04/09 06:35:13 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/09 06:35:13 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/04/09 06:35:13 INFO : three.txt: Deleted 2022/04/09 06:35:14 DEBUG : three.txt: md5 = 079e9cde968faccd65b70d2745549a10 OK 2022/04/09 06:35:14 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (8.73s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:16 DEBUG : dst/one: md5 = 6a4d66cd23d17cb1a7dbdfcf439eb4ed OK 2022/04/09 06:35:16 DEBUG : dst/two: md5 = ae8b519410d581d8a79ee6e5c191d005 OK 2022/04/09 06:35:16 DEBUG : dst/three.txt: md5 = c95c20667c6aa856ea3317757399fbeb OK 2022/04/09 06:35:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst.bin" 2022/04/09 06:35:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nilemuh1pufinuq7gepepob1/dst" 2022/04/09 06:35:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/04/09 06:35:18 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/09 06:35:18 INFO : one: Deleted 2022/04/09 06:35:19 DEBUG : one: md5 = 9d32cfe2c3b6cb165edf76f3544ba0e0 OK 2022/04/09 06:35:19 INFO : one: Copied (new) 2022/04/09 06:35:19 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:19 DEBUG : two: Unchanged skipping 2022/04/09 06:35:19 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/04/09 06:35:19 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/09 06:35:19 INFO : three.txt: Deleted 2022/04/09 06:35:20 DEBUG : three.txt: md5 = cdacfc346514ed99021b5e0103de8c86 OK 2022/04/09 06:35:20 INFO : three.txt: Copied (new) 2022/04/09 06:35:20 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/04/09 06:35:22 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/04/09 06:35:23 INFO : one: Deleted 2022/04/09 06:35:23 DEBUG : one: md5 = 842492dac1f1befefead6f11fe55dd38 OK 2022/04/09 06:35:23 INFO : one: Copied (new) 2022/04/09 06:35:23 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:23 DEBUG : two: Unchanged skipping 2022/04/09 06:35:23 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/04/09 06:35:23 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/04/09 06:35:24 INFO : three.txt: Deleted 2022/04/09 06:35:24 DEBUG : three.txt: md5 = f1966132e8e2dd6a9fe91877f3cf781e OK 2022/04/09 06:35:24 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (9.78s) === RUN TestSyncUTFNorm run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:25 DEBUG : Testêé: md5 = 685822e9e5cdc3077dd77cf4589e7527 OK 2022/04/09 06:35:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:35:26 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/04/09 06:35:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:35:26 DEBUG : Testêé: md5 = dca4a8b838364d8329aa1202e43e1e84 OK 2022/04/09 06:35:26 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/04/09 06:35:26 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.51s) === RUN TestSyncImmutable run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:35:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:35:27 DEBUG : existing: md5 = c147945b207ca2694fa12884a595359b OK 2022/04/09 06:35:27 INFO : existing: Copied (new) 2022/04/09 06:35:27 DEBUG : Waiting for deletions to finish 2022/04/09 06:35:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:35:27 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/04/09 06:35:27 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/04/09 06:35:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:35:27 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': not deleting files as there were IO errors 2022/04/09 06:35:27 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.38s) === RUN TestSyncIgnoreCase run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:28 DEBUG : EXISTING: md5 = d27e278f75b886ff74b852e1f7bb0c95 OK 2022/04/09 06:35:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:35:29 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:29 DEBUG : existing: Unchanged skipping 2022/04/09 06:35:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:35:29 DEBUG : Waiting for deletions to finish 2022/04/09 06:35:29 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.10s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", 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-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", 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-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.34s) --- SKIP: TestMaxTransfer/Hard (0.16s) --- SKIP: TestMaxTransfer/Soft (0.09s) --- SKIP: TestMaxTransfer/Cautious (0.09s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:30 DEBUG : both0: md5 = ad76acba4978dd3bb3a7ff2b92de2ee5 OK 2022/04/09 06:35:30 DEBUG : only0: md5 = 5331328d8251223176ef5e9d4bcbc748 OK 2022/04/09 06:35:31 DEBUG : both1: md5 = ad18406b25be5641884161a3997c3808 OK 2022/04/09 06:35:32 DEBUG : only1: md5 = 449d363eb2aa616981ffa675dd0ab435 OK 2022/04/09 06:35:32 DEBUG : both2: md5 = 1db732b9b898dc2a24374db81960b827 OK 2022/04/09 06:35:32 DEBUG : only2: md5 = ca05a3fb7904c2025224d7ea16c15e35 OK 2022/04/09 06:35:33 DEBUG : both3: md5 = b7a665ce8d94c49dfda41e5439dbda1d OK 2022/04/09 06:35:33 DEBUG : only3: md5 = b74e3d0d4234943745b88a23ee3b8e4a OK 2022/04/09 06:35:33 DEBUG : both4: md5 = 99eeeb929a7f285540776634c67d0497 OK 2022/04/09 06:35:34 DEBUG : only4: md5 = 738430e3817ac53916afe9a0f3f9909b OK 2022/04/09 06:35:34 DEBUG : both5: md5 = 43f1936a83a6cdd11938dbd5c9810a6f OK 2022/04/09 06:35:35 DEBUG : only5: md5 = 83612461217313c75f92ccd1c46fb074 OK 2022/04/09 06:35:35 DEBUG : both6: md5 = 0a0a0fedbf6300cceac0eee977973946 OK 2022/04/09 06:35:36 DEBUG : only6: md5 = 76e2805cc13e82258d68bafd1296ced8 OK 2022/04/09 06:35:36 DEBUG : both7: md5 = 95c43bbfb0b99437ae63b0192dc54027 OK 2022/04/09 06:35:36 DEBUG : only7: md5 = f3f0cbe1fb37869f04938b82e070c382 OK 2022/04/09 06:35:37 DEBUG : both8: md5 = a6e0ae0e53d56c8645b1c6eec8222bdf OK 2022/04/09 06:35:37 DEBUG : only8: md5 = 8178f50a3487bcbff722c6c70dafaff4 OK 2022/04/09 06:35:38 DEBUG : both9: md5 = ffa3e515814efe4c5c0b0c53364f6925 OK 2022/04/09 06:35:38 DEBUG : only9: md5 = 609df28f56f53063485a4d1eaea965bc OK 2022/04/09 06:35:39 DEBUG : both10: md5 = 56bc6d12f6d40a298101711a50cc2ea6 OK 2022/04/09 06:35:39 DEBUG : only10: md5 = 061364c14225527274fc9d0bcebc4f6c OK 2022/04/09 06:35:40 DEBUG : both11: md5 = c4bf2d360d39d3329c65485ec491b34a OK 2022/04/09 06:35:40 DEBUG : only11: md5 = d70936af10169c651b5b789c7197b0fd OK 2022/04/09 06:35:40 DEBUG : both12: md5 = 19c1d8cb8cc01b5532d3cac2f059f81b OK 2022/04/09 06:35:41 DEBUG : only12: md5 = 868664535d38b990f838b1a512bdba02 OK 2022/04/09 06:35:42 DEBUG : both13: md5 = b7dcb8a88bac22e622c59e9e2e0bd867 OK 2022/04/09 06:35:42 DEBUG : only13: md5 = e7b7a825ec04505a0b668177f30bdf83 OK 2022/04/09 06:35:42 DEBUG : both14: md5 = 7e58dfb3a1beac110bbf0e9506129330 OK 2022/04/09 06:35:43 DEBUG : only14: md5 = 927dfe9dd9ab1bf9236aff0c55fe7510 OK 2022/04/09 06:35:44 DEBUG : both15: md5 = 7b946e71f12904f67c63fe03cac5dc26 OK 2022/04/09 06:35:44 DEBUG : only15: md5 = aa700a29b72b1b4d9e58dc61b5da8706 OK 2022/04/09 06:35:44 DEBUG : both16: md5 = 3747bb7aa40d4a3f55a3c4a37df30c6b OK 2022/04/09 06:35:45 DEBUG : only16: md5 = cbcde2d2cfcc5ce09ffea4e7ee216e37 OK 2022/04/09 06:35:45 DEBUG : both17: md5 = f2d045346960a0d5fdcfc24e02ecf079 OK 2022/04/09 06:35:46 DEBUG : only17: md5 = 4ef661840b9c21f7a6d8952738ba6828 OK 2022/04/09 06:35:46 DEBUG : both18: md5 = 0cfb0a0463c177f6f30852368276abea OK 2022/04/09 06:35:47 DEBUG : only18: md5 = e23b0d98a2dedafbd6fd0bed1005a893 OK 2022/04/09 06:35:47 DEBUG : both19: md5 = 549c6c10f7ba9d94129fceb16ce72ad1 OK 2022/04/09 06:35:48 DEBUG : only19: md5 = 507923277908deecab12d3601c1621fa OK 2022/04/09 06:35:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:35:50 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both0: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both1: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both10: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both11: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both12: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both13: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both14: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both15: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both16: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both17: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both18: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both19: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both2: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both3: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both4: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both5: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both6: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both7: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both8: Unchanged skipping 2022/04/09 06:35:50 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:35:50 DEBUG : both9: Unchanged skipping 2022/04/09 06:35:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:35:50 DEBUG : Waiting for deletions to finish 2022/04/09 06:35:51 INFO : only10: Deleted 2022/04/09 06:35:51 INFO : only1: Deleted 2022/04/09 06:35:51 INFO : only0: Deleted 2022/04/09 06:35:51 INFO : only16: Deleted 2022/04/09 06:35:51 INFO : only18: Deleted 2022/04/09 06:35:51 INFO : only2: Deleted 2022/04/09 06:35:51 INFO : only3: Deleted 2022/04/09 06:35:51 INFO : only4: Deleted 2022/04/09 06:35:51 INFO : only6: Deleted 2022/04/09 06:35:51 INFO : only14: Deleted 2022/04/09 06:35:52 INFO : only8: Deleted 2022/04/09 06:35:52 INFO : only15: Deleted 2022/04/09 06:35:52 INFO : only19: Deleted 2022/04/09 06:35:52 INFO : only5: Deleted 2022/04/09 06:35:52 INFO : only11: Deleted 2022/04/09 06:35:52 INFO : only12: Deleted 2022/04/09 06:35:52 INFO : only13: Deleted 2022/04/09 06:35:52 INFO : only17: Deleted 2022/04/09 06:35:52 INFO : only7: Deleted 2022/04/09 06:35:53 INFO : only9: Deleted 2022/04/09 06:35:53 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (28.76s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1'", Local "Local file system at /tmp/rclone1425601831", Modify Window "1ns" 2022/04/09 06:35:58 DEBUG : both0: md5 = f5de1a85b653f3956a7b58d6b1ecdf1e OK 2022/04/09 06:35:59 DEBUG : only0: md5 = 891aa4a421466c99281f2ee77fd74ef6 OK 2022/04/09 06:36:00 DEBUG : both1: md5 = 135436589ba2b8f4a877df835d7a2146 OK 2022/04/09 06:36:00 DEBUG : only1: md5 = 2da9c4aaea271848e89d1fe0c5b761ce OK 2022/04/09 06:36:00 DEBUG : both2: md5 = 5d137524213db4d1fce5ec1cb1a86e48 OK 2022/04/09 06:36:01 DEBUG : only2: md5 = 59b20ee61fa20dd4f7b01a2650e5b6dc OK 2022/04/09 06:36:01 DEBUG : both3: md5 = 275cdd6e8e6edb135c2f1323a2caf278 OK 2022/04/09 06:36:02 DEBUG : only3: md5 = b04294a23febc97493f4420cec50dc9e OK 2022/04/09 06:36:02 DEBUG : both4: md5 = 737431b9dd6b45cf7042910182e87234 OK 2022/04/09 06:36:02 DEBUG : only4: md5 = 68636eb9e999d3e6ace9833c9ed51a96 OK 2022/04/09 06:36:03 DEBUG : both5: md5 = 42ad7e437826d4304b28194cbbba5f1c OK 2022/04/09 06:36:03 DEBUG : only5: md5 = ba20c69756a91873c7dd59e1a273eabb OK 2022/04/09 06:36:03 DEBUG : both6: md5 = 18611b8569efc831bd22c7d31d6c8d93 OK 2022/04/09 06:36:03 DEBUG : only6: md5 = a742f153dd0d9f8d136eb082af635f99 OK 2022/04/09 06:36:04 DEBUG : both7: md5 = 3cfd2bd5dacb21b3ec874096a381b455 OK 2022/04/09 06:36:04 DEBUG : only7: md5 = b39547a3bd5f87412808792fd14b37e2 OK 2022/04/09 06:36:04 DEBUG : both8: md5 = 19a91d2794c7e4262661af10796776d1 OK 2022/04/09 06:36:05 DEBUG : only8: md5 = 123840477f2e8b5b2aae38652541e460 OK 2022/04/09 06:36:05 DEBUG : both9: md5 = 67ffd1d784792938d393e2b81de4d9ed OK 2022/04/09 06:36:06 DEBUG : only9: md5 = a844697a0efcc1387b9ea611936c5c57 OK 2022/04/09 06:36:06 DEBUG : both10: md5 = ba61375227635c5ea6b418c1135874fa OK 2022/04/09 06:36:06 DEBUG : only10: md5 = 665ea9acf771016c84c2c8371671020a OK 2022/04/09 06:36:07 DEBUG : both11: md5 = 92034c828038e0d6fdbc184ec4333527 OK 2022/04/09 06:36:07 DEBUG : only11: md5 = 82761ae087c94cd7bf8317052eb0d18f OK 2022/04/09 06:36:07 DEBUG : both12: md5 = 4732ffb2f603299c8058be0ade974bb8 OK 2022/04/09 06:36:07 DEBUG : only12: md5 = ec8b3aceb0088fc3179d2fbb2e45c5ad OK 2022/04/09 06:36:08 DEBUG : both13: md5 = 837e9d076093944f3ff9c8fd38e60a2d OK 2022/04/09 06:36:08 DEBUG : only13: md5 = acfc79f9fd43dc82d4024ee6e25a7a2a OK 2022/04/09 06:36:08 DEBUG : both14: md5 = 3a662f3040d08af6b2d69681484e059b OK 2022/04/09 06:36:09 DEBUG : only14: md5 = 37d95fa783fff71aaedfb7d037de6ac1 OK 2022/04/09 06:36:09 DEBUG : both15: md5 = 88ef6586676b454fed986aae2389d423 OK 2022/04/09 06:36:09 DEBUG : only15: md5 = cdef92f3f02986d06992cc808b9fe463 OK 2022/04/09 06:36:10 DEBUG : both16: md5 = dd8f988d1cca918dee6231ac501a0056 OK 2022/04/09 06:36:10 DEBUG : only16: md5 = 94559ca189622705e724d6576c82c978 OK 2022/04/09 06:36:10 DEBUG : both17: md5 = b4855a9984523607dabb3284be225397 OK 2022/04/09 06:36:11 DEBUG : only17: md5 = bf9f720fa0996c11fd9495e6e2fe70b9 OK 2022/04/09 06:36:11 DEBUG : both18: md5 = b4601a34015611e09c89841e3c6ed27a OK 2022/04/09 06:36:11 DEBUG : only18: md5 = 1378c8c717ac6e9d72d6b644f0a13ce7 OK 2022/04/09 06:36:12 DEBUG : both19: md5 = ffce24b73eda90fc64ec3cf8bfb359a5 OK 2022/04/09 06:36:13 DEBUG : only19: md5 = 9cb5076a3a3eba1f33bc270a8758b702 OK 2022/04/09 06:36:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for checks to finish 2022/04/09 06:36:15 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both1: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both10: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both0: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both11: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both12: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both13: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both14: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both15: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both16: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both17: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both18: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both19: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both2: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both3: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both4: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both5: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both6: Unchanged skipping 2022/04/09 06:36:15 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/04/09 06:36:15 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both7: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both8: Unchanged skipping 2022/04/09 06:36:15 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/04/09 06:36:15 DEBUG : both9: Unchanged skipping 2022/04/09 06:36:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Waiting for transfers to finish 2022/04/09 06:36:15 DEBUG : only10: md5 = c29d7d9ac0a3656d110a5eb372d30fd6 OK 2022/04/09 06:36:15 INFO : only10: Copied (replaced existing) 2022/04/09 06:36:15 DEBUG : only11: md5 = f2e80a80777fd4652ee6c1f4a6672c95 OK 2022/04/09 06:36:15 INFO : only11: Copied (replaced existing) 2022/04/09 06:36:15 DEBUG : only1: md5 = 841061bb12566cbbf8952b22f76811f4 OK 2022/04/09 06:36:15 INFO : only1: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only0: md5 = b0be87884e7d13d55d2a570ecc786d16 OK 2022/04/09 06:36:16 INFO : only0: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only12: md5 = 312de7f32a014e9c25e0c9c28d2fbcc7 OK 2022/04/09 06:36:16 INFO : only12: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only13: md5 = 862cd61ad5c614a84aa3735cbc8e0ce8 OK 2022/04/09 06:36:16 INFO : only13: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only14: md5 = e2a10066297b9cfcd255cc7a8bc666cd OK 2022/04/09 06:36:16 INFO : only14: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only16: md5 = 2d0c71c94a95e1dc26459ff77aa67206 OK 2022/04/09 06:36:16 INFO : only16: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only15: md5 = f4cec24fe6167d18794d8085e68d1de8 OK 2022/04/09 06:36:16 INFO : only15: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only17: md5 = a22e150b57c6d84ecfd6f212deea6803 OK 2022/04/09 06:36:16 INFO : only17: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only18: md5 = 71a1feb855b8bc5a3f4d0d5121a65048 OK 2022/04/09 06:36:16 INFO : only18: Copied (replaced existing) 2022/04/09 06:36:16 DEBUG : only19: md5 = 93d9629cf1effc25159a2a24309ea2b2 OK 2022/04/09 06:36:16 INFO : only19: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only2: md5 = 6baf4af156f7ec1cb66decc24879f303 OK 2022/04/09 06:36:18 INFO : only2: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only3: md5 = 79afae5f8f59ab4beb8f55a1fb0c265c OK 2022/04/09 06:36:18 INFO : only3: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only5: md5 = ee00f426b72d57487f3e1e344db4b931 OK 2022/04/09 06:36:18 INFO : only5: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only6: md5 = 1fe8aa1ed141e67af5b75309aefba5cb OK 2022/04/09 06:36:18 INFO : only6: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only7: md5 = 7497f45a45db7a32f530420aa9330a4b OK 2022/04/09 06:36:18 INFO : only7: Copied (replaced existing) 2022/04/09 06:36:18 DEBUG : only8: md5 = 650937e14ab953b43532cbef253ae9d4 OK 2022/04/09 06:36:18 INFO : only8: Copied (replaced existing) 2022/04/09 06:36:19 DEBUG : only9: md5 = f4fb98ecbe3d83c2145e55fa5643bcb8 OK 2022/04/09 06:36:19 INFO : only9: Copied (replaced existing) 2022/04/09 06:36:19 DEBUG : only4: md5 = 99c5f052124ed36450fabdae6e61355c OK 2022/04/09 06:36:19 INFO : only4: Copied (replaced existing) 2022/04/09 06:36:19 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (30.38s) PASS 2022/04/09 06:36:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nilemuh1pufinuq7gepepob1': Purge remote 2022/04/09 06:36:29 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 4m4.078584728s (try 1/5)