"./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Starting (try 1/5) 2024/04/18 01:00:22 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4" 2024/04/18 01:00:22 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/04/18 01:00:22 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4.bin" 2024/04/18 01:00:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4" 2024/04/18 01:00:23 DEBUG : Creating backend with remote "/tmp/rclone2223129932" === RUN TestPipe --- PASS: TestPipe (0.00s) === RUN TestPipeConcurrent --- PASS: TestPipeConcurrent (0.03s) === RUN TestPipeOrderBy === RUN TestPipeOrderBy/#00 === RUN TestPipeOrderBy/size === RUN TestPipeOrderBy/name === RUN TestPipeOrderBy/modtime === RUN TestPipeOrderBy/size,ascending === RUN TestPipeOrderBy/name,asc === RUN TestPipeOrderBy/modtime,ascending === RUN TestPipeOrderBy/size,descending === RUN TestPipeOrderBy/name,desc === RUN TestPipeOrderBy/modtime,descending === RUN TestPipeOrderBy/size,mixed,50 === RUN TestPipeOrderBy/size,mixed,51 --- PASS: TestPipeOrderBy (0.00s) --- PASS: TestPipeOrderBy/#00 (0.00s) --- PASS: TestPipeOrderBy/size (0.00s) --- PASS: TestPipeOrderBy/name (0.00s) --- PASS: TestPipeOrderBy/modtime (0.00s) --- PASS: TestPipeOrderBy/size,ascending (0.00s) --- PASS: TestPipeOrderBy/name,asc (0.00s) --- PASS: TestPipeOrderBy/modtime,ascending (0.00s) --- PASS: TestPipeOrderBy/size,descending (0.00s) --- PASS: TestPipeOrderBy/name,desc (0.00s) --- PASS: TestPipeOrderBy/modtime,descending (0.00s) --- PASS: TestPipeOrderBy/size,mixed,50 (0.00s) --- PASS: TestPipeOrderBy/size,mixed,51 (0.00s) === RUN TestNewLess === RUN TestNewLess/blankOK === RUN TestNewLess/tooManyParts === RUN TestNewLess/tooManyParts2 === RUN TestNewLess/badMixed === RUN TestNewLess/unknownComparison === RUN TestNewLess/unknownSortDirection === RUN TestNewLess/size === RUN TestNewLess/name === RUN TestNewLess/modtime === RUN TestNewLess/size,ascending === RUN TestNewLess/name,asc === RUN TestNewLess/modtime,ascending === RUN TestNewLess/size,descending === RUN TestNewLess/name,desc === RUN TestNewLess/modtime,descending === RUN TestNewLess/modtime,mixed === RUN TestNewLess/modtime,mixed,30 --- PASS: TestNewLess (0.00s) --- PASS: TestNewLess/blankOK (0.00s) --- PASS: TestNewLess/tooManyParts (0.00s) --- PASS: TestNewLess/tooManyParts2 (0.00s) --- PASS: TestNewLess/badMixed (0.00s) --- PASS: TestNewLess/unknownComparison (0.00s) --- PASS: TestNewLess/unknownSortDirection (0.00s) --- PASS: TestNewLess/size (0.00s) --- PASS: TestNewLess/name (0.00s) --- PASS: TestNewLess/modtime (0.00s) --- PASS: TestNewLess/size,ascending (0.00s) --- PASS: TestNewLess/name,asc (0.00s) --- PASS: TestNewLess/modtime,ascending (0.00s) --- PASS: TestNewLess/size,descending (0.00s) --- PASS: TestNewLess/name,desc (0.00s) --- PASS: TestNewLess/modtime,descending (0.00s) --- PASS: TestNewLess/modtime,mixed (0.00s) --- PASS: TestNewLess/modtime,mixed,30 (0.00s) === RUN TestRcCopy rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcCopy (0.00s) === RUN TestRcMove rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcMove (0.00s) === RUN TestRcSync rc_test.go:16: Skipping test on non local remote --- SKIP: TestRcSync (0.00s) === RUN TestCopyWithDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:23 NOTICE: sub dir: Skipped make directory as --dry-run is set 2024/04/18 01:00:23 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:23 NOTICE: sub dir/hello world: Skipped copy as --dry-run is set (size 11) 2024/04/18 01:00:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyWithDryRun (0.59s) === RUN TestCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:24 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 01:00:24 DEBUG : sub dir: Making directory 2024/04/18 01:00:24 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:24 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:24 DEBUG : sub dir/hello world: md5 = 01cbb1e8e85a36b5e43e60ad9541a0e4 OK 2024/04/18 01:00:24 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopy (1.54s) === RUN TestCopyMetadata run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:97: Skipping as metadata not supported --- SKIP: TestCopyMetadata (0.09s) === RUN TestCopyMissingDirectory run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:25 DEBUG : Creating backend with remote "/non-existing" 2024/04/18 01:00:25 ERROR : Local file system at /non-existing: error reading source root directory: directory not found 2024/04/18 01:00:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyMissingDirectory (0.22s) === RUN TestCopyNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:26 DEBUG : sub dir: Making directory 2024/04/18 01:00:26 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:26 DEBUG : sub dir/hello world: md5 = d8c192391d99827c429c7a9d2f586102 OK 2024/04/18 01:00:26 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyNoTraverse (1.10s) === RUN TestCopyCheckFirst run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Running all checks before starting transfers 2024/04/18 01:00:27 DEBUG : sub dir: Making directory 2024/04/18 01:00:27 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Checks finished, now starting transfers 2024/04/18 01:00:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:27 DEBUG : sub dir/hello world: md5 = b8329c1cf02b9994ea4a97e803adec99 OK 2024/04/18 01:00:27 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyCheckFirst (1.04s) === RUN TestSyncNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:28 ERROR : Ignoring --no-traverse with sync 2024/04/18 01:00:28 DEBUG : sub dir: Making directory 2024/04/18 01:00:28 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:28 DEBUG : sub dir/hello world: md5 = d98295a2be661f48f7aa65854587ff8d OK 2024/04/18 01:00:28 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:00:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncNoTraverse (1.05s) === RUN TestCopyWithDepth run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:29 DEBUG : hello world2: Need to transfer - File not found at Destination 2024/04/18 01:00:29 DEBUG : sub dir: Making directory 2024/04/18 01:00:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:29 DEBUG : hello world2: md5 = db5627fe23f725cfa855328b5dafdf62 OK 2024/04/18 01:00:29 INFO : hello world2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyWithDepth (1.43s) === RUN TestCopyWithFilesFrom run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:30 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:00:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:30 DEBUG : potato2: md5 = 011f55c3abb36b0947873571e9732f36 OK 2024/04/18 01:00:30 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyWithFilesFrom (1.07s) === RUN TestCopyWithFilesFromAndNoTraverse run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:31 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:00:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:31 DEBUG : potato2: md5 = 9e5356255547ac01e8c8bb869ad50acc OK 2024/04/18 01:00:31 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyWithFilesFromAndNoTraverse (0.85s) === RUN TestCopyEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:32 DEBUG : sub dir2: Making directory with metadata 2024/04/18 01:00:32 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 01:00:32 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 01:00:32 DEBUG : sub dir: Making directory 2024/04/18 01:00:32 DEBUG : sub dir2: Making directory 2024/04/18 01:00:32 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:33 DEBUG : sub dir/hello world: md5 = ef7e1dc9f96aca63103a57bb67aaa7e8 OK 2024/04/18 01:00:33 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:00:33 DEBUG : sub dir2: Making directory 2024/04/18 01:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': copied 1 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/18 01:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestCopyEmptyDirectories (1.15s) === RUN TestMoveEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:33 DEBUG : sub dir2: Making directory with metadata 2024/04/18 01:00:33 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 01:00:33 DEBUG : sub dir: Making directory 2024/04/18 01:00:33 DEBUG : sub dir2: Making directory 2024/04/18 01:00:33 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:33 DEBUG : sub dir/hello world: md5 = b28b43b720058582de1a4754f6ee9476 OK 2024/04/18 01:00:33 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:00:33 INFO : sub dir/hello world: Deleted 2024/04/18 01:00:33 DEBUG : sub dir: Making directory 2024/04/18 01:00:33 DEBUG : sub dir2: Making directory 2024/04/18 01:00:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': copied 2 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/18 01:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata 2024/04/18 01:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestMoveEmptyDirectories (1.04s) === RUN TestSyncNoUpdateDirModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:351: Skipping test as backend does not support DirSetModTime --- SKIP: TestSyncNoUpdateDirModtime (0.09s) === RUN TestSyncEmptyDirectories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:34 DEBUG : sub dir2: Making directory with metadata 2024/04/18 01:00:34 INFO : sub dir2: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2024/04/18 01:00:34 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 01:00:34 DEBUG : sub dir: Making directory 2024/04/18 01:00:34 DEBUG : sub dir2: Making directory 2024/04/18 01:00:34 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:34 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:35 DEBUG : sub dir/hello world: md5 = 95d88cad939171e1bf63f14130fa8d25 OK 2024/04/18 01:00:35 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:00:35 DEBUG : sub dir2: Making directory 2024/04/18 01:00:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': copied 1 directories 2024/04/18 01:00:35 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" fstest.go:244: Filtering empty directory "sub dir2" 2024/04/18 01:00:35 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Skipping modtime test as remote does not support DirSetModTime or MkdirMetadata --- PASS: TestSyncEmptyDirectories (1.04s) === RUN TestSyncSetDelayedModTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:427: Backend doesn't have DirModTimeUpdatesOnWrite set --- SKIP: TestSyncSetDelayedModTimes (0.09s) === RUN TestServerSideCopy run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:36 DEBUG : sub dir/hello world: md5 = 2e0806bb5796d16df35ebd80216a54d6 OK 2024/04/18 01:00:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-jitiwiz9fedopug6tibimen5" 2024/04/18 01:00:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jitiwiz9fedopug6tibimen5.bin" 2024/04/18 01:00:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-jitiwiz9fedopug6tibimen5" sync_test.go:487: Server side copy (if possible) Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4' -> Encrypted drive 'TestCryptSwift:rclone-test-jitiwiz9fedopug6tibimen5' 2024/04/18 01:00:37 DEBUG : sub dir: Making directory 2024/04/18 01:00:37 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jitiwiz9fedopug6tibimen5': Waiting for checks to finish 2024/04/18 01:00:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jitiwiz9fedopug6tibimen5': Waiting for transfers to finish 2024/04/18 01:00:37 INFO : sub dir/hello world: Copied (server-side copy) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-jitiwiz9fedopug6tibimen5': Purge remote 2024/04/18 01:00:38 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:38 INFO : sub dir/hello world.bin: Deleted --- PASS: TestServerSideCopy (3.12s) === RUN TestCopyAfterDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:39 DEBUG : sub dir/hello world: md5 = 878206f18ed817ecf518c77981f0593a OK 2024/04/18 01:00:39 ERROR : : error listing: directory not found 2024/04/18 01:00:39 DEBUG : Local file system at /tmp/rclone2223129932: Making directory 2024/04/18 01:00:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyAfterDelete (1.35s) === RUN TestCopyRedownload run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:40 DEBUG : sub dir/hello world: md5 = 669d4870239745157e524a4e6b22978b OK 2024/04/18 01:00:40 DEBUG : sub dir: Making directory 2024/04/18 01:00:41 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:00:41 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for checks to finish 2024/04/18 01:00:41 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for transfers to finish 2024/04/18 01:00:41 DEBUG : sub dir/hello world.woviwuv7.partial: renamed to: sub dir/hello world 2024/04/18 01:00:41 INFO : sub dir/hello world: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyRedownload (1.42s) === RUN TestSyncBasedOnCheckSum run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:42 DEBUG : check sum: Need to transfer - File not found at Destination 2024/04/18 01:00:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:42 DEBUG : check sum: md5 = 9f5ccf7ec85a078d390162c65d96af3e OK 2024/04/18 01:00:42 INFO : check sum: Copied (new) 2024/04/18 01:00:42 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:42 NOTICE: Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2024/04/18 01:00:42 DEBUG : check sum: Size of src and dst objects identical 2024/04/18 01:00:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:42 DEBUG : check sum: Unchanged skipping 2024/04/18 01:00:42 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:42 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:42 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncBasedOnCheckSum (1.67s) === RUN TestSyncSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:43 DEBUG : sizeonly: Need to transfer - File not found at Destination 2024/04/18 01:00:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:43 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:43 DEBUG : sizeonly: md5 = 25bf28672f64ef17706ccbd647aff26a OK 2024/04/18 01:00:43 INFO : sizeonly: Copied (new) 2024/04/18 01:00:43 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:44 DEBUG : sizeonly: Sizes identical 2024/04/18 01:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:44 DEBUG : sizeonly: Unchanged skipping 2024/04/18 01:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:44 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:44 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncSizeOnly (1.30s) === RUN TestSyncIgnoreSize run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:44 DEBUG : ignore-size: Need to transfer - File not found at Destination 2024/04/18 01:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:44 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:45 DEBUG : ignore-size: md5 = ceae96d76fc29d395d726c7c36659476 OK 2024/04/18 01:00:45 INFO : ignore-size: Copied (new) 2024/04/18 01:00:45 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:45 DEBUG : ignore-size: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:00:45 DEBUG : ignore-size: Unchanged skipping 2024/04/18 01:00:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:45 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:45 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncIgnoreSize (1.46s) === RUN TestSyncIgnoreTimes run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:46 DEBUG : existing: md5 = a33d19fd0d033be47204c97273624976 OK 2024/04/18 01:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:46 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:00:46 DEBUG : existing: Unchanged skipping 2024/04/18 01:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:46 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:46 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:46 DEBUG : existing: Transferring unconditionally as --ignore-times is in use 2024/04/18 01:00:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:47 DEBUG : existing: md5 = d8058f8b40d80b5d702a15f2ce53364f OK 2024/04/18 01:00:47 INFO : existing: Copied (replaced existing) 2024/04/18 01:00:47 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncIgnoreTimes (1.69s) === RUN TestSyncIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:48 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/18 01:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:48 DEBUG : existing: md5 = 14eba3e9c75fa66962a6eda46e0a3713 OK 2024/04/18 01:00:48 INFO : existing: Copied (new) 2024/04/18 01:00:48 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:48 DEBUG : existing: Destination exists, skipping 2024/04/18 01:00:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:48 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:48 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncIgnoreExisting (1.28s) === RUN TestSyncIgnoreErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:49 DEBUG : b/potato: md5 = c5815ac792f5f1ab58d60e3df4cbfbf8 OK 2024/04/18 01:00:49 DEBUG : c/non empty space: md5 = 284513c3cf11e43dd2a965b02749e689 OK 2024/04/18 01:00:49 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/18 01:00:50 DEBUG : a: Making directory 2024/04/18 01:00:50 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 01:00:50 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:00:50 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 01:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:50 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:50 DEBUG : a/potato2: md5 = 654ca5b3ae58cd1b6e739c16bde69595 OK 2024/04/18 01:00:50 INFO : a/potato2: Copied (new) 2024/04/18 01:00:50 DEBUG : Waiting for deletions to finish 2024/04/18 01:00:50 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncIgnoreErrors (2.69s) === RUN TestSyncAfterChangingModtimeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:52 DEBUG : empty space: md5 = b526f9568133e2e7c0186f5aefc07c4d OK 2024/04/18 01:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:52 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 2024/04/18 01:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:52 NOTICE: empty space: Skipped copy as --dry-run is set (size 1) 2024/04/18 01:00:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:52 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 2024/04/18 01:00:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:52 DEBUG : empty space: md5 = f8d686b3829f8992a53fa23d786ab526 OK 2024/04/18 01:00:52 INFO : empty space: Copied (replaced existing) 2024/04/18 01:00:52 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterChangingModtimeOnly (1.76s) === RUN TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:811: Can't check this if no hashes supported --- PASS: TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime (0.08s) === RUN TestSyncDoesntUpdateModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:54 DEBUG : foo: md5 = 19e06e13cafe1d434b8aa3164257bbaa OK 2024/04/18 01:00:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:54 DEBUG : foo: Modification times differ by -95456h54m52.62345679s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2001-02-03 04:05:06.499999999 +0000 UTC 2024/04/18 01:00:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:54 DEBUG : foo: md5 = 1ccae96b1f66ea34f2c6d12f1116a04a OK 2024/04/18 01:00:54 INFO : foo: Copied (replaced existing) 2024/04/18 01:00:54 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncDoesntUpdateModtime (1.41s) === RUN TestSyncAfterAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:55 DEBUG : empty space: md5 = d1f044a6734acbd16cf023c02aad2d57 OK 2024/04/18 01:00:55 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/18 01:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:55 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:00:55 DEBUG : empty space: Unchanged skipping 2024/04/18 01:00:55 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:56 DEBUG : potato: md5 = dfd155257fd2b0a7a4e7ae16d5c5b203 OK 2024/04/18 01:00:56 INFO : potato: Copied (new) 2024/04/18 01:00:56 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterAddingAFile (2.52s) === RUN TestSyncAfterChangingFilesSizeOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:00:57 DEBUG : potato: md5 = b7f1e68cf5069f1b1d8d5e3c5838b4b8 OK 2024/04/18 01:00:58 DEBUG : potato: Sizes differ (src 21 vs dst 60) 2024/04/18 01:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:00:58 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:00:58 DEBUG : potato: md5 = 8f59897b6700d16826a9e18293250707 OK 2024/04/18 01:00:58 INFO : potato: Copied (replaced existing) 2024/04/18 01:00:58 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterChangingFilesSizeOnly (2.02s) === RUN TestSyncAfterChangingContentsOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:00 DEBUG : potato: md5 = 0d5e423f5a33af66b6a7d73360335127 OK 2024/04/18 01:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:00 DEBUG : potato: Modification times differ by 119h59m59.876543211s: 2011-12-25 12:59:59.123456789 +0000 UTC, 2011-12-30 12:59:59 +0000 UTC 2024/04/18 01:01:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:00 DEBUG : potato: md5 = 0cb2e6981535e3b72377cb2f69557f0d OK 2024/04/18 01:01:00 INFO : potato: Copied (replaced existing) 2024/04/18 01:01:00 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterChangingContentsOnly (1.83s) === RUN TestSyncAfterRemovingAFileAndAddingAFileDryRun run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:01 DEBUG : potato: md5 = 85bccf02925fb56e2044b9ae6b40762f OK 2024/04/18 01:01:01 DEBUG : empty space: md5 = 4449e4b7ee869a8ffa9e33d85284a909 OK 2024/04/18 01:01:02 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:02 NOTICE: potato2: Skipped copy as --dry-run is set (size 60) 2024/04/18 01:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:02 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:02 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:02 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:02 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:02 NOTICE: potato: Skipped delete as --dry-run is set (size 21) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileDryRun (1.66s) === RUN TestSyncAfterRemovingAFileAndAddingAFile run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:03 DEBUG : potato: md5 = 3a458f8bf71acb36dca5d28c5d1d7e7b OK 2024/04/18 01:01:03 DEBUG : empty space: md5 = 70139565a0c657896d61677f8c176fec OK 2024/04/18 01:01:04 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:04 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:04 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:04 DEBUG : potato2: md5 = aeccb1a2eecdff0091d322677804e7b9 OK 2024/04/18 01:01:04 INFO : potato2: Copied (new) 2024/04/18 01:01:04 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:04 INFO : potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFile (2.14s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:05 DEBUG : b/potato: md5 = 1e1d3c2231ce50f89cce5719d38f7bef OK 2024/04/18 01:01:05 DEBUG : c/non empty space: md5 = 48c60c9c0ab3269dccb64b7b363a6c7f OK 2024/04/18 01:01:05 DEBUG : d: Making directory 2024/04/18 01:01:05 DEBUG : d/e: Making directory fstest.go:244: Filtering empty directory "d" fstest.go:244: Filtering empty directory "d/e" 2024/04/18 01:01:06 DEBUG : a: Making directory 2024/04/18 01:01:06 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:06 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:06 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 01:01:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:06 DEBUG : a/potato2: md5 = 922f6c0048f528cdb39939faf431208f OK 2024/04/18 01:01:06 INFO : a/potato2: Copied (new) 2024/04/18 01:01:06 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:06 INFO : b/potato: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDir (2.31s) === RUN TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:07 DEBUG : b/potato: md5 = 1e6d635e302471b3aa37224fe706be9e OK 2024/04/18 01:01:07 DEBUG : c/non empty space: md5 = 45a7bfc405285957d21e10600b54bfd4 OK 2024/04/18 01:01:07 DEBUG : d: Making directory fstest.go:244: Filtering empty directory "d" 2024/04/18 01:01:08 DEBUG : a: Making directory 2024/04/18 01:01:08 DEBUG : a/potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:08 DEBUG : c/non empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:08 DEBUG : c/non empty space: Unchanged skipping 2024/04/18 01:01:08 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:08 DEBUG : a/potato2: md5 = 9838d38eb1615f2c8caa286af167fb3a OK 2024/04/18 01:01:08 INFO : a/potato2: Copied (new) 2024/04/18 01:01:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': not deleting files as there were IO errors 2024/04/18 01:01:08 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" fstest.go:244: Filtering empty directory "d" --- PASS: TestSyncAfterRemovingAFileAndAddingAFileSubDirWithErrors (2.19s) === RUN TestSyncDeleteAfter --- PASS: TestSyncDeleteAfter (0.00s) === RUN TestSyncDeleteDuring run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:10 DEBUG : potato: md5 = aac82be9e56d206e00aba8765ca6b79e OK 2024/04/18 01:01:10 DEBUG : empty space: md5 = c227a481430ec187b17dfad13642f779 OK 2024/04/18 01:01:10 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:10 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:10 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:10 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:10 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:10 INFO : potato: Deleted 2024/04/18 01:01:10 DEBUG : potato2: md5 = 5477f6bf91c02b983e411a3dc4375d2b OK 2024/04/18 01:01:10 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncDeleteDuring (1.71s) === RUN TestSyncDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:11 DEBUG : potato: md5 = 3e9ec0ae0b2759baa8c3f3164994a68a OK 2024/04/18 01:01:11 DEBUG : empty space: md5 = 72b4e501cf6f578a47d73ba4e69037a2 OK 2024/04/18 01:01:12 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:12 INFO : potato: Deleted 2024/04/18 01:01:12 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:12 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:12 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:12 DEBUG : potato2: md5 = 9768eb2c9ff0c72482f720ce413c0bcb OK 2024/04/18 01:01:12 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncDeleteBefore (1.86s) === RUN TestCopyDeleteBefore run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:13 DEBUG : potato: md5 = 90e63c8fe1d0ec5412cf87942d29a222 OK 2024/04/18 01:01:14 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:14 DEBUG : potato2: md5 = 90f3a3ec1ac5c848d6d7ebe2505a03ed OK 2024/04/18 01:01:14 INFO : potato2: Copied (new) run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestCopyDeleteBefore (1.56s) === RUN TestSyncWithExclude run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:15 DEBUG : potato2: md5 = aeb7e9778a40fadc2683032b0d84351b OK 2024/04/18 01:01:15 DEBUG : empty space: md5 = 768808f8c19b29e66f2fc8538b18b2e9 OK 2024/04/18 01:01:15 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : enormous: Excluded 2024/04/18 01:01:15 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : potato2: Excluded 2024/04/18 01:01:15 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : potato2: Excluded 2024/04/18 01:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:15 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:15 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:15 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:15 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : enormous: Excluded 2024/04/18 01:01:15 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : potato2: Excluded 2024/04/18 01:01:15 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 01:01:15 DEBUG : potato2: Excluded 2024/04/18 01:01:15 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for checks to finish 2024/04/18 01:01:15 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:15 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:15 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for transfers to finish 2024/04/18 01:01:15 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:15 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncWithExclude (1.82s) === RUN TestSyncWithExcludeAndDeleteExcluded run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:16 DEBUG : potato2: md5 = 8754b8c94145bf13c2186ccb9122e2c1 OK 2024/04/18 01:01:17 DEBUG : empty space: md5 = 559f02ba58b01048034105a7d707f241 OK 2024/04/18 01:01:17 DEBUG : enormous: md5 = bf815a66139f413291d3c32bf21746fc OK 2024/04/18 01:01:17 DEBUG : enormous: Excluded (Size Filter) 2024/04/18 01:01:17 DEBUG : enormous: Excluded 2024/04/18 01:01:17 DEBUG : potato2: Excluded (Size Filter) 2024/04/18 01:01:17 DEBUG : potato2: Excluded 2024/04/18 01:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:17 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:17 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:17 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:17 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:17 INFO : potato2: Deleted 2024/04/18 01:01:18 INFO : enormous: Deleted 2024/04/18 01:01:18 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:18 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for checks to finish 2024/04/18 01:01:18 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:18 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:18 DEBUG : Local file system at /tmp/rclone2223129932: Waiting for transfers to finish 2024/04/18 01:01:18 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:18 INFO : enormous: Deleted 2024/04/18 01:01:18 INFO : potato2: Deleted 2024/04/18 01:01:18 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncWithExcludeAndDeleteExcluded (2.49s) === RUN TestSyncWithUpdateOlder run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:19 DEBUG : one: md5 = f2b7306b6e43a09764598e7f77b45587 OK 2024/04/18 01:01:20 DEBUG : two: md5 = 06662a1fa10499ed3f8c32c6bd7336b0 OK 2024/04/18 01:01:20 DEBUG : three: md5 = 4813a634707076331a0fb11734b50499 OK 2024/04/18 01:01:20 DEBUG : four: md5 = 211b2f1d1ef4300de422921db32a9dca OK 2024/04/18 01:01:21 DEBUG : five: Need to transfer - File not found at Destination 2024/04/18 01:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:21 DEBUG : four: Sizes differ (src 4 vs dst 8) 2024/04/18 01:01:21 DEBUG : four: Destination mod time is within 1s of source but files differ, transferring 2024/04/18 01:01:21 DEBUG : one: Destination is newer than source, skipping 2024/04/18 01:01:21 DEBUG : three: Sizes identical 2024/04/18 01:01:21 DEBUG : three: Destination mod time is within 1s of source and files identical, skipping 2024/04/18 01:01:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:21 DEBUG : four: md5 = 977a39261108404496296063b7d82a7a OK 2024/04/18 01:01:21 INFO : four: Copied (replaced existing) 2024/04/18 01:01:21 DEBUG : two: md5 = 3a6bb6b6b79f244bb985948bc9a7c5f8 OK 2024/04/18 01:01:21 INFO : two: Copied (replaced existing) 2024/04/18 01:01:21 DEBUG : five: md5 = 9d067d9487dc39b3d8b9ff5258fbca09 OK 2024/04/18 01:01:21 INFO : five: Copied (new) 2024/04/18 01:01:21 DEBUG : Waiting for deletions to finish sync_test.go:1244: Skip test with --checksum as no hashes supported --- PASS: TestSyncWithUpdateOlder (3.55s) === RUN TestSyncWithMaxDuration === RUN TestSyncWithMaxDuration/Hard sync_test.go:1261: Skipping test on non local remote === RUN TestSyncWithMaxDuration/Soft sync_test.go:1261: Skipping test on non local remote --- PASS: TestSyncWithMaxDuration (0.00s) --- SKIP: TestSyncWithMaxDuration/Hard (0.00s) --- SKIP: TestSyncWithMaxDuration/Soft (0.00s) === RUN TestSyncWithTrackRenames run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:1327: Can track renames: false 2024/04/18 01:01:22 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/18 01:01:23 DEBUG : potato: Need to transfer - File not found at Destination 2024/04/18 01:01:23 DEBUG : yam: Need to transfer - File not found at Destination 2024/04/18 01:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:23 DEBUG : potato: md5 = db9a5bac72b55d3d18f4c2be78b2dab6 OK 2024/04/18 01:01:23 INFO : potato: Copied (new) 2024/04/18 01:01:23 DEBUG : yam: md5 = b874b0d88b542db6ad619efb3250f5f2 OK 2024/04/18 01:01:23 INFO : yam: Copied (new) 2024/04/18 01:01:23 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:23 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Ignoring --track-renames as the source and destination do not have a common hash 2024/04/18 01:01:23 DEBUG : yaml: Need to transfer - File not found at Destination 2024/04/18 01:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:23 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:23 DEBUG : potato: Unchanged skipping 2024/04/18 01:01:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:23 DEBUG : yaml: md5 = de2ffa5b63df8a3be91807f0399c8b4d OK 2024/04/18 01:01:23 INFO : yaml: Copied (new) 2024/04/18 01:01:23 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:24 INFO : yam: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncWithTrackRenames (2.52s) === RUN TestParseRenamesStrategyModtime --- PASS: TestParseRenamesStrategyModtime (0.00s) === RUN TestRenamesStrategyModtime --- PASS: TestRenamesStrategyModtime (0.00s) === RUN TestSyncWithTrackRenamesStrategyModtime run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:1399: Can track renames: true 2024/04/18 01:01:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Making map for --track-renames 2024/04/18 01:01:25 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Finished making map for --track-renames 2024/04/18 01:01:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:25 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/18 01:01:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for renames to finish 2024/04/18 01:01:25 DEBUG : yam: Need to transfer - No matching file found at Destination 2024/04/18 01:01:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:25 DEBUG : yam: md5 = f4a249287350c94eece94c2e77121c44 OK 2024/04/18 01:01:25 INFO : yam: Copied (new) 2024/04/18 01:01:25 DEBUG : potato: md5 = 8557b30bd10b30c8e0430571852d06d0 OK 2024/04/18 01:01:25 INFO : potato: Copied (new) 2024/04/18 01:01:25 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Making map for --track-renames 2024/04/18 01:01:26 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Finished making map for --track-renames 2024/04/18 01:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:26 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:26 DEBUG : potato: Unchanged skipping 2024/04/18 01:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for renames to finish 2024/04/18 01:01:26 INFO : yam: Copied (server-side copy) to: yaml 2024/04/18 01:01:26 INFO : yam: Deleted 2024/04/18 01:01:26 INFO : yaml: Renamed from "yam" 2024/04/18 01:01:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:26 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyModtime (2.47s) === RUN TestSyncWithTrackRenamesStrategyLeaf run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:1438: Can track renames: true 2024/04/18 01:01:27 DEBUG : sub: Making directory 2024/04/18 01:01:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Making map for --track-renames 2024/04/18 01:01:27 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Finished making map for --track-renames 2024/04/18 01:01:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:27 DEBUG : potato: Need to transfer - No matching file found at Destination 2024/04/18 01:01:27 DEBUG : sub/yam: Need to transfer - No matching file found at Destination 2024/04/18 01:01:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for renames to finish 2024/04/18 01:01:27 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:28 DEBUG : potato: md5 = 96ce590dee0d4b5b6623998ff20897a8 OK 2024/04/18 01:01:28 INFO : potato: Copied (new) 2024/04/18 01:01:28 DEBUG : sub/yam: md5 = a485fc0ffe977c5bc0c6306845a1cd48 OK 2024/04/18 01:01:28 INFO : sub/yam: Copied (new) 2024/04/18 01:01:28 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:28 DEBUG : potato: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:28 DEBUG : potato: Unchanged skipping 2024/04/18 01:01:28 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Making map for --track-renames 2024/04/18 01:01:28 INFO : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Finished making map for --track-renames 2024/04/18 01:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for renames to finish 2024/04/18 01:01:29 INFO : sub/yam: Copied (server-side copy) to: yam 2024/04/18 01:01:29 INFO : sub/yam: Deleted 2024/04/18 01:01:29 INFO : yam: Renamed from "sub/yam" 2024/04/18 01:01:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:29 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncWithTrackRenamesStrategyLeaf (2.50s) === RUN TestServerSideMoveLocal run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:30 DEBUG : Creating backend with remote "/tmp/rclone2223129932/dir1" 2024/04/18 01:01:30 DEBUG : Creating backend with remote "/tmp/rclone2223129932/dir2" 2024/04/18 01:01:30 DEBUG : Local file system at /tmp/rclone2223129932/dir2: Using server-side directory move 2024/04/18 01:01:30 INFO : Local file system at /tmp/rclone2223129932/dir2: Server side directory move failed - fallback to file moves: can't copy directory - destination already exists 2024/04/18 01:01:30 DEBUG : file1.txt: Need to transfer - File not found at Destination 2024/04/18 01:01:30 DEBUG : Local file system at /tmp/rclone2223129932/dir2: Waiting for checks to finish 2024/04/18 01:01:30 DEBUG : Local file system at /tmp/rclone2223129932/dir2: Waiting for transfers to finish 2024/04/18 01:01:30 INFO : file1.txt: Moved (server-side) --- PASS: TestServerSideMoveLocal (0.18s) === RUN TestMoveWithDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:30 DEBUG : nested: Making directory 2024/04/18 01:01:30 DEBUG : sub dir: Making directory 2024/04/18 01:01:30 DEBUG : nested/sub dir: Making directory 2024/04/18 01:01:30 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:01:30 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/18 01:01:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:30 DEBUG : sub dir/hello world: md5 = 060ddb82861a6a03c31e57b6cbc1185c OK 2024/04/18 01:01:30 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:01:30 INFO : sub dir/hello world: Deleted 2024/04/18 01:01:30 DEBUG : nested/sub dir/file: md5 = 2d3f52ee3383fa6a7c5d37786863f4a6 OK 2024/04/18 01:01:30 INFO : nested/sub dir/file: Copied (new) 2024/04/18 01:01:30 INFO : nested/sub dir/file: Deleted 2024/04/18 01:01:30 INFO : sub dir: Removing directory 2024/04/18 01:01:30 INFO : nested/sub dir: Removing directory 2024/04/18 01:01:30 INFO : nested: Removing directory 2024/04/18 01:01:30 DEBUG : Local file system at /tmp/rclone2223129932: deleted 3 directories run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestMoveWithDeleteEmptySrcDirs (1.14s) === RUN TestMoveWithoutDeleteEmptySrcDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:31 DEBUG : nested: Making directory 2024/04/18 01:01:31 DEBUG : sub dir: Making directory 2024/04/18 01:01:31 DEBUG : sub dir/hello world: Need to transfer - File not found at Destination 2024/04/18 01:01:31 DEBUG : nested/sub dir: Making directory 2024/04/18 01:01:31 DEBUG : nested/sub dir/file: Need to transfer - File not found at Destination 2024/04/18 01:01:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:31 DEBUG : nested/sub dir/file: md5 = b5715c0bd44c5029dfe34f513d73cd46 OK 2024/04/18 01:01:31 INFO : nested/sub dir/file: Copied (new) 2024/04/18 01:01:31 INFO : nested/sub dir/file: Deleted 2024/04/18 01:01:31 DEBUG : sub dir/hello world: md5 = d1214130e7bc6273fa90217b57c0288a OK 2024/04/18 01:01:31 INFO : sub dir/hello world: Copied (new) 2024/04/18 01:01:31 INFO : sub dir/hello world: Deleted run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestMoveWithoutDeleteEmptySrcDirs (1.28s) === RUN TestMoveWithIgnoreExisting run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:33 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/18 01:01:33 DEBUG : existing-b: Need to transfer - File not found at Destination 2024/04/18 01:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:33 DEBUG : existing: md5 = 7dbe4aed40c656a81015dd6fb27d7580 OK 2024/04/18 01:01:33 INFO : existing: Copied (new) 2024/04/18 01:01:33 INFO : existing: Deleted 2024/04/18 01:01:33 DEBUG : existing-b: md5 = 58668f2a5253fa75c908cd2c7c41500a OK 2024/04/18 01:01:33 INFO : existing-b: Copied (new) 2024/04/18 01:01:33 INFO : existing-b: Deleted 2024/04/18 01:01:33 DEBUG : existing: Destination exists, skipping 2024/04/18 01:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:01:33 DEBUG : existing: Not removing source file as destination file exists and --ignore-existing is set 2024/04/18 01:01:33 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:01:33 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestMoveWithIgnoreExisting (1.73s) === RUN TestServerSideMove run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:34 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-cofodon2cacapiq6tujijat5" 2024/04/18 01:01:34 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cofodon2cacapiq6tujijat5.bin" 2024/04/18 01:01:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-cofodon2cacapiq6tujijat5" 2024/04/18 01:01:36 DEBUG : potato2: md5 = 0b2a9aa687960037b4d6da494413233f OK 2024/04/18 01:01:36 DEBUG : empty space: md5 = 2a7b739e8adffd39e5fcc93bfd3e3bdc OK 2024/04/18 01:01:36 DEBUG : potato3: md5 = 4f3a2c5bb51cceb197221b28ba67b6c4 OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4' -> Encrypted drive 'TestCryptSwift:rclone-test-cofodon2cacapiq6tujijat5' 2024/04/18 01:01:37 DEBUG : empty space: md5 = c2980e7809bc8b1b898fd70f50c53113 OK 2024/04/18 01:01:37 DEBUG : potato3: md5 = 2d8d9f7b0b9aed445b4827f6853fbbc4 OK 2024/04/18 01:01:38 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:38 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/18 01:01:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cofodon2cacapiq6tujijat5': Waiting for checks to finish 2024/04/18 01:01:38 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:38 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:38 INFO : empty space: Deleted 2024/04/18 01:01:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cofodon2cacapiq6tujijat5': Waiting for transfers to finish 2024/04/18 01:01:38 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:38 INFO : potato3: Deleted 2024/04/18 01:01:38 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:38 INFO : potato2: Deleted 2024/04/18 01:01:38 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nicucej1cirofur2sexuhab1" 2024/04/18 01:01:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nicucej1cirofur2sexuhab1.bin" 2024/04/18 01:01:39 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nicucej1cirofur2sexuhab1" 2024/04/18 01:01:40 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/18 01:01:40 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:40 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/18 01:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nicucej1cirofur2sexuhab1': Waiting for checks to finish 2024/04/18 01:01:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nicucej1cirofur2sexuhab1': Waiting for transfers to finish 2024/04/18 01:01:40 INFO : empty space: Copied (server-side copy) 2024/04/18 01:01:40 INFO : empty space: Deleted 2024/04/18 01:01:40 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:40 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:41 INFO : potato2: Deleted 2024/04/18 01:01:41 INFO : potato3: Deleted 2024/04/18 01:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nicucej1cirofur2sexuhab1': Purge remote 2024/04/18 01:01:41 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:41 INFO : potato3.bin: Deleted 2024/04/18 01:01:41 INFO : potato2.bin: Deleted 2024/04/18 01:01:41 INFO : empty space.bin: Deleted 2024/04/18 01:01:41 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-cofodon2cacapiq6tujijat5': Purge remote 2024/04/18 01:01:41 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMove (7.46s) === RUN TestServerSideMoveWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:42 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-tajigor9feriqic4rajoraf3" 2024/04/18 01:01:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tajigor9feriqic4rajoraf3.bin" 2024/04/18 01:01:42 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-tajigor9feriqic4rajoraf3" 2024/04/18 01:01:43 DEBUG : potato2: md5 = 2d198032ee1c485331a234b79c073278 OK 2024/04/18 01:01:43 DEBUG : empty space: md5 = c8518fe37a4734065562d453c079b3ca OK 2024/04/18 01:01:43 DEBUG : potato3: md5 = 0a13d87b20d2f14611a35c2b2e6b10b8 OK sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4' -> Encrypted drive 'TestCryptSwift:rclone-test-tajigor9feriqic4rajoraf3' 2024/04/18 01:01:44 DEBUG : empty space: md5 = 6dd4f870d3f7bcb5382f3e58bab72a7a OK 2024/04/18 01:01:44 DEBUG : potato3: md5 = 796a91997f65e4d777f202de0563037c OK 2024/04/18 01:01:45 DEBUG : empty space: Excluded (Size Filter) 2024/04/18 01:01:45 DEBUG : empty space: Excluded 2024/04/18 01:01:45 DEBUG : empty space: Excluded (Size Filter) 2024/04/18 01:01:45 DEBUG : empty space: Excluded 2024/04/18 01:01:45 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:45 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/18 01:01:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tajigor9feriqic4rajoraf3': Waiting for checks to finish 2024/04/18 01:01:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tajigor9feriqic4rajoraf3': Waiting for transfers to finish 2024/04/18 01:01:45 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:45 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:45 INFO : potato3: Deleted 2024/04/18 01:01:45 INFO : potato2: Deleted 2024/04/18 01:01:46 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xemebel9maqefuq1mopumek6" 2024/04/18 01:01:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xemebel9maqefuq1mopumek6.bin" 2024/04/18 01:01:46 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xemebel9maqefuq1mopumek6" 2024/04/18 01:01:47 DEBUG : empty space: Excluded (Size Filter) 2024/04/18 01:01:47 DEBUG : empty space: Excluded 2024/04/18 01:01:47 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:47 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/18 01:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xemebel9maqefuq1mopumek6': Waiting for checks to finish 2024/04/18 01:01:47 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xemebel9maqefuq1mopumek6': Waiting for transfers to finish 2024/04/18 01:01:47 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:47 INFO : potato3: Deleted 2024/04/18 01:01:48 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:48 INFO : potato2: Deleted 2024/04/18 01:01:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xemebel9maqefuq1mopumek6': Purge remote 2024/04/18 01:01:48 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:48 INFO : potato3.bin: Deleted 2024/04/18 01:01:48 INFO : potato2.bin: Deleted 2024/04/18 01:01:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-tajigor9feriqic4rajoraf3': Purge remote 2024/04/18 01:01:48 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:49 INFO : empty space.bin: Deleted --- PASS: TestServerSideMoveWithFilter (7.68s) === RUN TestServerSideMoveDeleteEmptySourceDirs run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:49 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-nabemak2mimahab6xicofew9" 2024/04/18 01:01:49 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nabemak2mimahab6xicofew9.bin" 2024/04/18 01:01:50 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-nabemak2mimahab6xicofew9" 2024/04/18 01:01:51 DEBUG : potato2: md5 = 7e5c7914002103c8a977bea8ae24c4f9 OK 2024/04/18 01:01:51 DEBUG : empty space: md5 = af2ce7c7406e3719f9a359472885cec4 OK 2024/04/18 01:01:51 DEBUG : potato3: md5 = cd34f016b6520a6053dcd1863262cb04 OK 2024/04/18 01:01:51 DEBUG : tomatoDir: Making directory sync_test.go:1494: Server side move (if possible) Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4' -> Encrypted drive 'TestCryptSwift:rclone-test-nabemak2mimahab6xicofew9' 2024/04/18 01:01:52 DEBUG : empty space: md5 = ddcb9a2914bfc124681e7741166eb032 OK 2024/04/18 01:01:52 DEBUG : potato3: md5 = 6a0b8286fb05b9d4cb33b94b55a4150b OK 2024/04/18 01:01:52 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:52 DEBUG : potato3: Sizes differ (src 68 vs dst 60) 2024/04/18 01:01:52 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nabemak2mimahab6xicofew9': Waiting for checks to finish 2024/04/18 01:01:52 DEBUG : empty space: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:01:52 DEBUG : empty space: Unchanged skipping 2024/04/18 01:01:53 INFO : empty space: Deleted 2024/04/18 01:01:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nabemak2mimahab6xicofew9': Waiting for transfers to finish 2024/04/18 01:01:53 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:53 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:53 INFO : potato2: Deleted 2024/04/18 01:01:53 INFO : potato3: Deleted 2024/04/18 01:01:53 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-riwokac6vonasir0teniroc6" 2024/04/18 01:01:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-riwokac6vonasir0teniroc6.bin" 2024/04/18 01:01:54 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-riwokac6vonasir0teniroc6" 2024/04/18 01:01:54 DEBUG : tomatoDir: Making directory 2024/04/18 01:01:54 DEBUG : empty space: Need to transfer - File not found at Destination 2024/04/18 01:01:54 DEBUG : potato2: Need to transfer - File not found at Destination 2024/04/18 01:01:54 DEBUG : potato3: Need to transfer - File not found at Destination 2024/04/18 01:01:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riwokac6vonasir0teniroc6': Waiting for checks to finish 2024/04/18 01:01:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riwokac6vonasir0teniroc6': Waiting for transfers to finish 2024/04/18 01:01:55 INFO : empty space: Copied (server-side copy) 2024/04/18 01:01:55 INFO : potato3: Copied (server-side copy) 2024/04/18 01:01:55 INFO : potato3: Deleted 2024/04/18 01:01:55 INFO : empty space: Deleted 2024/04/18 01:01:55 INFO : potato2: Copied (server-side copy) 2024/04/18 01:01:55 INFO : potato2: Deleted 2024/04/18 01:01:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-riwokac6vonasir0teniroc6': Purge remote 2024/04/18 01:01:56 DEBUG : Waiting for deletions to finish 2024/04/18 01:01:56 INFO : empty space.bin: Deleted 2024/04/18 01:01:56 INFO : potato3.bin: Deleted 2024/04/18 01:01:56 INFO : potato2.bin: Deleted 2024/04/18 01:01:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-nabemak2mimahab6xicofew9': Purge remote 2024/04/18 01:01:56 DEBUG : Waiting for deletions to finish --- PASS: TestServerSideMoveDeleteEmptySourceDirs (7.19s) === RUN TestServerSideMoveOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:57 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-move-test" 2024/04/18 01:01:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-move-test.bin" 2024/04/18 01:01:57 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-move-test" 2024/04/18 01:01:58 DEBUG : potato2: md5 = 409ac0248ab687057a046368b6fb8b82 OK --- PASS: TestServerSideMoveOverlap (1.88s) === RUN TestSyncOverlap run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:01:58 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test" 2024/04/18 01:01:58 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test.bin" 2024/04/18 01:01:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncOverlap (1.81s) === RUN TestSyncOverlapWithFilter run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:00 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test" 2024/04/18 01:02:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test.bin" 2024/04/18 01:02:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test" 2024/04/18 01:02:01 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-include/layer2" 2024/04/18 01:02:01 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-include/layer2.bin" 2024/04/18 01:02:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-include/layer2" 2024/04/18 01:02:02 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-ignore-file" 2024/04/18 01:02:02 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-ignore-file.bin" 2024/04/18 01:02:03 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-ignore-file" 2024/04/18 01:02:04 DEBUG : rclone-sync-test-ignore-file/.ignore: md5 = 7bff9e98e69ada250e722c7f17f5ff7b OK 2024/04/18 01:02:04 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/18 01:02:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test': Waiting for checks to finish 2024/04/18 01:02:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test': Waiting for transfers to finish 2024/04/18 01:02:04 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:04 INFO : There was nothing to transfer 2024/04/18 01:02:04 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/18 01:02:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:02:04 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:02:04 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:04 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-include/layer2': Waiting for checks to finish 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-include/layer2': Waiting for transfers to finish 2024/04/18 01:02:05 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:05 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:02:05 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:05 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:05 DEBUG : rclone-sync-test-ignore-file: Excluded 2024/04/18 01:02:05 DEBUG : : Excluded 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-ignore-file': Waiting for checks to finish 2024/04/18 01:02:05 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/rclone-sync-test-ignore-file': Waiting for transfers to finish 2024/04/18 01:02:05 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:05 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncOverlapWithFilter (8.34s) === RUN TestSyncCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:09 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:02:09 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:10 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/CompareDest" 2024/04/18 01:02:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/CompareDest.bin" 2024/04/18 01:02:10 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/CompareDest" 2024/04/18 01:02:11 DEBUG : one: Need to transfer - File not found at Destination 2024/04/18 01:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:11 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:11 DEBUG : one: md5 = 78aa17108c7eac761ca9747f25d957e9 OK 2024/04/18 01:02:11 INFO : one: Copied (new) 2024/04/18 01:02:11 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:12 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:12 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:12 DEBUG : one: md5 = 84091e0eb1279faa6cb5029e8e342a9f OK 2024/04/18 01:02:12 INFO : one: Copied (replaced existing) 2024/04/18 01:02:12 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:13 DEBUG : dst/one: md5 = cd65d67efd0c2a3c8155338b744306d7 OK 2024/04/18 01:02:13 DEBUG : CompareDest/one: md5 = d8777023ac04fad3ba90b6a7c23bb977 OK 2024/04/18 01:02:13 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:13 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:13 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/18 01:02:13 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:13 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:13 INFO : There was nothing to transfer 2024/04/18 01:02:13 DEBUG : CompareDest/two: md5 = e35c03fc74e4f983acce9142f7c16800 OK 2024/04/18 01:02:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:14 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/18 01:02:14 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:14 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/18 01:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:14 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:14 INFO : There was nothing to transfer 2024/04/18 01:02:14 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:14 DEBUG : two: Destination found in --compare-dest, skipping 2024/04/18 01:02:14 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:14 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:14 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/18 01:02:14 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:14 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:14 INFO : There was nothing to transfer sync_test.go:1964: No hash on uploaded file so skipping compare timestamp test 2024/04/18 01:02:15 DEBUG : two: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:15 DEBUG : two: Need to transfer - File not found at Destination 2024/04/18 01:02:15 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:15 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:15 DEBUG : one: Destination found in --compare-dest, skipping 2024/04/18 01:02:15 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:15 DEBUG : two: md5 = 123a5ae871cf7b340569b01733254eb1 OK 2024/04/18 01:02:15 INFO : two: Copied (new) 2024/04/18 01:02:15 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCompareDest (7.99s) === RUN TestSyncMultipleCompareDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:17 DEBUG : pre-dest1/1: md5 = 4ff02ab83bf9c3e88da526ac39bed375 OK 2024/04/18 01:02:17 DEBUG : pre-dest2/2: md5 = cc9e41490ca51347ec7f5f4522885303 OK 2024/04/18 01:02:18 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dest" 2024/04/18 01:02:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dest.bin" 2024/04/18 01:02:18 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dest" 2024/04/18 01:02:19 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/pre-dest1" 2024/04/18 01:02:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/pre-dest1.bin" 2024/04/18 01:02:19 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/pre-dest1" 2024/04/18 01:02:20 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/pre-dest2" 2024/04/18 01:02:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/pre-dest2.bin" 2024/04/18 01:02:20 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/pre-dest2" 2024/04/18 01:02:21 DEBUG : 1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:21 DEBUG : 1: Destination found in --compare-dest, skipping 2024/04/18 01:02:21 DEBUG : 2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:21 DEBUG : 2: Destination found in --compare-dest, skipping 2024/04/18 01:02:21 DEBUG : 3: Need to transfer - File not found at Destination 2024/04/18 01:02:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dest': Waiting for checks to finish 2024/04/18 01:02:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dest': Waiting for transfers to finish 2024/04/18 01:02:22 DEBUG : 3: md5 = 3a0e353bb157cfdde1acafe8e4bd0f94 OK 2024/04/18 01:02:22 INFO : 3: Copied (new) 2024/04/18 01:02:22 DEBUG : Waiting for deletions to finish --- PASS: TestSyncMultipleCompareDest (6.43s) === RUN TestSyncCopyDest run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:23 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:02:23 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:24 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/CopyDest" 2024/04/18 01:02:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/CopyDest.bin" 2024/04/18 01:02:24 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/CopyDest" 2024/04/18 01:02:25 DEBUG : one: Need to transfer - File not found at Destination 2024/04/18 01:02:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:25 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:26 DEBUG : one: md5 = 8e58d59d3e7840d5f8450d6dc9a7d833 OK 2024/04/18 01:02:26 INFO : one: Copied (new) 2024/04/18 01:02:26 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:26 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:26 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:26 DEBUG : one: md5 = 901764450cb2fc8726d45a7b19aee7bf OK 2024/04/18 01:02:26 INFO : one: Copied (replaced existing) 2024/04/18 01:02:26 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:27 DEBUG : dst/one: md5 = ec970dd0a61fbea3815b89ae1f31fb8d OK 2024/04/18 01:02:27 DEBUG : CopyDest/one: md5 = 6282ffbb44a84307a3071d931a565de2 OK 2024/04/18 01:02:27 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/BackupDir" 2024/04/18 01:02:27 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/BackupDir.bin" 2024/04/18 01:02:28 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/BackupDir" 2024/04/18 01:02:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:28 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:28 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:28 DEBUG : one: Sizes differ (src 5 vs dst 3) 2024/04/18 01:02:29 INFO : one: Copied (server-side copy) 2024/04/18 01:02:29 INFO : one: Deleted 2024/04/18 01:02:29 INFO : one: Copied (server-side copy) 2024/04/18 01:02:29 DEBUG : one: Destination found in --copy-dest, using server-side copy 2024/04/18 01:02:29 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:29 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:30 DEBUG : CopyDest/two: md5 = 8d56bde35de26cb230c95fecea6d9b60 OK 2024/04/18 01:02:30 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:30 INFO : two: Copied (server-side copy) 2024/04/18 01:02:30 DEBUG : two: Destination found in --copy-dest, using server-side copy 2024/04/18 01:02:30 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:31 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:31 DEBUG : one: Unchanged skipping 2024/04/18 01:02:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:31 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:31 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:31 DEBUG : one: Unchanged skipping 2024/04/18 01:02:31 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:31 DEBUG : two: Unchanged skipping 2024/04/18 01:02:31 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:31 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:31 INFO : There was nothing to transfer 2024/04/18 01:02:32 DEBUG : CopyDest/three: md5 = 5a8303cc72512b313b8a121a2c4d2890 OK 2024/04/18 01:02:32 DEBUG : three: Sizes differ (src 7 vs dst 5) 2024/04/18 01:02:32 DEBUG : three: Destination not found in --copy-dest 2024/04/18 01:02:32 DEBUG : three: Need to transfer - File not found at Destination 2024/04/18 01:02:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:32 DEBUG : one: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:32 DEBUG : one: Unchanged skipping 2024/04/18 01:02:32 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:32 DEBUG : two: Unchanged skipping 2024/04/18 01:02:32 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:33 DEBUG : three: md5 = e2537eabdeb858f5cdd0fba71549e082 OK 2024/04/18 01:02:33 INFO : three: Copied (new) 2024/04/18 01:02:33 DEBUG : Waiting for deletions to finish --- PASS: TestSyncCopyDest (11.26s) === RUN TestSyncBackupDir run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:34 DEBUG : dst/one: md5 = 1bc3c10902840b923481a73d9b685c8d OK 2024/04/18 01:02:35 DEBUG : dst/two: md5 = c9a6b08b063a7e01508c7f7c085848d6 OK 2024/04/18 01:02:35 DEBUG : dst/three.txt: md5 = 2d14ec62a192d2f7ee37c4565ad445e0 OK 2024/04/18 01:02:35 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:35 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:02:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:36 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:36 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup.bin" 2024/04/18 01:02:37 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:37 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:02:37 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:37 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:37 DEBUG : two: Unchanged skipping 2024/04/18 01:02:38 INFO : one: Copied (server-side copy) 2024/04/18 01:02:38 INFO : one: Deleted 2024/04/18 01:02:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:38 DEBUG : one: md5 = 0f6b133d8569b09646e3e6252b49477e OK 2024/04/18 01:02:38 INFO : one: Copied (new) 2024/04/18 01:02:38 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:39 INFO : three.txt: Copied (server-side copy) 2024/04/18 01:02:39 INFO : three.txt: Deleted 2024/04/18 01:02:39 INFO : three.txt: Moved into backup dir 2024/04/18 01:02:39 DEBUG : dst/three.txt: md5 = 982e6566ec146e984d17947c9d34fb5a OK 2024/04/18 01:02:40 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:02:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:40 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:40 DEBUG : two: Unchanged skipping 2024/04/18 01:02:40 INFO : one: Copied (server-side copy) 2024/04/18 01:02:40 INFO : one: Deleted 2024/04/18 01:02:40 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:41 DEBUG : one: md5 = 5e2f0e81ab047601ddba8eb5c3ae9c57 OK 2024/04/18 01:02:41 INFO : one: Copied (new) 2024/04/18 01:02:41 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:41 INFO : three.txt: Copied (server-side copy) 2024/04/18 01:02:41 INFO : three.txt: Deleted 2024/04/18 01:02:41 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDir (8.19s) === RUN TestSyncBackupDirWithSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:43 DEBUG : dst/one: md5 = 261938801aa0797698bf350bd7ac641a OK 2024/04/18 01:02:43 DEBUG : dst/two: md5 = b3b891a9c942d88bdffb80a5bc3ef550 OK 2024/04/18 01:02:43 DEBUG : dst/three.txt: md5 = d7ddd5c4a541fdc0a6c274a68395c83e OK 2024/04/18 01:02:43 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:43 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:02:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:44 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:44 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup.bin" 2024/04/18 01:02:45 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:45 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:02:45 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:45 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:45 DEBUG : two: Unchanged skipping 2024/04/18 01:02:46 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:02:46 INFO : one: Deleted 2024/04/18 01:02:46 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:46 DEBUG : one: md5 = c4dd5b51804f13db58a87448df771b3d OK 2024/04/18 01:02:46 INFO : one: Copied (new) 2024/04/18 01:02:46 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:47 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:02:47 INFO : three.txt: Deleted 2024/04/18 01:02:47 INFO : three.txt: Moved into backup dir 2024/04/18 01:02:47 DEBUG : dst/three.txt: md5 = 02059a7dffe08b43e4ad0386c1bbe460 OK 2024/04/18 01:02:48 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:48 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:48 DEBUG : two: Unchanged skipping 2024/04/18 01:02:48 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:02:48 INFO : one: Deleted 2024/04/18 01:02:48 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:49 DEBUG : one: md5 = 075aed322338016c0b4c7a2da67cb97d OK 2024/04/18 01:02:49 INFO : one: Copied (new) 2024/04/18 01:02:49 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:49 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:02:49 INFO : three.txt: Deleted 2024/04/18 01:02:49 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffix (8.09s) === RUN TestSyncBackupDirWithSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:51 DEBUG : dst/one: md5 = d810c627661179f299355e13062002e1 OK 2024/04/18 01:02:51 DEBUG : dst/two: md5 = 2f5afddf1e511e449543a9647a6ec08b OK 2024/04/18 01:02:51 DEBUG : dst/three.txt: md5 = 32fb0755d4f1b9e4d75efd31631c132f OK 2024/04/18 01:02:51 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:51 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:02:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:52 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:52 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup.bin" 2024/04/18 01:02:53 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/backup" 2024/04/18 01:02:53 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:02:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:53 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:53 DEBUG : two: Unchanged skipping 2024/04/18 01:02:54 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/18 01:02:54 INFO : one: Deleted 2024/04/18 01:02:54 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:54 DEBUG : one: md5 = 80494bfd7e062386052601a078d1b2d2 OK 2024/04/18 01:02:54 INFO : one: Copied (new) 2024/04/18 01:02:54 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:55 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/18 01:02:55 INFO : three.txt: Deleted 2024/04/18 01:02:55 INFO : three.txt: Moved into backup dir 2024/04/18 01:02:56 DEBUG : dst/three.txt: md5 = 0270f42e7fdcc9adac55510176634808 OK 2024/04/18 01:02:56 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:02:56 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:02:56 DEBUG : two: Unchanged skipping 2024/04/18 01:02:56 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/18 01:02:56 INFO : one: Deleted 2024/04/18 01:02:56 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:02:57 DEBUG : one: md5 = 32a57431380d7e2f1ff726b80ed5360e OK 2024/04/18 01:02:57 INFO : one: Copied (new) 2024/04/18 01:02:57 DEBUG : Waiting for deletions to finish 2024/04/18 01:02:57 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/18 01:02:57 INFO : three.txt: Deleted 2024/04/18 01:02:57 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirWithSuffixKeepExtension (7.89s) === RUN TestSyncBackupDirSuffixOnly run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:02:59 DEBUG : dst/one: md5 = f7d200b308e13262ced49ac035410c8e OK 2024/04/18 01:02:59 DEBUG : dst/two: md5 = 1025dbbe0cc8ef6f53a1ab65dcf96948 OK 2024/04/18 01:02:59 DEBUG : dst/three.txt: md5 = 02039560b8c72df3cb3c64cdf75c7911 OK 2024/04/18 01:02:59 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:02:59 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:03:00 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:03:00 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:03:00 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:03:00 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:00 DEBUG : two: Unchanged skipping 2024/04/18 01:03:01 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:03:01 INFO : one: Deleted 2024/04/18 01:03:01 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:03:01 DEBUG : one: md5 = d1075f46cc6614c9a49db5b9b32df4b5 OK 2024/04/18 01:03:01 INFO : one: Copied (new) 2024/04/18 01:03:01 DEBUG : Waiting for deletions to finish 2024/04/18 01:03:02 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:03:02 INFO : three.txt: Deleted 2024/04/18 01:03:02 INFO : three.txt: Moved into backup dir 2024/04/18 01:03:02 DEBUG : dst/three.txt: md5 = 1ac133d28e9d7ddca50d1a0714fd31f5 OK 2024/04/18 01:03:03 DEBUG : one.bak: Excluded (Path Filter) 2024/04/18 01:03:03 DEBUG : one.bak: Excluded 2024/04/18 01:03:03 DEBUG : three.txt.bak: Excluded (Path Filter) 2024/04/18 01:03:03 DEBUG : three.txt.bak: Excluded 2024/04/18 01:03:03 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:03:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for checks to finish 2024/04/18 01:03:03 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:03 DEBUG : two: Unchanged skipping 2024/04/18 01:03:03 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:03:03 INFO : one: Deleted 2024/04/18 01:03:03 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst': Waiting for transfers to finish 2024/04/18 01:03:03 DEBUG : one: md5 = b4d3895c2a42fbb67035e64367b787ad OK 2024/04/18 01:03:03 INFO : one: Copied (new) 2024/04/18 01:03:03 DEBUG : Waiting for deletions to finish 2024/04/18 01:03:04 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:03:04 INFO : three.txt: Deleted 2024/04/18 01:03:04 INFO : three.txt: Moved into backup dir --- PASS: TestSyncBackupDirSuffixOnly (6.97s) === RUN TestSyncSuffix run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:06 DEBUG : dst/one: md5 = 73bac7c8c1b96ca8cc13783ab098e30e OK 2024/04/18 01:03:06 DEBUG : dst/two: md5 = 074179f6263b4ebf8beae5fbf1d738af OK 2024/04/18 01:03:06 DEBUG : dst/three.txt: md5 = bea47bee865eb24636ffd3091d342637 OK 2024/04/18 01:03:06 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:03:06 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:03:07 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:03:07 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:03:08 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:03:08 INFO : one: Deleted 2024/04/18 01:03:08 DEBUG : one: md5 = 54311ce40bfeae839f7ac8fff066aa78 OK 2024/04/18 01:03:08 INFO : one: Copied (new) 2024/04/18 01:03:08 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:08 DEBUG : two: Unchanged skipping 2024/04/18 01:03:08 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/18 01:03:08 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:03:09 INFO : three.txt: Deleted 2024/04/18 01:03:09 DEBUG : three.txt: md5 = bb1a9acaa7f05856108a89c027eeb912 OK 2024/04/18 01:03:09 INFO : three.txt: Copied (new) 2024/04/18 01:03:09 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:03:10 INFO : one: Copied (server-side copy) to: one.bak 2024/04/18 01:03:10 INFO : one: Deleted 2024/04/18 01:03:10 DEBUG : one: md5 = 8652b984cf8260f9540d5075ba7d8317 OK 2024/04/18 01:03:10 INFO : one: Copied (new) 2024/04/18 01:03:10 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:10 DEBUG : two: Unchanged skipping 2024/04/18 01:03:10 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/18 01:03:10 INFO : three.txt: Copied (server-side copy) to: three.txt.bak 2024/04/18 01:03:11 INFO : three.txt: Deleted 2024/04/18 01:03:11 DEBUG : three.txt: md5 = 4516fc070313050e24cfdf19a7137601 OK 2024/04/18 01:03:11 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffix (6.66s) === RUN TestSyncSuffixKeepExtension run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:12 DEBUG : dst/one: md5 = c6bd2083e4d876cd21af240149a31b77 OK 2024/04/18 01:03:12 DEBUG : dst/two: md5 = 7ec3f400d98908f46b556e934bce1532 OK 2024/04/18 01:03:13 DEBUG : dst/three.txt: md5 = 3956e447c4edb10a19bbd086921d0ff7 OK 2024/04/18 01:03:13 DEBUG : Creating backend with remote "TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:03:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst.bin" 2024/04/18 01:03:13 DEBUG : Creating backend with remote "memstore:crypt/rclone-test-xedehes0miniwuy6vemasaq4/dst" 2024/04/18 01:03:14 DEBUG : one: Sizes differ (src 4 vs dst 3) 2024/04/18 01:03:15 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/18 01:03:15 INFO : one: Deleted 2024/04/18 01:03:15 DEBUG : one: md5 = 30269efc84f4b4a67f15e1ebaa0a7fff OK 2024/04/18 01:03:15 INFO : one: Copied (new) 2024/04/18 01:03:15 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:15 DEBUG : two: Unchanged skipping 2024/04/18 01:03:15 DEBUG : three.txt: Sizes differ (src 6 vs dst 5) 2024/04/18 01:03:15 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/18 01:03:16 INFO : three.txt: Deleted 2024/04/18 01:03:16 DEBUG : three.txt: md5 = ad7c9095d6f75016d161108c157a0a23 OK 2024/04/18 01:03:16 INFO : three.txt: Copied (new) 2024/04/18 01:03:16 DEBUG : one: Sizes differ (src 5 vs dst 4) 2024/04/18 01:03:17 INFO : one: Copied (server-side copy) to: one-2019-01-01 2024/04/18 01:03:17 INFO : one: Deleted 2024/04/18 01:03:17 DEBUG : one: md5 = c70c51a8ff06fd9ce7f67792621fc030 OK 2024/04/18 01:03:17 INFO : one: Copied (new) 2024/04/18 01:03:17 DEBUG : two: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:17 DEBUG : two: Unchanged skipping 2024/04/18 01:03:17 DEBUG : three.txt: Sizes differ (src 19 vs dst 6) 2024/04/18 01:03:17 INFO : three.txt: Copied (server-side copy) to: three-2019-01-01.txt 2024/04/18 01:03:17 INFO : three.txt: Deleted 2024/04/18 01:03:18 DEBUG : three.txt: md5 = 2a65c316f6c2a6e3afaddced6549e994 OK 2024/04/18 01:03:18 INFO : three.txt: Copied (new) --- PASS: TestSyncSuffixKeepExtension (6.89s) === RUN TestSyncUTFNorm run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:19 DEBUG : Testêé: md5 = 02413c733dd5bb51db7f49c8d40bb9d6 OK 2024/04/18 01:03:20 DEBUG : Testêé: Sizes differ (src 14 vs dst 18) 2024/04/18 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:20 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:20 DEBUG : Testêé: md5 = 938fb96f804e15a1ad86a7ecba919d2e OK 2024/04/18 01:03:20 INFO : Testêé: Copied (replaced existing) to: Testêé 2024/04/18 01:03:20 DEBUG : Waiting for deletions to finish --- PASS: TestSyncUTFNorm (1.55s) === RUN TestSyncImmutable run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:21 DEBUG : existing: Need to transfer - File not found at Destination 2024/04/18 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:21 DEBUG : existing: md5 = 0fa99cd41ee71d962f1ef5d1efd66527 OK 2024/04/18 01:03:21 INFO : existing: Copied (new) 2024/04/18 01:03:21 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:21 DEBUG : existing: Sizes differ (src 8 vs dst 6) 2024/04/18 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:21 ERROR : existing: Source and destination exist but do not match: immutable file modified 2024/04/18 01:03:21 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': not deleting files as there were IO errors 2024/04/18 01:03:21 ERROR : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': not deleting directories as there were IO errors run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncImmutable (1.54s) === RUN TestSyncIgnoreCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:22 DEBUG : EXISTING: md5 = 5eb21fbd302151f95301cb5c35f7f02c OK 2024/04/18 01:03:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:23 DEBUG : existing: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:23 DEBUG : existing: Unchanged skipping 2024/04/18 01:03:23 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:23 DEBUG : Waiting for deletions to finish 2024/04/18 01:03:23 INFO : There was nothing to transfer --- PASS: TestSyncIgnoreCase (1.33s) === RUN TestFixCase run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:2420: Skipping test as local or remote are case-sensitive --- SKIP: TestFixCase (0.27s) === RUN TestMaxTransfer === RUN TestMaxTransfer/Hard run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Soft run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:2459: This test only runs on local === RUN TestMaxTransfer/Cautious run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" sync_test.go:2459: This test only runs on local --- PASS: TestMaxTransfer (0.25s) --- SKIP: TestMaxTransfer/Hard (0.08s) --- SKIP: TestMaxTransfer/Soft (0.08s) --- SKIP: TestMaxTransfer/Cautious (0.08s) === RUN TestSyncConcurrentDelete run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:24 DEBUG : both0: md5 = 3f48579b2a052c3b1d6b14a0ffa8050d OK 2024/04/18 01:03:24 DEBUG : only0: md5 = b75ebc8f863334de4a6aed378910915d OK 2024/04/18 01:03:25 DEBUG : both1: md5 = 22b2dc72a6a253d9e1a9498da679460b OK 2024/04/18 01:03:25 DEBUG : only1: md5 = db883fed377bd58f24783532015268a6 OK 2024/04/18 01:03:25 DEBUG : both2: md5 = 1c8febd021392e0c46e24cd393aeef38 OK 2024/04/18 01:03:26 DEBUG : only2: md5 = 2ea0a1e2301dfa6f754b5cb458fa2e0b OK 2024/04/18 01:03:26 DEBUG : both3: md5 = e55d5dfda018dcde0d73f14081e95d2a OK 2024/04/18 01:03:26 DEBUG : only3: md5 = c37833116e9d8bfcedb33fa5ce1dbd9b OK 2024/04/18 01:03:27 DEBUG : both4: md5 = 5430614778c41ae982fc84e6a498401c OK 2024/04/18 01:03:27 DEBUG : only4: md5 = 307a83a6105b86780fe64383a8a5b34c OK 2024/04/18 01:03:27 DEBUG : both5: md5 = a1220db658152ab7cd7f734de2570090 OK 2024/04/18 01:03:27 DEBUG : only5: md5 = ebc416707d82f3a862e8bf4155e4a90e OK 2024/04/18 01:03:28 DEBUG : both6: md5 = 376562d9125296eeeda6cfacf33cd2f0 OK 2024/04/18 01:03:28 DEBUG : only6: md5 = 050a4452d9dc2c8aa27eb2a96b9b7dca OK 2024/04/18 01:03:28 DEBUG : both7: md5 = 681ad5a42a43190e847623b62ae15ed9 OK 2024/04/18 01:03:28 DEBUG : only7: md5 = e857b5f864ef2c32a5f0c8dded2fe492 OK 2024/04/18 01:03:29 DEBUG : both8: md5 = 721e2ced5f60478554ae125f01064cbf OK 2024/04/18 01:03:29 DEBUG : only8: md5 = b9d5edcf2272a7aa635f97c850e8927e OK 2024/04/18 01:03:29 DEBUG : both9: md5 = 5670bd015a57b8e9999bac1b6fbee4a3 OK 2024/04/18 01:03:29 DEBUG : only9: md5 = 398207c60e408f399f834d092d2a02e7 OK 2024/04/18 01:03:30 DEBUG : both10: md5 = a578ce617dd760869058ed0aaca741bf OK 2024/04/18 01:03:30 DEBUG : only10: md5 = 3ac2d76302e8aabeab22c08e9f816447 OK 2024/04/18 01:03:31 DEBUG : both11: md5 = 8bdd8dda311fde313a003c6ef3141311 OK 2024/04/18 01:03:31 DEBUG : only11: md5 = b5bf0bd90698da5d9a49b57660ecb59b OK 2024/04/18 01:03:32 DEBUG : both12: md5 = 7266070ea6b64eb32293d7161f9c6896 OK 2024/04/18 01:03:32 DEBUG : only12: md5 = 9c1275dd2d87dd9c33c1c985f3222b25 OK 2024/04/18 01:03:32 DEBUG : both13: md5 = b7e6a8440238037fe346f1a6d3338b42 OK 2024/04/18 01:03:33 DEBUG : only13: md5 = 12fd707a0ba6c1b7e5d15795446af502 OK 2024/04/18 01:03:33 DEBUG : both14: md5 = 00310d75ed032e6f4172f81b47c2db31 OK 2024/04/18 01:03:33 DEBUG : only14: md5 = 7d4001d94f92edf0280ae0fbb452fff0 OK 2024/04/18 01:03:33 DEBUG : both15: md5 = a65d11d401f8fe16dfff540acfebefd0 OK 2024/04/18 01:03:34 DEBUG : only15: md5 = 172a23993a9931f3cd731671c199a2d9 OK 2024/04/18 01:03:34 DEBUG : both16: md5 = 72b1fca5e4830ecd6d80834982edbb0e OK 2024/04/18 01:03:34 DEBUG : only16: md5 = 16d0106edf6d07239c2962f693f841bb OK 2024/04/18 01:03:35 DEBUG : both17: md5 = 6686778c03e413233fcbff7bcbcda625 OK 2024/04/18 01:03:35 DEBUG : only17: md5 = 63ef4cd0ea901d031ce11891b725905d OK 2024/04/18 01:03:36 DEBUG : both18: md5 = bf3ae26d22f5abae391e7b28e4aa7070 OK 2024/04/18 01:03:36 DEBUG : only18: md5 = b62e50de3a38ed9e645f46d2236f5399 OK 2024/04/18 01:03:36 DEBUG : both19: md5 = 8836b1099ca3c147714925e864f80330 OK 2024/04/18 01:03:37 DEBUG : only19: md5 = b6ca44f5bc07b322f740acda2dbb952b OK 2024/04/18 01:03:38 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:38 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:38 DEBUG : both0: Unchanged skipping 2024/04/18 01:03:38 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:38 DEBUG : both1: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both10: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both11: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both13: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both14: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both12: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both15: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both17: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both18: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both16: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both19: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both2: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both3: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both4: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both5: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both6: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both7: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both8: Unchanged skipping 2024/04/18 01:03:39 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:39 DEBUG : both9: Unchanged skipping 2024/04/18 01:03:39 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:39 DEBUG : Waiting for deletions to finish 2024/04/18 01:03:39 INFO : only19: Deleted 2024/04/18 01:03:39 INFO : only13: Deleted 2024/04/18 01:03:39 INFO : only2: Deleted 2024/04/18 01:03:39 INFO : only9: Deleted 2024/04/18 01:03:39 INFO : only15: Deleted 2024/04/18 01:03:39 INFO : only18: Deleted 2024/04/18 01:03:39 INFO : only3: Deleted 2024/04/18 01:03:39 INFO : only1: Deleted 2024/04/18 01:03:39 INFO : only14: Deleted 2024/04/18 01:03:39 INFO : only5: Deleted 2024/04/18 01:03:39 INFO : only6: Deleted 2024/04/18 01:03:39 INFO : only12: Deleted 2024/04/18 01:03:39 INFO : only17: Deleted 2024/04/18 01:03:39 INFO : only11: Deleted 2024/04/18 01:03:39 INFO : only16: Deleted 2024/04/18 01:03:39 INFO : only4: Deleted 2024/04/18 01:03:40 INFO : only8: Deleted 2024/04/18 01:03:40 INFO : only7: Deleted 2024/04/18 01:03:40 INFO : only10: Deleted 2024/04/18 01:03:40 INFO : only0: Deleted 2024/04/18 01:03:40 INFO : There was nothing to transfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncConcurrentDelete (20.05s) === RUN TestSyncConcurrentTruncate run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:03:44 DEBUG : both0: md5 = ecb5d8f01bffca05514ff6489027954b OK 2024/04/18 01:03:44 DEBUG : only0: md5 = 201d8531202f5d33574d1516f2344ca4 OK 2024/04/18 01:03:45 DEBUG : both1: md5 = 58787a9918332246bca41554acee17cd OK 2024/04/18 01:03:45 DEBUG : only1: md5 = c57410e804663289d5cbc267f250afda OK 2024/04/18 01:03:45 DEBUG : both2: md5 = fa7926b5bad5a57279bdf834fea283c8 OK 2024/04/18 01:03:45 DEBUG : only2: md5 = 1894b04a83d529305830d0985d556e8d OK 2024/04/18 01:03:45 DEBUG : both3: md5 = 06807d217304cff4e0c1e51202d10e0f OK 2024/04/18 01:03:45 DEBUG : only3: md5 = bf006d714c0a8cffba1b662ac0dabb65 OK 2024/04/18 01:03:45 DEBUG : both4: md5 = e8b4fcce11a857c3360bad42a496f411 OK 2024/04/18 01:03:46 DEBUG : only4: md5 = 50ba4b0906096105fe6aff3625453528 OK 2024/04/18 01:03:46 DEBUG : both5: md5 = 21a81c0afa93cac21314cfb84ca2fa7b OK 2024/04/18 01:03:46 DEBUG : only5: md5 = 2562f1486a8f86ccd3078a14f8c08af6 OK 2024/04/18 01:03:46 DEBUG : both6: md5 = addbacbecfc9bed9cd7c58d21526623b OK 2024/04/18 01:03:46 DEBUG : only6: md5 = 18d82c05920c54254e3d6bf923839398 OK 2024/04/18 01:03:47 DEBUG : both7: md5 = 4a03f3b143c5557de3422345e555f87b OK 2024/04/18 01:03:47 DEBUG : only7: md5 = e3b0b5e30886d15c35c1b8ebcd25b2f8 OK 2024/04/18 01:03:47 DEBUG : both8: md5 = f0a2c594d9a6fd35eba252ce934d7607 OK 2024/04/18 01:03:47 DEBUG : only8: md5 = 0546b593ca7d6aaab82e02ce6db68cb3 OK 2024/04/18 01:03:47 DEBUG : both9: md5 = cd5f2e747ea2a19850c3314392a8f9a7 OK 2024/04/18 01:03:47 DEBUG : only9: md5 = 2f06ee698a0b59a986df04535193cf47 OK 2024/04/18 01:03:48 DEBUG : both10: md5 = 8825a50d78910e19a7c72d617fd7d25c OK 2024/04/18 01:03:48 DEBUG : only10: md5 = 52693cc88869bdd0d529ba31f3de1dff OK 2024/04/18 01:03:48 DEBUG : both11: md5 = 9269a0732dd28ca6b1f14605b5a4d103 OK 2024/04/18 01:03:48 DEBUG : only11: md5 = 4a8cbc85e8e2f920e72009791c914efb OK 2024/04/18 01:03:49 DEBUG : both12: md5 = 006b3cec4d5d8baafe9869f301dd0819 OK 2024/04/18 01:03:49 DEBUG : only12: md5 = 1305984613dc44303e2e7f26a424e884 OK 2024/04/18 01:03:49 DEBUG : both13: md5 = c29f5a7cc0bf1028cf046842a7efa717 OK 2024/04/18 01:03:49 DEBUG : only13: md5 = 987fea90b58676b34dfd17aadc53f039 OK 2024/04/18 01:03:49 DEBUG : both14: md5 = 09a30b6e293950cb37c7aa7cbf809e18 OK 2024/04/18 01:03:50 DEBUG : only14: md5 = 29b742434161562766d6e06ece1cd737 OK 2024/04/18 01:03:50 DEBUG : both15: md5 = 5e03c4a2411dc96bda61886986e41575 OK 2024/04/18 01:03:50 DEBUG : only15: md5 = 75348e3a4be5ea75f56162e59a63f7a2 OK 2024/04/18 01:03:50 DEBUG : both16: md5 = b8ce4d7f0790f6f782c793c9c408f722 OK 2024/04/18 01:03:50 DEBUG : only16: md5 = b9e2b3ae20c2116029ed96e4fc970693 OK 2024/04/18 01:03:50 DEBUG : both17: md5 = d2237ccb8a0fc9d970057f251c49990c OK 2024/04/18 01:03:51 DEBUG : only17: md5 = c9aa2797831be8396664ea9297805585 OK 2024/04/18 01:03:51 DEBUG : both18: md5 = 86ff6f38404723e3745f8ac45fe328db OK 2024/04/18 01:03:51 DEBUG : only18: md5 = acf9d1e28c7ce9335cb9e4bc21201adf OK 2024/04/18 01:03:51 DEBUG : both19: md5 = a1d71b36e10df60a745cee26d4c4ed3f OK 2024/04/18 01:03:51 DEBUG : only19: md5 = 97f08e0535be4c18b7bbddfe0510367a OK 2024/04/18 01:03:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for checks to finish 2024/04/18 01:03:53 DEBUG : both10: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both10: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both1: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both1: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both0: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both0: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both11: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both11: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both12: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both12: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both13: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both13: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both14: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both14: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both15: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both15: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both17: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both17: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both18: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both18: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both16: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both16: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both19: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both19: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both2: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both2: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both3: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both4: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both3: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both4: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both5: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both5: Unchanged skipping 2024/04/18 01:03:53 DEBUG : both6: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both6: Unchanged skipping 2024/04/18 01:03:53 DEBUG : only0: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only1: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only10: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only11: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : both7: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : only12: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : both8: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : only13: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : both8: Unchanged skipping 2024/04/18 01:03:53 DEBUG : only14: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only15: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only16: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only17: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only18: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only19: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only2: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : both7: Unchanged skipping 2024/04/18 01:03:53 DEBUG : only3: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only4: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only5: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only6: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only7: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only8: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : only9: Sizes differ (src 0 vs dst 6) 2024/04/18 01:03:53 DEBUG : both9: Size and modification time the same (differ by 0s, within tolerance 1ns) 2024/04/18 01:03:53 DEBUG : both9: Unchanged skipping 2024/04/18 01:03:53 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Waiting for transfers to finish 2024/04/18 01:03:54 DEBUG : only1: md5 = e9adb7e0e89bc6f13b2b4dfe8df0b0fd OK 2024/04/18 01:03:54 INFO : only1: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only0: md5 = e3ade7017cc5f6edf01f0293031a0557 OK 2024/04/18 01:03:54 INFO : only0: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only10: md5 = 12ad87f96367acfca3030e895cb880ee OK 2024/04/18 01:03:54 INFO : only10: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only11: md5 = 8aaf29d2845974b8df4dffaaff199d65 OK 2024/04/18 01:03:54 INFO : only11: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only12: md5 = ae9716c2e91174baa75398bd8b81ddcf OK 2024/04/18 01:03:54 INFO : only12: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only15: md5 = e8a6e452668b95dd0e31a92b0ecca903 OK 2024/04/18 01:03:54 INFO : only15: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only16: md5 = 5adae9d2d5a3a3fab5601d28272107e0 OK 2024/04/18 01:03:54 INFO : only16: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only14: md5 = ff36024b917aa96d64e884cc1b177f14 OK 2024/04/18 01:03:54 INFO : only14: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only17: md5 = 4bc529d9830b1659512cdf7c9e8acde1 OK 2024/04/18 01:03:54 INFO : only17: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only18: md5 = 93c80caa52c8529f95a066f1134cad8f OK 2024/04/18 01:03:54 INFO : only18: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only13: md5 = c7d9aa5c41e15cca92198c782666a692 OK 2024/04/18 01:03:54 INFO : only13: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only19: md5 = 747686a452b57e8339c822a8ee514839 OK 2024/04/18 01:03:54 INFO : only19: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only4: md5 = b4471380dc5e12e29a94d49fb4c5bb87 OK 2024/04/18 01:03:54 INFO : only4: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only5: md5 = 092b66662fb030b9680d91f87613d64d OK 2024/04/18 01:03:54 INFO : only5: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only7: md5 = 51ea440ce4f78ece1560193ff44c4f90 OK 2024/04/18 01:03:54 INFO : only7: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only6: md5 = bae580a3c77dacdde008b26cd758dceb OK 2024/04/18 01:03:54 INFO : only6: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only8: md5 = 90156d9238cc89c3c95bcab748ed4327 OK 2024/04/18 01:03:54 INFO : only8: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only2: md5 = f817de01da708b2147e44781ca5495c7 OK 2024/04/18 01:03:54 INFO : only2: Copied (replaced existing) 2024/04/18 01:03:54 DEBUG : only3: md5 = a61c31608b76362179ad321666f2e7db OK 2024/04/18 01:03:54 INFO : only3: Copied (replaced existing) 2024/04/18 01:03:55 DEBUG : only9: md5 = a3cdce8db592120c065f7527ea2618f4 OK 2024/04/18 01:03:55 INFO : only9: Copied (replaced existing) 2024/04/18 01:03:55 DEBUG : Waiting for deletions to finish run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestSyncConcurrentTruncate (19.34s) === RUN TestNothingToTransfer run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" 2024/04/18 01:04:03 INFO : sub dir: Set directory modification time (using DirSetModTime) 2024/04/18 01:04:03 DEBUG : sub dir: Making directory 2024/04/18 01:04:03 INFO : sub dir: Made directory with modification time 2011-12-30 12:59:59 +0000 UTC run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" run.go:180: Remote "Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4'", Local "Local file system at /tmp/rclone2223129932", Modify Window "1ns" --- PASS: TestNothingToTransfer (2.48s) PASS 2024/04/18 01:04:06 DEBUG : Encrypted drive 'TestCryptSwift:rclone-test-xedehes0miniwuy6vemasaq4': Purge remote 2024/04/18 01:04:06 DEBUG : Waiting for deletions to finish "./sync.test -test.v -test.timeout 1h0m0s -remote TestCryptSwift: -verbose" - Finished OK in 3m44.323052117s (try 1/5)