"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2022/02/10 05:00:14 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0" 2022/02/10 05:00:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2022/02/10 05:00:15 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0.bin" 2022/02/10 05:00:15 DEBUG : Config file has changed externaly - reloading 2022/02/10 05:00:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0" 2022/02/10 05:00:16 DEBUG : Config file has changed externaly - reloading 2022/02/10 05:00:16 DEBUG : Creating backend with remote "/tmp/rclone1070390538" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.04s) === 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-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:16 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2022/02/10 05:00:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish --- PASS: TestCopyWithDryRun (0.38s) === RUN TestCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:18 DEBUG : sub dir/hello world: md5 = 922d10adeeeca26604022bd3c4daad9c OK 2022/02/10 05:00:18 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopy (1.93s) === RUN TestCopyMissingDirectory run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:19 DEBUG : Creating backend with remote "/non-existing" 2022/02/10 05:00:19 DEBUG : Config file has changed externaly - reloading 2022/02/10 05:00:19 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2022/02/10 05:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish --- PASS: TestCopyMissingDirectory (0.20s) === RUN TestCopyNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:19 DEBUG : sub dir/hello world: md5 = 6cbe89f1ae480be9a254cea4be0d7e25 OK 2022/02/10 05:00:19 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyNoTraverse (1.91s) === RUN TestCopyCheckFirst run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:21 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Running all checks before starting transfers 2022/02/10 05:00:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:21 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Checks finished, now starting transfers 2022/02/10 05:00:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:22 DEBUG : sub dir/hello world: md5 = 696f4904d858fe55997f1057db3f3c2d OK 2022/02/10 05:00:22 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyCheckFirst (1.72s) === RUN TestSyncNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:22 ERROR : Ignoring --no-traverse with sync 2022/02/10 05:00:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:23 DEBUG : sub dir/hello world: md5 = aeabbc87b12c4565d57c35c6ec963842 OK 2022/02/10 05:00:23 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:00:23 DEBUG : Waiting for deletions to finish --- PASS: TestSyncNoTraverse (1.27s) === RUN TestCopyWithDepth run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:25 DEBUG : hello world2: md5 = 4e11136ab837e2f070374565e28694c9 OK 2022/02/10 05:00:25 INFO : hello world2: Copied (new) --- PASS: TestCopyWithDepth (1.54s) === RUN TestCopyWithFilesFrom run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:25 DEBUG : hello world2: Excluded 2022/02/10 05:00:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:27 DEBUG : potato2: md5 = 3a86b4b759ade2fcecbcd230797d989b OK 2022/02/10 05:00:27 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFrom (2.62s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:29 DEBUG : potato2: md5 = 977e8f9e5fcb252a4012c1120658ce0f OK 2022/02/10 05:00:29 INFO : potato2: Copied (new) --- PASS: TestCopyWithFilesFromAndNoTraverse (1.48s) === RUN TestCopyEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:29 DEBUG : sub dir2: Making directory 2022/02/10 05:00:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:30 DEBUG : sub dir/hello world: md5 = 38fc0769000ed06a02370a5fdc6c3f63 OK 2022/02/10 05:00:30 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:00:30 DEBUG : sub dir2: Making directory 2022/02/10 05:00:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': copied 1 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestCopyEmptyDirectories (1.53s) === RUN TestMoveEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:31 DEBUG : sub dir2: Making directory 2022/02/10 05:00:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:31 DEBUG : sub dir/hello world: md5 = f98cd35232209394a1d748651d19bf02 OK 2022/02/10 05:00:31 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:00:31 INFO : sub dir/hello world: Deleted 2022/02/10 05:00:31 DEBUG : sub dir: Making directory 2022/02/10 05:00:31 DEBUG : sub dir2: Making directory 2022/02/10 05:00:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': copied 2 directories fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestMoveEmptyDirectories (1.15s) === RUN TestSyncEmptyDirectories run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:32 DEBUG : sub dir2: Making directory 2022/02/10 05:00:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:33 DEBUG : sub dir/hello world: md5 = 027ed4d63dc15e987a3d0a12bdd7d595 OK 2022/02/10 05:00:33 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:00:33 DEBUG : sub dir2: Making directory 2022/02/10 05:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': copied 1 directories 2022/02/10 05:00:33 DEBUG : Waiting for deletions to finish fstest.go:251: Filtering empty directory "sub dir2" --- PASS: TestSyncEmptyDirectories (1.13s) === RUN TestServerSideCopy run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:34 DEBUG : sub dir/hello world: md5 = a12630a36c64d927eb304e4e9aef8c5d OK 2022/02/10 05:00:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xavoqey3remaveg1ciyozac8" 2022/02/10 05:00:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xavoqey3remaveg1ciyozac8.bin" 2022/02/10 05:00:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xavoqey3remaveg1ciyozac8" sync_test.go:275: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0' -> Encrypted drive 'TestCryptSwift:rclone-test-xavoqey3remaveg1ciyozac8' 2022/02/10 05:00:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xavoqey3remaveg1ciyozac8': Waiting for checks to finish 2022/02/10 05:00:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xavoqey3remaveg1ciyozac8': Waiting for transfers to finish 2022/02/10 05:00:42 INFO : sub dir/hello world: Copied (server-side copy) 2022/02/10 05:00:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xavoqey3remaveg1ciyozac8': Purge remote 2022/02/10 05:00:43 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideCopy (9.89s) === RUN TestCopyAfterDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:44 DEBUG : sub dir/hello world: md5 = d8571b0912deb1f164c40d5dbb692eb4 OK 2022/02/10 05:00:44 ERROR : : error listing: directory not found 2022/02/10 05:00:44 DEBUG : Local file system at /tmp/rclone1070390538: Making directory 2022/02/10 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish --- PASS: TestCopyAfterDelete (1.78s) === RUN TestCopyRedownload run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:45 DEBUG : sub dir/hello world: md5 = 460b9ba431dbde7b5ec428c6f7c007dd OK 2022/02/10 05:00:46 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for checks to finish 2022/02/10 05:00:46 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for transfers to finish 2022/02/10 05:00:46 INFO : sub dir/hello world: Copied (new) --- PASS: TestCopyRedownload (1.28s) === RUN TestSyncBasedOnCheckSum run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:47 DEBUG : check sum: md5 = 0ede6b64e612093594d0772bb2655e35 OK 2022/02/10 05:00:47 INFO : check sum: Copied (new) 2022/02/10 05:00:47 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:47 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2022/02/10 05:00:47 DEBUG : check sum: Size of src and dst objects identical 2022/02/10 05:00:47 DEBUG : check sum: Unchanged skipping 2022/02/10 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:47 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:47 INFO : There was nothing to transfer --- PASS: TestSyncBasedOnCheckSum (1.80s) === RUN TestSyncSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:49 DEBUG : sizeonly: md5 = 5e112e9604a5d2c3cc55d4bc10d5e4b0 OK 2022/02/10 05:00:49 INFO : sizeonly: Copied (new) 2022/02/10 05:00:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:50 DEBUG : sizeonly: Sizes identical 2022/02/10 05:00:50 DEBUG : sizeonly: Unchanged skipping 2022/02/10 05:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:50 INFO : There was nothing to transfer --- PASS: TestSyncSizeOnly (2.75s) === RUN TestSyncIgnoreSize run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:51 DEBUG : ignore-size: md5 = 2379131aa855c060b0389dad0c987593 OK 2022/02/10 05:00:51 INFO : ignore-size: Copied (new) 2022/02/10 05:00:51 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:52 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:00:52 DEBUG : ignore-size: Unchanged skipping 2022/02/10 05:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:52 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:52 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreSize (1.47s) === RUN TestSyncIgnoreTimes run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:53 DEBUG : existing: md5 = 4e23cfd173cbf590ceeb8fda6ed53293 OK 2022/02/10 05:00:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:53 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:00:53 DEBUG : existing: Unchanged skipping 2022/02/10 05:00:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:53 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:53 INFO : There was nothing to transfer 2022/02/10 05:00:53 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2022/02/10 05:00:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:54 DEBUG : existing: md5 = 0e5731d32b962f83aebbd9b62eeb945c OK 2022/02/10 05:00:54 INFO : existing: Copied (replaced existing) 2022/02/10 05:00:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncIgnoreTimes (2.37s) === RUN TestSyncIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:55 DEBUG : existing: md5 = 802b608a29624a1826800192d22e4f3a OK 2022/02/10 05:00:55 INFO : existing: Copied (new) 2022/02/10 05:00:55 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:55 DEBUG : existing: Destination exists, skipping 2022/02/10 05:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:55 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:55 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreExisting (0.96s) === RUN TestSyncIgnoreErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:00:56 DEBUG : b/potato: md5 = 318c8d9eb8d78540fd6e25f6bc4565f1 OK 2022/02/10 05:00:57 DEBUG : c/non empty space: md5 = cd8b48a683851cdffbd1f598756f4db6 OK 2022/02/10 05:00:57 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/02/10 05:00:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:00:57 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:00:57 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:00:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:00:58 DEBUG : a/potato2: md5 = 00f3c921eeb5ffe90149486c2ed56fcf OK 2022/02/10 05:00:58 INFO : a/potato2: Copied (new) 2022/02/10 05:00:58 DEBUG : Waiting for deletions to finish 2022/02/10 05:00:58 INFO : b/potato: Deleted --- PASS: TestSyncIgnoreErrors (3.93s) === RUN TestSyncAfterChangingModtimeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:00 DEBUG : empty space: md5 = f312f81f57c173cada3654f546816ed1 OK 2022/02/10 05:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:00 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/02/10 05:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:00 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2022/02/10 05:01:00 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:00 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/02/10 05:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:01 DEBUG : empty space: md5 = 8621cf83774ce565a003c915c4fe4ee0 OK 2022/02/10 05:01:01 INFO : empty space: Copied (replaced existing) 2022/02/10 05:01:01 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingModtimeOnly (2.08s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" sync_test.go:577: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.08s) === RUN TestSyncDoesntUpdateModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:02 DEBUG : foo: md5 = f7ba4ab31100f24ef8c079044b78da4f OK 2022/02/10 05:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:02 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/02/10 05:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:03 DEBUG : foo: md5 = d1ed5031593d2575eceda5013924677b OK 2022/02/10 05:01:03 INFO : foo: Copied (replaced existing) 2022/02/10 05:01:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncDoesntUpdateModtime (1.79s) === RUN TestSyncAfterAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:04 DEBUG : empty space: md5 = 850f3057336b1387f8ef528bafcef74c OK 2022/02/10 05:01:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:04 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:05 DEBUG : potato: md5 = bb9256cb66d77d77de4883c68535ccc0 OK 2022/02/10 05:01:05 INFO : potato: Copied (new) 2022/02/10 05:01:05 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterAddingAFile (2.91s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:07 DEBUG : potato: md5 = 5e2cf612a3608d3fce67217daeaafe9e OK 2022/02/10 05:01:07 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2022/02/10 05:01:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:07 DEBUG : potato: md5 = 7964396f3d49801ea305382222261a2a OK 2022/02/10 05:01:07 INFO : potato: Copied (replaced existing) 2022/02/10 05:01:07 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingFilesSizeOnly (1.71s) === RUN TestSyncAfterChangingContentsOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:08 DEBUG : potato: md5 = 3321963c65bba7953f611e633384412c OK 2022/02/10 05:01:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:09 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/02/10 05:01:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:09 DEBUG : potato: md5 = 85b6d2d2727a70b769035aafdaff791e OK 2022/02/10 05:01:09 INFO : potato: Copied (replaced existing) 2022/02/10 05:01:09 DEBUG : Waiting for deletions to finish --- PASS: TestSyncAfterChangingContentsOnly (1.74s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:10 DEBUG : potato: md5 = ec6a9016b66b779d45020c9721245c2e OK 2022/02/10 05:01:10 DEBUG : empty space: md5 = 2c63e271e7f7414b0537c6da8ed78a10 OK 2022/02/10 05:01:10 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2022/02/10 05:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:11 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:11 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:11 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:11 NOTICE: potato: Skipped delete as --dry-run is set (size 21) --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.95s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:12 DEBUG : potato: md5 = 81be8a1da779c59e77120c435f7d3cd3 OK 2022/02/10 05:01:13 DEBUG : empty space: md5 = a35d3274d9e85715c3c61c0a1831ee1b OK 2022/02/10 05:01:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:14 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:14 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:14 DEBUG : potato2: md5 = 24cac892055b76a071b60df61cf7e773 OK 2022/02/10 05:01:14 INFO : potato2: Copied (new) 2022/02/10 05:01:14 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:14 INFO : potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (3.88s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:16 DEBUG : b/potato: md5 = 3c6196755b2775b4eadbaf714f569122 OK 2022/02/10 05:01:16 DEBUG : c/non empty space: md5 = 579f4a98c913f3f3255aa8419c4978e4 OK 2022/02/10 05:01:16 DEBUG : d: Making directory 2022/02/10 05:01:16 DEBUG : d/e: Making directory fstest.go:251: Filtering empty directory "d" fstest.go:251: Filtering empty directory "d/e" 2022/02/10 05:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:17 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:17 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:17 DEBUG : a/potato2: md5 = 2d071e1f997f6a62d19af39b5cd3ffad OK 2022/02/10 05:01:17 INFO : a/potato2: Copied (new) 2022/02/10 05:01:17 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:17 INFO : b/potato: Deleted --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.70s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:19 DEBUG : b/potato: md5 = c853d4e1e7b231ea92d9c9a257fe5308 OK 2022/02/10 05:01:20 DEBUG : c/non empty space: md5 = 98bc04c6f19d231782a63d69a299aff2 OK 2022/02/10 05:01:20 DEBUG : d: Making directory fstest.go:251: Filtering empty directory "d" 2022/02/10 05:01:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:20 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:20 DEBUG : c/non empty space: Unchanged skipping 2022/02/10 05:01:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:21 DEBUG : a/potato2: md5 = a6fbcd7db84e010bf7b6b04f786907f1 OK 2022/02/10 05:01:21 INFO : a/potato2: Copied (new) 2022/02/10 05:01:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': not deleting files as there were IO errors 2022/02/10 05:01:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': not deleting directories as there were IO errors fstest.go:251: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (3.86s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:22 DEBUG : potato: md5 = 54dbf56ec0628d9f4b5530d2b4ead1c4 OK 2022/02/10 05:01:23 DEBUG : empty space: md5 = d4d6ebcafc5e343e7d5c8b1f73252a8a OK 2022/02/10 05:01:23 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:23 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:23 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:24 DEBUG : potato2: md5 = 3c86ebba6346e7537f2983a25f92c791 OK 2022/02/10 05:01:24 INFO : potato2: Copied (new) 2022/02/10 05:01:24 INFO : potato: Deleted --- PASS: TestSyncDeleteDuring (4.05s) === RUN TestSyncDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:26 DEBUG : potato: md5 = 9b6f850f15fda6743ddd9bc4ed25ddd5 OK 2022/02/10 05:01:27 DEBUG : empty space: md5 = c7ecc565e792ee93bd94f98511a6e134 OK 2022/02/10 05:01:27 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:28 INFO : potato: Deleted 2022/02/10 05:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:28 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:28 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:28 DEBUG : potato2: md5 = 252e461d735a6fb1d343bfe0643a6f5b OK 2022/02/10 05:01:28 INFO : potato2: Copied (new) --- PASS: TestSyncDeleteBefore (3.09s) === RUN TestCopyDeleteBefore run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:30 DEBUG : potato: md5 = 351d08607ce4faf2100cc2a69e25c7bb OK 2022/02/10 05:01:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:30 DEBUG : potato2: md5 = 35c81aa26754c0bae7d5b4265ad31ce7 OK 2022/02/10 05:01:30 INFO : potato2: Copied (new) --- PASS: TestCopyDeleteBefore (3.18s) === RUN TestSyncWithExclude run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:33 DEBUG : potato2: md5 = 4b51190962ae07155d80af764844281f OK 2022/02/10 05:01:33 DEBUG : empty space: md5 = 3255c5808c953d0c8c06ee4e838c0b06 OK 2022/02/10 05:01:34 DEBUG : enormous: Excluded 2022/02/10 05:01:34 DEBUG : potato2: Excluded 2022/02/10 05:01:34 DEBUG : potato2: Excluded 2022/02/10 05:01:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:34 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:34 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:34 INFO : There was nothing to transfer 2022/02/10 05:01:34 DEBUG : enormous: Excluded 2022/02/10 05:01:34 DEBUG : potato2: Excluded 2022/02/10 05:01:34 DEBUG : potato2: Excluded 2022/02/10 05:01:34 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for checks to finish 2022/02/10 05:01:34 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:34 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:34 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for transfers to finish 2022/02/10 05:01:34 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:34 INFO : There was nothing to transfer --- PASS: TestSyncWithExclude (2.51s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:35 DEBUG : potato2: md5 = 24ffd1723a0b4524d49ca0d1fa13788e OK 2022/02/10 05:01:36 DEBUG : empty space: md5 = 7c4dde5089ece0f873dfac18aa5c698a OK 2022/02/10 05:01:37 DEBUG : enormous: md5 = 108d44168142c0163c2a5cf570de1c76 OK 2022/02/10 05:01:37 DEBUG : enormous: Excluded 2022/02/10 05:01:37 DEBUG : potato2: Excluded 2022/02/10 05:01:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:37 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:37 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:37 INFO : enormous: Deleted 2022/02/10 05:01:37 INFO : potato2: Deleted 2022/02/10 05:01:37 INFO : There was nothing to transfer 2022/02/10 05:01:37 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for checks to finish 2022/02/10 05:01:37 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:37 DEBUG : empty space: Unchanged skipping 2022/02/10 05:01:37 DEBUG : Local file system at /tmp/rclone1070390538: Waiting for transfers to finish 2022/02/10 05:01:37 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:37 INFO : potato2: Deleted 2022/02/10 05:01:37 INFO : enormous: Deleted 2022/02/10 05:01:37 INFO : There was nothing to transfer --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.77s) === RUN TestSyncWithUpdateOlder run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:39 DEBUG : one: md5 = 6b6537fa389f1c02b1de3bcfd60f1efc OK 2022/02/10 05:01:39 DEBUG : two: md5 = 7f16e9d9d808a8ac2d20a8ac99e279b3 OK 2022/02/10 05:01:40 DEBUG : three: md5 = 7cd9bc1e795c0f07d475884af5cc77c9 OK 2022/02/10 05:01:40 DEBUG : four: md5 = 786d8fbd703bb2ae77be7e9247b0b823 OK 2022/02/10 05:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:41 DEBUG : one: Destination is newer than source, skipping 2022/02/10 05:01:41 DEBUG : four: Sizes differ (src 4 vs dst 8) 2022/02/10 05:01:41 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2022/02/10 05:01:41 DEBUG : three: Sizes identical 2022/02/10 05:01:41 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2022/02/10 05:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:41 DEBUG : two: md5 = 40a1aa340b9fb369b3283eebc494c687 OK 2022/02/10 05:01:41 INFO : two: Copied (replaced existing) 2022/02/10 05:01:41 DEBUG : four: md5 = fa72f32fdcc80d2440f2f104f89a0e57 OK 2022/02/10 05:01:41 INFO : four: Copied (replaced existing) 2022/02/10 05:01:42 DEBUG : five: md5 = 38ea6b34043ee7c066f8a02b8c3a7b40 OK 2022/02/10 05:01:42 INFO : five: Copied (new) 2022/02/10 05:01:42 DEBUG : Waiting for deletions to finish sync_test.go:992: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (5.76s) === 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-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" sync_test.go:1058: Can track renames: false 2022/02/10 05:01:43 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Ignoring --track-renames as the source and destination do not have a common hash 2022/02/10 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:44 DEBUG : potato: md5 = 3101bfccb18353db5732477d12706f9a OK 2022/02/10 05:01:44 INFO : potato: Copied (new) 2022/02/10 05:01:44 DEBUG : yam: md5 = de9ea1abdf2cefc58f8b7d55ad773391 OK 2022/02/10 05:01:44 INFO : yam: Copied (new) 2022/02/10 05:01:44 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:45 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Ignoring --track-renames as the source and destination do not have a common hash 2022/02/10 05:01:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:45 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:45 DEBUG : potato: Unchanged skipping 2022/02/10 05:01:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:45 DEBUG : yaml: md5 = d866533f477cc0fccf08c052811c033c OK 2022/02/10 05:01:45 INFO : yaml: Copied (new) 2022/02/10 05:01:45 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:46 INFO : yam: Deleted --- PASS: TestSyncWithTrackRenames (3.28s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" sync_test.go:1127: Can track renames: true 2022/02/10 05:01:47 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Making map for --track-renames 2022/02/10 05:01:47 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Finished making map for --track-renames 2022/02/10 05:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for renames to finish 2022/02/10 05:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:47 DEBUG : yam: md5 = 4d08fabab5707931680bffd2706d288b OK 2022/02/10 05:01:47 INFO : yam: Copied (new) 2022/02/10 05:01:47 DEBUG : potato: md5 = 987c4e7017c28b925b58aebe44b363e3 OK 2022/02/10 05:01:47 INFO : potato: Copied (new) 2022/02/10 05:01:47 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:48 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Making map for --track-renames 2022/02/10 05:01:48 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Finished making map for --track-renames 2022/02/10 05:01:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:48 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:48 DEBUG : potato: Unchanged skipping 2022/02/10 05:01:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for renames to finish 2022/02/10 05:01:48 INFO : yam: Copied (server-side copy) to: yaml 2022/02/10 05:01:48 INFO : yam: Deleted 2022/02/10 05:01:48 INFO : yaml: Renamed from "yam" 2022/02/10 05:01:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:48 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.42s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" sync_test.go:1163: Can track renames: true 2022/02/10 05:01:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Making map for --track-renames 2022/02/10 05:01:49 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Finished making map for --track-renames 2022/02/10 05:01:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for renames to finish 2022/02/10 05:01:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:50 DEBUG : sub/yam: md5 = 20c54f74b67cd41d228c2ddbe664e256 OK 2022/02/10 05:01:50 INFO : sub/yam: Copied (new) 2022/02/10 05:01:50 DEBUG : potato: md5 = 7d1b7e5fedeae59855e7bf78feb6d623 OK 2022/02/10 05:01:50 INFO : potato: Copied (new) 2022/02/10 05:01:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:01:50 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:01:50 DEBUG : potato: Unchanged skipping 2022/02/10 05:01:50 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Making map for --track-renames 2022/02/10 05:01:50 INFO : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Finished making map for --track-renames 2022/02/10 05:01:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for renames to finish 2022/02/10 05:01:51 INFO : sub/yam: Copied (server-side copy) to: yam 2022/02/10 05:01:52 INFO : sub/yam: Deleted 2022/02/10 05:01:52 INFO : yam: Renamed from "sub/yam" 2022/02/10 05:01:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:52 DEBUG : Waiting for deletions to finish --- PASS: TestSyncWithTrackRenamesStrategyLeaf (3.48s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:53 DEBUG : nested/sub dir/file: md5 = 95787ffdedd5b12b739b9e82af058bf0 OK 2022/02/10 05:01:53 INFO : nested/sub dir/file: Copied (new) 2022/02/10 05:01:53 INFO : nested/sub dir/file: Deleted 2022/02/10 05:01:53 DEBUG : sub dir/hello world: md5 = e6b78ab6fa964d4d8703e104a01b7f0d OK 2022/02/10 05:01:53 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:01:53 INFO : sub dir/hello world: Deleted 2022/02/10 05:01:53 INFO : sub dir: Removing directory 2022/02/10 05:01:53 INFO : nested/sub dir: Removing directory 2022/02/10 05:01:53 INFO : nested: Removing directory 2022/02/10 05:01:53 DEBUG : Local file system at /tmp/rclone1070390538: deleted 3 directories --- PASS: TestMoveWithDeleteEmptySrcDirs (1.88s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:55 DEBUG : nested/sub dir/file: md5 = 7a783ce55541f94dc16fa824f920abab OK 2022/02/10 05:01:55 INFO : nested/sub dir/file: Copied (new) 2022/02/10 05:01:55 INFO : nested/sub dir/file: Deleted 2022/02/10 05:01:55 DEBUG : sub dir/hello world: md5 = 0cc297411a4f04c522882443b631c5e1 OK 2022/02/10 05:01:55 INFO : sub dir/hello world: Copied (new) 2022/02/10 05:01:55 INFO : sub dir/hello world: Deleted --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.92s) === RUN TestMoveWithIgnoreExisting run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:57 DEBUG : existing: md5 = c00cba6982e07c1eff474f18824730c3 OK 2022/02/10 05:01:57 INFO : existing: Copied (new) 2022/02/10 05:01:57 INFO : existing: Deleted 2022/02/10 05:01:57 DEBUG : existing-b: md5 = fb3428127983eb5895ab46d54f4e5f38 OK 2022/02/10 05:01:57 INFO : existing-b: Copied (new) 2022/02/10 05:01:57 INFO : existing-b: Deleted 2022/02/10 05:01:57 DEBUG : existing: Destination exists, skipping 2022/02/10 05:01:57 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2022/02/10 05:01:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:01:57 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:01:57 INFO : There was nothing to transfer --- PASS: TestMoveWithIgnoreExisting (2.16s) === RUN TestServerSideMove run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:01:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-gugipez7dilozon5dizomuc2" 2022/02/10 05:01:59 DEBUG : Config file has changed externaly - reloading 2022/02/10 05:01:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gugipez7dilozon5dizomuc2.bin" 2022/02/10 05:01:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-gugipez7dilozon5dizomuc2" 2022/02/10 05:02:01 DEBUG : potato2: md5 = 47c2cb70eabb25a49f0a72f3674afbe2 OK 2022/02/10 05:02:02 DEBUG : empty space: md5 = 97ba73bace61f77488057d530a8eb9bc OK 2022/02/10 05:02:03 DEBUG : potato3: md5 = 2236164eeea229991698f769137d282a OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0' -> Encrypted drive 'TestCryptSwift:rclone-test-gugipez7dilozon5dizomuc2' 2022/02/10 05:02:04 DEBUG : empty space: md5 = 8db7007d8cf2fd7ff6b54f843c7c1917 OK 2022/02/10 05:02:05 DEBUG : potato3: md5 = 673b7f303125327a03394d081e2b7df2 OK 2022/02/10 05:02:05 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gugipez7dilozon5dizomuc2': Waiting for checks to finish 2022/02/10 05:02:05 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:05 DEBUG : empty space: Unchanged skipping 2022/02/10 05:02:06 INFO : empty space: Deleted 2022/02/10 05:02:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gugipez7dilozon5dizomuc2': Waiting for transfers to finish 2022/02/10 05:02:06 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:06 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:06 INFO : potato3: Deleted 2022/02/10 05:02:06 INFO : potato2: Deleted 2022/02/10 05:02:07 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-woqusih3zocalal7vohisog1" 2022/02/10 05:02:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-woqusih3zocalal7vohisog1.bin" 2022/02/10 05:02:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-woqusih3zocalal7vohisog1" 2022/02/10 05:02:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woqusih3zocalal7vohisog1': Waiting for checks to finish 2022/02/10 05:02:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woqusih3zocalal7vohisog1': Waiting for transfers to finish 2022/02/10 05:02:09 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:09 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:09 INFO : empty space: Copied (server-side copy) 2022/02/10 05:02:09 INFO : potato2: Deleted 2022/02/10 05:02:09 INFO : empty space: Deleted 2022/02/10 05:02:09 INFO : potato3: Deleted 2022/02/10 05:02:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-woqusih3zocalal7vohisog1': Purge remote 2022/02/10 05:02:10 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:10 INFO : potato2.bin: Deleted 2022/02/10 05:02:10 INFO : potato3.bin: Deleted 2022/02/10 05:02:10 INFO : empty space.bin: Deleted 2022/02/10 05:02:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-gugipez7dilozon5dizomuc2': Purge remote 2022/02/10 05:02:10 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (11.75s) === RUN TestServerSideMoveWithFilter run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-homonah6nizorid1vosuduv5" 2022/02/10 05:02:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-homonah6nizorid1vosuduv5.bin" 2022/02/10 05:02:11 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-homonah6nizorid1vosuduv5" 2022/02/10 05:02:12 DEBUG : potato2: md5 = d7972e578715c6420cf29645af992a7c OK 2022/02/10 05:02:12 DEBUG : empty space: md5 = 9e8887250ad87c26ef1ad00867cf5209 OK 2022/02/10 05:02:13 DEBUG : potato3: md5 = 81099e4bdd96f85ac1a1e6d7ad7d40af OK sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0' -> Encrypted drive 'TestCryptSwift:rclone-test-homonah6nizorid1vosuduv5' 2022/02/10 05:02:14 DEBUG : empty space: md5 = 46515bd983b96ceb875d9c4612fbe5d4 OK 2022/02/10 05:02:15 DEBUG : potato3: md5 = 590d9b86e810e501fc562fddb5f94eeb OK 2022/02/10 05:02:15 DEBUG : empty space: Excluded 2022/02/10 05:02:15 DEBUG : empty space: Excluded 2022/02/10 05:02:15 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homonah6nizorid1vosuduv5': Waiting for checks to finish 2022/02/10 05:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homonah6nizorid1vosuduv5': Waiting for transfers to finish 2022/02/10 05:02:15 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:15 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:16 INFO : potato3: Deleted 2022/02/10 05:02:16 INFO : potato2: Deleted 2022/02/10 05:02:16 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-kogokuk3gugewuq2xezevuy0" 2022/02/10 05:02:16 DEBUG : Config file has changed externaly - reloading 2022/02/10 05:02:16 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kogokuk3gugewuq2xezevuy0.bin" 2022/02/10 05:02:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-kogokuk3gugewuq2xezevuy0" 2022/02/10 05:02:17 DEBUG : empty space: Excluded 2022/02/10 05:02:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kogokuk3gugewuq2xezevuy0': Waiting for checks to finish 2022/02/10 05:02:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kogokuk3gugewuq2xezevuy0': Waiting for transfers to finish 2022/02/10 05:02:18 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:18 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:18 INFO : potato3: Deleted 2022/02/10 05:02:19 INFO : potato2: Deleted 2022/02/10 05:02:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-kogokuk3gugewuq2xezevuy0': Purge remote 2022/02/10 05:02:19 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:19 INFO : potato3.bin: Deleted 2022/02/10 05:02:19 INFO : potato2.bin: Deleted 2022/02/10 05:02:19 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-homonah6nizorid1vosuduv5': Purge remote 2022/02/10 05:02:19 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:20 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (10.29s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:21 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wutokuk9juleqon8vaqeqow4" 2022/02/10 05:02:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wutokuk9juleqon8vaqeqow4.bin" 2022/02/10 05:02:21 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wutokuk9juleqon8vaqeqow4" 2022/02/10 05:02:23 DEBUG : potato2: md5 = b84171af7a8eca6243e0858736b50bd3 OK 2022/02/10 05:02:24 DEBUG : empty space: md5 = 45d85a7c45c579fe3cf504a7e8f4dcb5 OK 2022/02/10 05:02:24 DEBUG : potato3: md5 = d90f823a3075456898605bcaf98cfa1f OK 2022/02/10 05:02:24 DEBUG : tomatoDir: Making directory sync_test.go:1215: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0' -> Encrypted drive 'TestCryptSwift:rclone-test-wutokuk9juleqon8vaqeqow4' 2022/02/10 05:02:25 DEBUG : empty space: md5 = 2b85b2857f944dd3ad91ffb22352a132 OK 2022/02/10 05:02:26 DEBUG : potato3: md5 = 3ff276e36d382a2e0db62fc5d295b2fb OK 2022/02/10 05:02:27 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2022/02/10 05:02:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wutokuk9juleqon8vaqeqow4': Waiting for checks to finish 2022/02/10 05:02:27 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:27 DEBUG : empty space: Unchanged skipping 2022/02/10 05:02:27 INFO : empty space: Deleted 2022/02/10 05:02:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wutokuk9juleqon8vaqeqow4': Waiting for transfers to finish 2022/02/10 05:02:27 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:27 INFO : potato3: Deleted 2022/02/10 05:02:32 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:32 INFO : potato2: Deleted 2022/02/10 05:02:33 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jogaqub0xadalek2kovigep9" 2022/02/10 05:02:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jogaqub0xadalek2kovigep9.bin" 2022/02/10 05:02:33 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jogaqub0xadalek2kovigep9" 2022/02/10 05:02:34 DEBUG : tomatoDir: Making directory 2022/02/10 05:02:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jogaqub0xadalek2kovigep9': Waiting for checks to finish 2022/02/10 05:02:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jogaqub0xadalek2kovigep9': Waiting for transfers to finish 2022/02/10 05:02:35 INFO : empty space: Copied (server-side copy) 2022/02/10 05:02:35 INFO : potato3: Copied (server-side copy) 2022/02/10 05:02:35 INFO : potato3: Deleted 2022/02/10 05:02:35 INFO : potato2: Copied (server-side copy) 2022/02/10 05:02:35 INFO : empty space: Deleted 2022/02/10 05:02:40 INFO : potato2: Deleted 2022/02/10 05:02:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jogaqub0xadalek2kovigep9': Purge remote 2022/02/10 05:02:41 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:41 INFO : empty space.bin: Deleted 2022/02/10 05:02:41 INFO : potato3.bin: Deleted 2022/02/10 05:02:42 INFO : potato2.bin: Deleted 2022/02/10 05:02:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wutokuk9juleqon8vaqeqow4': Purge remote 2022/02/10 05:02:42 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (21.69s) === RUN TestServerSideMoveOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/rclone-move-test" 2022/02/10 05:02:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/rclone-move-test.bin" 2022/02/10 05:02:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/rclone-move-test" 2022/02/10 05:02:44 DEBUG : potato2: md5 = 40d81dc1f17c2747690901fc5877493a OK --- PASS: TestServerSideMoveOverlap (2.69s) === RUN TestSyncOverlap run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:45 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/rclone-sync-test" 2022/02/10 05:02:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/rclone-sync-test.bin" 2022/02/10 05:02:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/rclone-sync-test" --- PASS: TestSyncOverlap (1.25s) === RUN TestSyncCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:02:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:02:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:02:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/CompareDest" 2022/02/10 05:02:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/CompareDest.bin" 2022/02/10 05:02:48 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/CompareDest" 2022/02/10 05:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:49 DEBUG : one: md5 = 73c4501d9d811be656f9aa245e8c095d OK 2022/02/10 05:02:49 INFO : one: Copied (new) 2022/02/10 05:02:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:49 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:02:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:50 DEBUG : one: md5 = b18d10506f0f3bd476369b36b7c2238f OK 2022/02/10 05:02:50 INFO : one: Copied (replaced existing) 2022/02/10 05:02:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:50 DEBUG : dst/one: md5 = 3346ba4694d5cba2116127e1d177f9b6 OK 2022/02/10 05:02:51 DEBUG : CompareDest/one: md5 = 461cfadc8cb12b8bb360499bad17403a OK 2022/02/10 05:02:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:51 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:51 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:02:51 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:51 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:51 INFO : There was nothing to transfer 2022/02/10 05:02:52 DEBUG : CompareDest/two: md5 = cacece66ecc71e6086ddaddbd57da3c6 OK 2022/02/10 05:02:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:53 DEBUG : two: Destination found in --compare-dest, skipping 2022/02/10 05:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:53 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:53 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:53 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:53 INFO : There was nothing to transfer 2022/02/10 05:02:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:53 DEBUG : two: Destination found in --compare-dest, skipping 2022/02/10 05:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:53 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:53 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:53 DEBUG : Waiting for deletions to finish 2022/02/10 05:02:53 INFO : There was nothing to transfer sync_test.go:1543: No hash on uploaded file so skipping compare timestamp test 2022/02/10 05:02:54 DEBUG : two: Sizes differ (src 5 vs dst 3) 2022/02/10 05:02:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:02:54 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:02:54 DEBUG : one: Destination found in --compare-dest, skipping 2022/02/10 05:02:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:02:54 DEBUG : two: md5 = 6e0d640f0d4cc26fdde313f5006d2bbe OK 2022/02/10 05:02:54 INFO : two: Copied (new) 2022/02/10 05:02:54 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (9.91s) === RUN TestSyncMultipleCompareDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:02:57 DEBUG : pre-dest1/1: md5 = 16eadf159cf9d5833cb9eb93582cf944 OK 2022/02/10 05:02:58 DEBUG : pre-dest2/2: md5 = cfb3d93de837f1346d645d4100bfde02 OK 2022/02/10 05:02:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dest" 2022/02/10 05:02:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dest.bin" 2022/02/10 05:02:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dest" 2022/02/10 05:02:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/pre-dest1" 2022/02/10 05:02:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/pre-dest1.bin" 2022/02/10 05:03:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/pre-dest1" 2022/02/10 05:03:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/pre-dest2" 2022/02/10 05:03:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/pre-dest2.bin" 2022/02/10 05:03:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/pre-dest2" 2022/02/10 05:03:01 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:01 DEBUG : 1: Destination found in --compare-dest, skipping 2022/02/10 05:03:01 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:01 DEBUG : 2: Destination found in --compare-dest, skipping 2022/02/10 05:03:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dest': Waiting for checks to finish 2022/02/10 05:03:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dest': Waiting for transfers to finish 2022/02/10 05:03:03 DEBUG : 3: md5 = 683c4e1605a2ed6d2adbc36ab79a7d11 OK 2022/02/10 05:03:03 INFO : 3: Copied (new) 2022/02/10 05:03:03 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (7.84s) === RUN TestSyncCopyDest run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:03:04 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:03:04 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:05 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/CopyDest" 2022/02/10 05:03:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/CopyDest.bin" 2022/02/10 05:03:05 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/CopyDest" 2022/02/10 05:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:07 DEBUG : one: md5 = 9cf390652768f66b7889d73bb9e59602 OK 2022/02/10 05:03:07 INFO : one: Copied (new) 2022/02/10 05:03:07 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:07 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:03:07 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:08 DEBUG : one: md5 = e43d3d80c07d8b4274531c0344ee4dc2 OK 2022/02/10 05:03:08 INFO : one: Copied (replaced existing) 2022/02/10 05:03:08 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:08 DEBUG : dst/one: md5 = a3dd8d016a92b4f280574d4e2ffb5372 OK 2022/02/10 05:03:09 DEBUG : CopyDest/one: md5 = 008365bb566b4a849e4a35a43e133543 OK 2022/02/10 05:03:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/BackupDir" 2022/02/10 05:03:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/BackupDir.bin" 2022/02/10 05:03:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/BackupDir" 2022/02/10 05:03:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:10 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:10 DEBUG : one: Sizes differ (src 5 vs dst 3) 2022/02/10 05:03:11 INFO : one: Copied (server-side copy) 2022/02/10 05:03:11 INFO : one: Deleted 2022/02/10 05:03:11 INFO : one: Copied (server-side copy) 2022/02/10 05:03:11 DEBUG : one: Destination found in --copy-dest, using server-side copy 2022/02/10 05:03:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:11 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:12 DEBUG : CopyDest/two: md5 = 37af65c461393220d4d2a09333b9f498 OK 2022/02/10 05:03:13 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:13 INFO : two: Copied (server-side copy) 2022/02/10 05:03:13 DEBUG : two: Destination found in --copy-dest, using server-side copy 2022/02/10 05:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:13 DEBUG : one: Unchanged skipping 2022/02/10 05:03:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:13 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:14 DEBUG : one: Unchanged skipping 2022/02/10 05:03:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:14 DEBUG : two: Unchanged skipping 2022/02/10 05:03:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:14 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:14 INFO : There was nothing to transfer 2022/02/10 05:03:15 DEBUG : CopyDest/three: md5 = 0febe40fc86ca9e2ab9896a3aa7116b8 OK 2022/02/10 05:03:15 DEBUG : three: Sizes differ (src 7 vs dst 5) 2022/02/10 05:03:15 DEBUG : three: Destination not found in --copy-dest 2022/02/10 05:03:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:16 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:16 DEBUG : two: Unchanged skipping 2022/02/10 05:03:16 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:16 DEBUG : one: Unchanged skipping 2022/02/10 05:03:16 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:16 DEBUG : three: md5 = 9487a7f7b54eaca5ff0a10659be77cc0 OK 2022/02/10 05:03:16 INFO : three: Copied (new) 2022/02/10 05:03:16 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (15.90s) === RUN TestSyncBackupDir run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:03:20 DEBUG : dst/one: md5 = aa5e8f7c8dd10faf9c0001d887f5893c OK 2022/02/10 05:03:21 DEBUG : dst/two: md5 = a44c377192c830f774575be836062625 OK 2022/02/10 05:03:22 DEBUG : dst/three.txt: md5 = e6902c458ad4331cde549583c2bfe544 OK 2022/02/10 05:03:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:03:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup.bin" 2022/02/10 05:03:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:24 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:03:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:24 DEBUG : two: Unchanged skipping 2022/02/10 05:03:25 INFO : one: Copied (server-side copy) 2022/02/10 05:03:25 INFO : one: Deleted 2022/02/10 05:03:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:25 DEBUG : one: md5 = f65ababdffa28aba6432add6e64dd340 OK 2022/02/10 05:03:25 INFO : one: Copied (new) 2022/02/10 05:03:25 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:26 INFO : three.txt: Copied (server-side copy) 2022/02/10 05:03:26 INFO : three.txt: Deleted 2022/02/10 05:03:26 INFO : three.txt: Moved into backup dir 2022/02/10 05:03:27 DEBUG : dst/three.txt: md5 = 7f326ae3a7b51097d4ebb0d3698738b1 OK 2022/02/10 05:03:27 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:03:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:27 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:27 DEBUG : two: Unchanged skipping 2022/02/10 05:03:28 INFO : one: Copied (server-side copy) 2022/02/10 05:03:28 INFO : one: Deleted 2022/02/10 05:03:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:29 DEBUG : one: md5 = 51b0c07f5c7bd747684368adb8290341 OK 2022/02/10 05:03:29 INFO : one: Copied (new) 2022/02/10 05:03:29 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:29 INFO : three.txt: Copied (server-side copy) 2022/02/10 05:03:30 INFO : three.txt: Deleted 2022/02/10 05:03:30 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (11.68s) === RUN TestSyncBackupDirWithSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:03:32 DEBUG : dst/one: md5 = e9d3a827b8d43948ae85d67109f65ce1 OK 2022/02/10 05:03:33 DEBUG : dst/two: md5 = 8b51ae745b2ce6501004f343c717fa42 OK 2022/02/10 05:03:34 DEBUG : dst/three.txt: md5 = ba7a3b4dab9ee0e9de5e2de9f3b09909 OK 2022/02/10 05:03:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:03:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup.bin" 2022/02/10 05:03:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:36 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:03:36 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:36 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:36 DEBUG : two: Unchanged skipping 2022/02/10 05:03:37 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:03:37 INFO : one: Deleted 2022/02/10 05:03:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:37 DEBUG : one: md5 = a2de0534e48fcf4a29191bbb5f2dccc3 OK 2022/02/10 05:03:37 INFO : one: Copied (new) 2022/02/10 05:03:37 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:39 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:03:39 INFO : three.txt: Deleted 2022/02/10 05:03:39 INFO : three.txt: Moved into backup dir 2022/02/10 05:03:39 DEBUG : dst/three.txt: md5 = 5212a285a7f903cebf05038daa4d93cf OK 2022/02/10 05:03:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:03:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:40 DEBUG : two: Unchanged skipping 2022/02/10 05:03:40 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:03:41 INFO : one: Deleted 2022/02/10 05:03:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:41 DEBUG : one: md5 = baf0b198a1e9333f71bd65e14dc0c6b9 OK 2022/02/10 05:03:41 INFO : one: Copied (new) 2022/02/10 05:03:41 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:42 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:03:42 INFO : three.txt: Deleted 2022/02/10 05:03:42 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (12.54s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:03:44 DEBUG : dst/one: md5 = d2b880e7b594d6a157c7326dc6121256 OK 2022/02/10 05:03:45 DEBUG : dst/two: md5 = b0dad70a5e022abece8ba5de33b71744 OK 2022/02/10 05:03:46 DEBUG : dst/three.txt: md5 = 76e188d2dc1da8e36f398b56e4a49ddf OK 2022/02/10 05:03:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:03:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:03:47 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup.bin" 2022/02/10 05:03:47 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/backup" 2022/02/10 05:03:48 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:03:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:48 DEBUG : two: Unchanged skipping 2022/02/10 05:03:50 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:03:50 INFO : one: Deleted 2022/02/10 05:03:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:50 DEBUG : one: md5 = 5ba5b4dc6ad757afc00fafdbb03f60ab OK 2022/02/10 05:03:50 INFO : one: Copied (new) 2022/02/10 05:03:50 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:52 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:03:52 INFO : three.txt: Deleted 2022/02/10 05:03:52 INFO : three.txt: Moved into backup dir 2022/02/10 05:03:53 DEBUG : dst/three.txt: md5 = 85a787c40ec72e0dc65cdd7d6a8afd09 OK 2022/02/10 05:03:54 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:03:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:03:54 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:03:54 DEBUG : two: Unchanged skipping 2022/02/10 05:03:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:03:56 INFO : one: Deleted 2022/02/10 05:03:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:03:57 DEBUG : one: md5 = 6417e5be2bdaa20c93d24c5987efce43 OK 2022/02/10 05:03:57 INFO : one: Copied (new) 2022/02/10 05:03:57 DEBUG : Waiting for deletions to finish 2022/02/10 05:03:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:03:58 INFO : three.txt: Deleted 2022/02/10 05:03:58 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (15.64s) === RUN TestSyncBackupDirSuffixOnly run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:00 DEBUG : dst/one: md5 = a5e5ffb656eafee08cd6165b02c73865 OK 2022/02/10 05:04:01 DEBUG : dst/two: md5 = b4bd6fb15196d5d3f87aff8f9e47443b OK 2022/02/10 05:04:01 DEBUG : dst/three.txt: md5 = 1ad37b1756e51d3cdb71f117d3f17b0c OK 2022/02/10 05:04:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:04:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:03 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:04:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:04:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:03 DEBUG : two: Unchanged skipping 2022/02/10 05:04:04 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:04:05 INFO : one: Deleted 2022/02/10 05:04:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:04:05 DEBUG : one: md5 = 59f1268ad87136746b1e08906a4ed049 OK 2022/02/10 05:04:05 INFO : one: Copied (new) 2022/02/10 05:04:05 DEBUG : Waiting for deletions to finish 2022/02/10 05:04:06 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:04:06 INFO : three.txt: Deleted 2022/02/10 05:04:06 INFO : three.txt: Moved into backup dir 2022/02/10 05:04:09 DEBUG : dst/three.txt: md5 = 398b0c3cf8d0d8f1b901db98a45139ab OK 2022/02/10 05:04:09 DEBUG : one.bak: Excluded 2022/02/10 05:04:09 DEBUG : three.txt.bak: Excluded 2022/02/10 05:04:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:04:09 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for checks to finish 2022/02/10 05:04:09 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:09 DEBUG : two: Unchanged skipping 2022/02/10 05:04:10 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:04:10 INFO : one: Deleted 2022/02/10 05:04:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst': Waiting for transfers to finish 2022/02/10 05:04:10 DEBUG : one: md5 = 9a7769980e5e5213646f3bd1ee76a542 OK 2022/02/10 05:04:10 INFO : one: Copied (new) 2022/02/10 05:04:10 DEBUG : Waiting for deletions to finish 2022/02/10 05:04:11 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:04:11 INFO : three.txt: Deleted 2022/02/10 05:04:11 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (14.46s) === RUN TestSyncSuffix run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:15 DEBUG : dst/one: md5 = 26673802c7850697d4380830ffcf9bbe OK 2022/02/10 05:04:16 DEBUG : dst/two: md5 = feddce87c390bfda8433b745fcae6930 OK 2022/02/10 05:04:17 DEBUG : dst/three.txt: md5 = 7c846c7020f20f6892d2b9443934e71e OK 2022/02/10 05:04:17 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:04:17 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:18 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:04:19 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:04:20 INFO : one: Deleted 2022/02/10 05:04:20 DEBUG : one: md5 = 2ae701f4cdaedb17ed9a1f56f6c5d394 OK 2022/02/10 05:04:20 INFO : one: Copied (new) 2022/02/10 05:04:20 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:20 DEBUG : two: Unchanged skipping 2022/02/10 05:04:20 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/02/10 05:04:21 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:04:21 INFO : three.txt: Deleted 2022/02/10 05:04:21 DEBUG : three.txt: md5 = 2b4224fc7d7f4aaff986e7892b1a4978 OK 2022/02/10 05:04:21 INFO : three.txt: Copied (new) 2022/02/10 05:04:22 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:04:22 INFO : one: Copied (server-side copy) to: one.bak 2022/02/10 05:04:23 INFO : one: Deleted 2022/02/10 05:04:24 DEBUG : one: md5 = f1a03e5c959294b14b7dfed0d6ef5e98 OK 2022/02/10 05:04:24 INFO : one: Copied (new) 2022/02/10 05:04:24 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:24 DEBUG : two: Unchanged skipping 2022/02/10 05:04:24 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/02/10 05:04:25 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2022/02/10 05:04:25 INFO : three.txt: Deleted 2022/02/10 05:04:26 DEBUG : three.txt: md5 = 3c8a7cd14b2a452afb3b8f0647b39672 OK 2022/02/10 05:04:26 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (13.56s) === RUN TestSyncSuffixKeepExtension run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:28 DEBUG : dst/one: md5 = 356e3887312499650bb7fad6efc9f364 OK 2022/02/10 05:04:29 DEBUG : dst/two: md5 = 261328ab731a94a7383495c5bb7253ef OK 2022/02/10 05:04:31 DEBUG : dst/three.txt: md5 = ecdcd6f0a734d911397d347fa7864076 OK 2022/02/10 05:04:31 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:31 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst.bin" 2022/02/10 05:04:32 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-wepinat6hikeyal3dukomor0/dst" 2022/02/10 05:04:32 DEBUG : one: Sizes differ (src 4 vs dst 3) 2022/02/10 05:04:34 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:04:34 INFO : one: Deleted 2022/02/10 05:04:35 DEBUG : one: md5 = 9ad0a5bb0a882bca8ec3cfd79ee9fa34 OK 2022/02/10 05:04:35 INFO : one: Copied (new) 2022/02/10 05:04:35 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:35 DEBUG : two: Unchanged skipping 2022/02/10 05:04:36 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2022/02/10 05:04:36 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:04:37 INFO : three.txt: Deleted 2022/02/10 05:04:38 DEBUG : three.txt: md5 = 5e221efbb838c3edd7727f2ce062c41c OK 2022/02/10 05:04:38 INFO : three.txt: Copied (new) 2022/02/10 05:04:39 DEBUG : one: Sizes differ (src 5 vs dst 4) 2022/02/10 05:04:39 INFO : one: Copied (server-side copy) to: one-2019-01-01 2022/02/10 05:04:40 INFO : one: Deleted 2022/02/10 05:04:41 DEBUG : one: md5 = 2b2c1085c76c0233bf6b5e694de441d9 OK 2022/02/10 05:04:41 INFO : one: Copied (new) 2022/02/10 05:04:41 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:41 DEBUG : two: Unchanged skipping 2022/02/10 05:04:41 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2022/02/10 05:04:41 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2022/02/10 05:04:42 INFO : three.txt: Deleted 2022/02/10 05:04:42 DEBUG : three.txt: md5 = 47c1a2c4191850a3540867c353f565d1 OK 2022/02/10 05:04:42 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (16.40s) === RUN TestSyncUTFNorm run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:45 DEBUG : Testêé: md5 = 2947bba136d863317e5fd8483a0be347 OK 2022/02/10 05:04:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:04:45 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2022/02/10 05:04:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:04:45 DEBUG : Testêé: md5 = 7969c725c5a40562e5515ec3ed93c347 OK 2022/02/10 05:04:45 INFO : Testêé: Copied (replaced existing) to: Testêé 2022/02/10 05:04:45 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (2.35s) === RUN TestSyncImmutable run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:04:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:04:47 DEBUG : existing: md5 = 9dcfa30c67f60b84b25ebd60a1e729ac OK 2022/02/10 05:04:47 INFO : existing: Copied (new) 2022/02/10 05:04:47 DEBUG : Waiting for deletions to finish 2022/02/10 05:04:48 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2022/02/10 05:04:48 ERROR : existing: Source and destination exist but do not match: immutable file modified 2022/02/10 05:04:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:04:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:04:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': not deleting files as there were IO errors 2022/02/10 05:04:48 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': not deleting directories as there were IO errors --- PASS: TestSyncImmutable (1.66s) === RUN TestSyncIgnoreCase run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:49 DEBUG : EXISTING: md5 = daecb3a9235e0a6e42604f7feb53b739 OK 2022/02/10 05:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:04:49 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:04:49 DEBUG : existing: Unchanged skipping 2022/02/10 05:04:49 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:04:49 DEBUG : Waiting for deletions to finish 2022/02/10 05:04:49 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.89s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", 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-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", 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-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" sync_test.go:1989: This test only runs on local --- PASS: TestMaxTransfer (0.52s) --- SKIP: TestMaxTransfer/Hard (0.08s) --- SKIP: TestMaxTransfer/Soft (0.36s) --- SKIP: TestMaxTransfer/Cautious (0.08s) === RUN TestSyncConcurrentDelete run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:04:51 DEBUG : both0: md5 = 54ef01d86f82af691d7c3dcab9fe8f86 OK 2022/02/10 05:04:52 DEBUG : only0: md5 = 98ee79b2168f9c0a2d7ff9dc85da0c8f OK 2022/02/10 05:04:53 DEBUG : both1: md5 = 5ab7f2841cb8789f3bc69030e6dfc37b OK 2022/02/10 05:05:13 DEBUG : only1: md5 = 8cccd1e56b477aab44ae239a6c63cdc5 OK 2022/02/10 05:05:15 DEBUG : both2: md5 = c51fb003f1ca15b52ac56e6c66ab50b0 OK 2022/02/10 05:05:16 DEBUG : only2: md5 = e83390bc07d677343a3027f4d0797c8e OK 2022/02/10 05:05:17 DEBUG : both3: md5 = a2f3ec93b6ac5c84448b2ae9671f7425 OK 2022/02/10 05:05:17 DEBUG : only3: md5 = 9a255579f2e79c4a3b2d806d904a579d OK 2022/02/10 05:05:22 DEBUG : both4: md5 = 8cca71d76f17155407a1e66bc1200ce6 OK 2022/02/10 05:05:26 DEBUG : only4: md5 = d65166c912f9f2e203e39aab44d17aae OK 2022/02/10 05:05:47 DEBUG : both5: md5 = e0dc9956aabfe6c75e4d64e5b298bb39 OK 2022/02/10 05:05:48 DEBUG : only5: md5 = 09cba04de923ec62e6e023ff974c6787 OK 2022/02/10 05:05:49 DEBUG : both6: md5 = 5e22ad1542d036b7903cae45c3ef6ca1 OK 2022/02/10 05:05:50 DEBUG : only6: md5 = 0eb2a3c70075530488465ebc3ae99409 OK 2022/02/10 05:05:51 DEBUG : both7: md5 = e623ae8d827fbcaa9826b9bad5c98a23 OK 2022/02/10 05:05:51 DEBUG : only7: md5 = c6a762785b6cab4969744786e8b36b88 OK 2022/02/10 05:05:52 DEBUG : both8: md5 = 0445910643341efa373429074ccc6307 OK 2022/02/10 05:05:53 DEBUG : only8: md5 = 16346b634988704bf2339db69b57d794 OK 2022/02/10 05:05:54 DEBUG : both9: md5 = 88a9e24b69133583d538bcfbf28e042b OK 2022/02/10 05:05:54 DEBUG : only9: md5 = e2de426e66658e27ff307c6ab09d1b52 OK 2022/02/10 05:05:55 DEBUG : both10: md5 = f968d8b5d113d95ea42a87c6229aeda4 OK 2022/02/10 05:05:56 DEBUG : only10: md5 = cec74be560fceefddadcdadff839f0f6 OK 2022/02/10 05:05:56 DEBUG : both11: md5 = fdb569d6d7e315e60b0ff295a1251293 OK 2022/02/10 05:05:57 DEBUG : only11: md5 = baed8f80bcc968acc3825d6e40058b24 OK 2022/02/10 05:05:57 DEBUG : both12: md5 = cd8242afde8362c24de738274ee08591 OK 2022/02/10 05:05:58 DEBUG : only12: md5 = 4efeda8fae0e307c2270d9dd5badfbcd OK 2022/02/10 05:05:59 DEBUG : both13: md5 = 1459cd17488e69704546b688fcb45038 OK 2022/02/10 05:06:01 DEBUG : only13: md5 = e0aea75fbcb9148f5a14217b1d7817b5 OK 2022/02/10 05:06:21 DEBUG : both14: md5 = 9dc3b99956453583a086e86a46f23a4b OK 2022/02/10 05:06:23 DEBUG : only14: md5 = 1faf0708980cb43d2da2169ee395b26d OK 2022/02/10 05:06:23 DEBUG : both15: md5 = d0f3b253d053413dc969d80fa9a08dd1 OK 2022/02/10 05:06:46 DEBUG : only15: md5 = c1b24bbf00bad93105d08ee74aa140d6 OK 2022/02/10 05:06:47 DEBUG : both16: md5 = 0051ecf8b524708ad8a63a69db971458 OK 2022/02/10 05:06:48 DEBUG : only16: md5 = 8ad9d8fa7260f8e8ef1bb25bbbb65d50 OK 2022/02/10 05:06:48 DEBUG : both17: md5 = 43ac824425d8fdede733cc845bb81ad8 OK 2022/02/10 05:06:49 DEBUG : only17: md5 = 0c2b1cd8130ed1445225ff539186e32b OK 2022/02/10 05:06:50 DEBUG : both18: md5 = cfa5e95d8fca4e78ae4142644a80d401 OK 2022/02/10 05:06:50 DEBUG : only18: md5 = efa4892111c9748799046b040d5872e4 OK 2022/02/10 05:06:51 DEBUG : both19: md5 = 286aa7c61fec18c242d285809f24de41 OK 2022/02/10 05:07:13 DEBUG : only19: md5 = 037720d35aaf6a836caef926d6a41068 OK 2022/02/10 05:07:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:07:17 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both1: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both0: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both11: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both10: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both12: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both13: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both15: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both14: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both17: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both19: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both2: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both3: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both4: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both5: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both18: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both16: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both6: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both7: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both8: Unchanged skipping 2022/02/10 05:07:17 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:07:17 DEBUG : both9: Unchanged skipping 2022/02/10 05:07:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:07:17 DEBUG : Waiting for deletions to finish 2022/02/10 05:07:18 INFO : only10: Deleted 2022/02/10 05:07:18 INFO : only16: Deleted 2022/02/10 05:07:18 INFO : only7: Deleted 2022/02/10 05:07:18 INFO : only8: Deleted 2022/02/10 05:07:18 INFO : only17: Deleted 2022/02/10 05:07:18 INFO : only11: Deleted 2022/02/10 05:07:18 INFO : only2: Deleted 2022/02/10 05:07:19 INFO : only3: Deleted 2022/02/10 05:07:19 INFO : only0: Deleted 2022/02/10 05:07:19 INFO : only12: Deleted 2022/02/10 05:07:20 INFO : only13: Deleted 2022/02/10 05:07:21 INFO : only15: Deleted 2022/02/10 05:07:21 INFO : only14: Deleted 2022/02/10 05:07:22 INFO : only6: Deleted 2022/02/10 05:07:22 INFO : only18: Deleted 2022/02/10 05:07:23 INFO : only4: Deleted 2022/02/10 05:07:31 INFO : only5: Deleted 2022/02/10 05:07:38 INFO : only19: Deleted 2022/02/10 05:07:42 INFO : only1: Deleted 2022/02/10 05:07:43 INFO : only9: Deleted 2022/02/10 05:07:43 INFO : There was nothing to transfer --- PASS: TestSyncConcurrentDelete (255.28s) === RUN TestSyncConcurrentTruncate run.go:181: Remote "Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0'", Local "Local file system at /tmp/rclone1070390538", Modify Window "1ns" 2022/02/10 05:09:06 DEBUG : both0: md5 = fa85aa3d936fb3e9f87cb4631bfcf226 OK 2022/02/10 05:09:07 DEBUG : only0: md5 = ebd4277c625d844403dfb9765f2d1cb7 OK 2022/02/10 05:09:07 DEBUG : both1: md5 = 84e81039d9e00e0e1b07ba7c833be1f5 OK 2022/02/10 05:09:30 DEBUG : only1: md5 = b6b7be474f4945708ed01facb06660f8 OK 2022/02/10 05:09:32 DEBUG : both2: md5 = ced5337f33be2e82326b3371196d87d5 OK 2022/02/10 05:09:32 DEBUG : only2: md5 = d5a0ce4ec6553385e76e1aa36fdb9dd9 OK 2022/02/10 05:09:32 DEBUG : both3: md5 = b1a57e676f431e618bd06c1b41cfe4f7 OK 2022/02/10 05:09:33 DEBUG : only3: md5 = 7fe5c31fb2072121c968cd8a25962a50 OK 2022/02/10 05:09:33 DEBUG : both4: md5 = fc32d115166d7f605370df2a7329d2f8 OK 2022/02/10 05:09:34 DEBUG : only4: md5 = 67dc665652ea3cc19666d52ea9800928 OK 2022/02/10 05:09:57 DEBUG : both5: md5 = 896f0a1b99a024f17e69b83648c11771 OK 2022/02/10 05:09:58 DEBUG : only5: md5 = 52a9dd33cd0d1763ceab1588b491fc56 OK 2022/02/10 05:09:58 DEBUG : both6: md5 = e9a497f036131c56b92f660942a5a8fa OK 2022/02/10 05:09:59 DEBUG : only6: md5 = 9f30838157b22c604f4e1f135a761425 OK 2022/02/10 05:09:59 DEBUG : both7: md5 = 24d8ae18e5652436a52830364be1886b OK 2022/02/10 05:10:00 DEBUG : only7: md5 = d67e09784f2b4de8cf64c66b871ffe31 OK 2022/02/10 05:10:00 DEBUG : both8: md5 = a03d5b668903c965b2281a2ea895bea0 OK 2022/02/10 05:10:00 DEBUG : only8: md5 = fa490a73df8e2f5ab4f02020c089a8db OK 2022/02/10 05:10:01 DEBUG : both9: md5 = f4c025c83ad6bed74f9de92cd0563a20 OK 2022/02/10 05:10:01 DEBUG : only9: md5 = d3509d362ce9cdd5bd6ac568c83e9350 OK 2022/02/10 05:10:02 DEBUG : both10: md5 = e580b4ec1b88803de70b68d3b1057d8b OK 2022/02/10 05:10:02 DEBUG : only10: md5 = a74596a30dbafe684b1eb0fef85ae09f OK 2022/02/10 05:10:02 DEBUG : both11: md5 = a90af8aad662ca6cdbe9ac3912cd5ecf OK 2022/02/10 05:10:03 DEBUG : only11: md5 = f3d80d87ff277993b9f6da0b77a52983 OK 2022/02/10 05:10:03 DEBUG : both12: md5 = 65b4a4795cb5e4a5eab4d7341f805c0d OK 2022/02/10 05:10:04 DEBUG : only12: md5 = bf5d9ad84903ec262906741200bfe6a1 OK 2022/02/10 05:10:05 DEBUG : both13: md5 = 22a7e4cfbe1fa75dfb42be431d9a87b9 OK 2022/02/10 05:10:05 DEBUG : only13: md5 = a83825ee094e5d4e8c2b00304533105a OK 2022/02/10 05:10:36 DEBUG : both14: md5 = e69766672c7167d6ac39ba2845f30a06 OK 2022/02/10 05:10:41 DEBUG : only14: md5 = dd5e7bec2ce24dd460bad9d9d5a67b04 OK 2022/02/10 05:10:41 DEBUG : both15: md5 = 6d62800fdbb479856916cbebee50a9c8 OK 2022/02/10 05:11:01 DEBUG : only15: md5 = 5a3e8e1d0b1c082c3b1b80db9d98dfc4 OK 2022/02/10 05:11:02 DEBUG : both16: md5 = 6fbacde3c8fbfdfea748db3c242c015c OK 2022/02/10 05:11:02 DEBUG : only16: md5 = 8079404c929f9b9e140b9481ae0428f7 OK 2022/02/10 05:11:03 DEBUG : both17: md5 = 25fbe386b1521aa3ed4aaa83df7e0ff6 OK 2022/02/10 05:11:04 DEBUG : only17: md5 = 96362d5ff2b3c663ea81abc114f8162d OK 2022/02/10 05:11:05 DEBUG : both18: md5 = a4bdba9c5f019a8c753124c14364e103 OK 2022/02/10 05:11:05 DEBUG : only18: md5 = 3da05a4e3fc8c0724ed3cb3470df8d5d OK 2022/02/10 05:11:06 DEBUG : both19: md5 = fdb8988f21c8d092618ca2937a047cb8 OK 2022/02/10 05:11:26 DEBUG : only19: md5 = abec93a93a82e39cbea530b97ec0ab68 OK 2022/02/10 05:11:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for checks to finish 2022/02/10 05:11:28 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both0: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both1: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both10: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both11: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both13: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both12: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both14: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both15: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both16: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both17: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both18: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both19: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both2: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both3: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both4: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both5: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both6: Unchanged skipping 2022/02/10 05:11:28 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2022/02/10 05:11:28 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both7: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both8: Unchanged skipping 2022/02/10 05:11:28 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2022/02/10 05:11:28 DEBUG : both9: Unchanged skipping 2022/02/10 05:11:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Waiting for transfers to finish 2022/02/10 05:11:29 DEBUG : only10: md5 = 074fcf50b33d6ffc9b8b7e8b457c8446 OK 2022/02/10 05:11:29 INFO : only10: Copied (replaced existing) 2022/02/10 05:11:29 DEBUG : only11: md5 = 29200c62303d276c466175af51d4fab4 OK 2022/02/10 05:11:29 INFO : only11: Copied (replaced existing) 2022/02/10 05:11:29 DEBUG : only12: md5 = 32483475ebb343548c761dae5bf0f940 OK 2022/02/10 05:11:29 INFO : only12: Copied (replaced existing) 2022/02/10 05:11:29 DEBUG : only0: md5 = 1867de591c2e17a46bfce5e3063f5548 OK 2022/02/10 05:11:29 INFO : only0: Copied (replaced existing) 2022/02/10 05:11:30 DEBUG : only14: md5 = b0b43e367c614da7c9f3bd05e412c575 OK 2022/02/10 05:11:30 INFO : only14: Copied (replaced existing) 2022/02/10 05:11:30 DEBUG : only13: md5 = a918822b3ae90ee387e6291a86b2bd42 OK 2022/02/10 05:11:30 INFO : only13: Copied (replaced existing) 2022/02/10 05:11:30 DEBUG : only16: md5 = f324ee2b99eb7242a79324a3309735f4 OK 2022/02/10 05:11:30 INFO : only16: Copied (replaced existing) 2022/02/10 05:11:30 DEBUG : only17: md5 = 7a968b297e27a0db5d90d84bd06963d7 OK 2022/02/10 05:11:30 INFO : only17: Copied (replaced existing) 2022/02/10 05:11:30 DEBUG : only18: md5 = 8343cd3868ca5b5cb54476d179897d60 OK 2022/02/10 05:11:30 INFO : only18: Copied (replaced existing) 2022/02/10 05:11:31 DEBUG : only2: md5 = eb62eb4223b0d8fc2e31ed0f7e295efc OK 2022/02/10 05:11:31 INFO : only2: Copied (replaced existing) 2022/02/10 05:11:31 DEBUG : only3: md5 = c91752aebbd51338d7d36176a6377061 OK 2022/02/10 05:11:31 INFO : only3: Copied (replaced existing) 2022/02/10 05:11:32 DEBUG : only4: md5 = c397849746da5f5b103df08268af9649 OK 2022/02/10 05:11:32 INFO : only4: Copied (replaced existing) 2022/02/10 05:11:33 DEBUG : only5: md5 = 4e55f5e8e9eee3630b509fe26a32a889 OK 2022/02/10 05:11:33 INFO : only5: Copied (replaced existing) 2022/02/10 05:11:33 DEBUG : only6: md5 = ca7052a503ba6c94d9c30d90ac1e7655 OK 2022/02/10 05:11:33 INFO : only6: Copied (replaced existing) 2022/02/10 05:11:33 DEBUG : only7: md5 = 28e798279a39a90a3f9579b1f12a9afb OK 2022/02/10 05:11:33 INFO : only7: Copied (replaced existing) 2022/02/10 05:11:34 DEBUG : only8: md5 = 93addf07898b92921bf82ab3347090d7 OK 2022/02/10 05:11:34 INFO : only8: Copied (replaced existing) 2022/02/10 05:11:34 DEBUG : only9: md5 = 57b08c2cdd2d536b591796d36e922dad OK 2022/02/10 05:11:34 INFO : only9: Copied (replaced existing) 2022/02/10 05:11:49 DEBUG : only1: md5 = 7a1dd0f1f1188f8528b71dcdfed83c82 OK 2022/02/10 05:11:49 INFO : only1: Copied (replaced existing) 2022/02/10 05:11:50 DEBUG : only15: md5 = f946fffab76c488206af50ffb9208526 OK 2022/02/10 05:11:50 INFO : only15: Copied (replaced existing) 2022/02/10 05:11:51 DEBUG : only19: md5 = 83664d2b201e6530faebd17b8a74d23e OK 2022/02/10 05:11:51 INFO : only19: Copied (replaced existing) 2022/02/10 05:11:51 DEBUG : Waiting for deletions to finish --- PASS: TestSyncConcurrentTruncate (279.65s) PASS 2022/02/10 05:13:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-wepinat6hikeyal3dukomor0': Purge remote 2022/02/10 05:13:46 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 13m31.714973457s (try 1/5)